-- Logs begin at Thu 2019-02-14 18:12:01 CST, end at Sun 2025-03-02 00:05:33 CST. -- Mar 02 00:04:00 volumio-zero2w kernel: hwmon hwmon1: Voltage normalised Mar 02 00:04:03 volumio-zero2w kernel: usb 2-2: new SuperSpeed USB device number 3 using xhci_hcd Mar 02 00:04:03 volumio-zero2w kernel: usb 2-2: New USB device found, idVendor=0bda, idProduct=9210, bcdDevice=20.01 Mar 02 00:04:03 volumio-zero2w kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 02 00:04:03 volumio-zero2w kernel: usb 2-2: Product: HIKSEMI Mar 02 00:04:03 volumio-zero2w kernel: usb 2-2: Manufacturer: HIKSEMI Mar 02 00:04:03 volumio-zero2w kernel: usb 2-2: SerialNumber: 0129380008F5 Mar 02 00:04:03 volumio-zero2w kernel: usb 2-2: Enable of device-initiated U1 failed. Mar 02 00:04:03 volumio-zero2w kernel: usb 2-2: Enable of device-initiated U2 failed. Mar 02 00:04:03 volumio-zero2w kernel: usb 2-2: Enable of device-initiated U1 failed. Mar 02 00:04:03 volumio-zero2w kernel: usb 2-2: Enable of device-initiated U2 failed. Mar 02 00:04:03 volumio-zero2w kernel: scsi host1: uas Mar 02 00:04:04 volumio-zero2w kernel: scsi 1:0:0:0: Direct-Access HS-SSD-A 4000 1024G 1.00 PQ: 0 ANSI: 6 Mar 02 00:04:04 volumio-zero2w kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Mar 02 00:04:04 volumio-zero2w kernel: sd 1:0:0:0: [sdb] 2000409264 512-byte logical blocks: (1.02 TB/954 GiB) Mar 02 00:04:04 volumio-zero2w kernel: sd 1:0:0:0: [sdb] Write Protect is off Mar 02 00:04:04 volumio-zero2w kernel: sd 1:0:0:0: [sdb] Mode Sense: 37 00 00 08 Mar 02 00:04:04 volumio-zero2w kernel: sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Mar 02 00:04:04 volumio-zero2w kernel: sd 1:0:0:0: [sdb] Preferred minimum I/O size 512 bytes Mar 02 00:04:04 volumio-zero2w kernel: sd 1:0:0:0: [sdb] Optimal transfer size 33553920 bytes Mar 02 00:04:04 volumio-zero2w kernel: sdb: sdb1 Mar 02 00:04:04 volumio-zero2w kernel: sd 1:0:0:0: [sdb] Attached SCSI disk Mar 02 00:04:04 volumio-zero2w kernel: hwmon hwmon1: Undervoltage detected! Mar 02 00:04:04 volumio-zero2w volumio[3394]: info: Mounting Device 新加卷 Mar 02 00:04:04 volumio-zero2w volumio[3394]: /bin/mkdir: cannot create directory ‘/mnt/USB/新加卷’: File exists Mar 02 00:04:04 volumio-zero2w volumio[3394]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/新加卷" Mar 02 00:04:04 volumio-zero2w volumio[3394]: /bin/mkdir: cannot create directory ‘/mnt/USB/新加卷’: File exists Mar 02 00:04:04 volumio-zero2w sudo[13001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/新加卷 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 02 00:04:04 volumio-zero2w sudo[13001]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 00:04:05 volumio-zero2w sudo[13001]: pam_unix(sudo:session): session closed for user root Mar 02 00:04:05 volumio-zero2w volumio[3394]: ntfs-3g-mount: failed to access mountpoint /media/新加卷: Transport endpoint is not connected Mar 02 00:04:05 volumio-zero2w volumio[3394]: error: Failed to mount 新加卷: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/新加卷" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 02 00:04:05 volumio-zero2w volumio[3394]: ntfs-3g-mount: failed to access mountpoint /media/新加卷: Transport endpoint is not connected Mar 02 00:04:12 volumio-zero2w kernel: hwmon hwmon1: Voltage normalised Mar 02 00:04:13 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 02 00:04:13 volumio-zero2w volumio[3394]: info: CURURI: music-library Mar 02 00:04:13 volumio-zero2w volumio[3394]: info: Preload queue cleared Mar 02 00:04:14 volumio-zero2w kernel: hwmon hwmon1: Undervoltage detected! Mar 02 00:04:16 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioGetState Mar 02 00:04:16 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:22 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 02 00:04:22 volumio-zero2w volumio[3394]: info: CURURI: music-library Mar 02 00:04:22 volumio-zero2w volumio[3394]: info: Preload queue cleared Mar 02 00:04:25 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioGetState Mar 02 00:04:25 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:29 volumio-zero2w volumio[3394]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 02 00:04:29 volumio-zero2w volumio[3394]: info: Retrying Login Due to Network Error Mar 02 00:04:31 volumio-zero2w volumio[3394]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 02 00:04:31 volumio-zero2w volumio[3394]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 02 00:04:31 volumio-zero2w volumio[3394]: info: Retrieving Cloud Streaming UI Mar 02 00:04:31 volumio-zero2w volumio[3394]: info: Getting Tidal Cloud Configuration Mar 02 00:04:31 volumio-zero2w volumio[3394]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 02 00:04:31 volumio-zero2w volumio[3394]: info: Getting Qobuz Cloud Configuration Mar 02 00:04:31 volumio-zero2w volumio[3394]: info: Asking plugin for UI Config Mar 02 00:04:31 volumio-zero2w volumio[3394]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 02 00:04:31 volumio-zero2w volumio[3394]: info: Getting Spotify Cloud Configuration Mar 02 00:04:31 volumio-zero2w volumio[3394]: info: Asking plugin for UI Config Mar 02 00:04:31 volumio-zero2w volumio[3394]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 02 00:04:31 volumio-zero2w volumio[3394]: info: Saving Spotify Acccount Mar 02 00:04:31 volumio-zero2w volumio[3394]: info: Got Tidal Cloud Configuration Mar 02 00:04:31 volumio-zero2w volumio[3394]: info: Got it Mar 02 00:04:31 volumio-zero2w volumio[3394]: info: Got it Mar 02 00:04:31 volumio-zero2w volumio[3394]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 02 00:04:31 volumio-zero2w volumio[3394]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 02 00:04:31 volumio-zero2w volumio[3394]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 02 00:04:32 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 02 00:04:32 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 02 00:04:32 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 02 00:04:32 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 02 00:04:32 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 02 00:04:32 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 02 00:04:32 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 02 00:04:32 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 02 00:04:32 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioGetBrowseSources Mar 02 00:04:32 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioGetBrowseSources Mar 02 00:04:32 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioGetBrowseSources Mar 02 00:04:32 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 02 00:04:32 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Mar 02 00:04:34 volumio-zero2w volumio[3394]: verbose: ControllerMpd::sendMpdCommand rescan Mar 02 00:04:34 volumio-zero2w volumio[3394]: verbose: ControllerMpd::sendMpdCommand status Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: Mar 02 00:04:34 volumio-zero2w volumio[3394]: ---------------------------- MPD announces state update: update Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: sendMpdCommand rescan took 9 milliseconds Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: ControllerMpd::getState Mar 02 00:04:34 volumio-zero2w volumio[3394]: verbose: ControllerMpd::sendMpdCommand status Mar 02 00:04:34 volumio-zero2w volumio[3394]: verbose: ControllerMpd::sendMpdCommand status Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: Mar 02 00:04:34 volumio-zero2w volumio[3394]: ---------------------------- MPD announces state update: update Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: ControllerMpd::getState Mar 02 00:04:34 volumio-zero2w volumio[3394]: verbose: ControllerMpd::sendMpdCommand status Mar 02 00:04:34 volumio-zero2w volumio[3394]: verbose: ControllerMpd::sendMpdCommand status Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: Mar 02 00:04:34 volumio-zero2w volumio[3394]: ---------------------------- MPD announces state update: update Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: ControllerMpd::getState Mar 02 00:04:34 volumio-zero2w volumio[3394]: verbose: ControllerMpd::sendMpdCommand status Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: sendMpdCommand status took 39 milliseconds Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: sendMpdCommand status took 33 milliseconds Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: sendMpdCommand status took 32 milliseconds Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: sendMpdCommand status took 12 milliseconds Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: sendMpdCommand status took 11 milliseconds Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: sendMpdCommand status took 9 milliseconds Mar 02 00:04:34 volumio-zero2w volumio[3394]: verbose: ControllerMpd::parseState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: Command Router : Notfying DB Updatetrue Mar 02 00:04:34 volumio-zero2w volumio[3394]: verbose: ControllerMpd::parseState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: Command Router : Notfying DB Updatetrue Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::Close All Modals sent Mar 02 00:04:34 volumio-zero2w volumio[3394]: verbose: ControllerMpd::parseState Mar 02 00:04:34 volumio-zero2w volumio[3394]: verbose: ControllerMpd::parseState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: Command Router : Notfying DB Updatetrue Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::Close All Modals sent Mar 02 00:04:34 volumio-zero2w volumio[3394]: verbose: ControllerMpd::parseState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: ControllerMpd::pushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::servicePushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:34 volumio-zero2w volumio[3394]: 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} Mar 02 00:04:34 volumio-zero2w volumio[3394]: verbose: CURRENT POSITION 6 Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState stateService stop Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState currentStatus stop Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: No code Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: ControllerMpd::pushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::servicePushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:34 volumio-zero2w volumio[3394]: 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} Mar 02 00:04:34 volumio-zero2w volumio[3394]: verbose: CURRENT POSITION 6 Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState stateService stop Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState currentStatus stop Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: No code Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: ControllerMpd::pushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::servicePushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:34 volumio-zero2w volumio[3394]: 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} Mar 02 00:04:34 volumio-zero2w volumio[3394]: verbose: CURRENT POSITION 6 Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState stateService stop Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState currentStatus stop Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: No code Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:34 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:35 volumio-zero2w volumio[3394]: info: ------------------------------ 543ms Mar 02 00:04:35 volumio-zero2w volumio[3394]: info: ------------------------------ 535ms Mar 02 00:04:35 volumio-zero2w volumio[3394]: info: ------------------------------ 514ms Mar 02 00:04:36 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 02 00:04:38 volumio-zero2w kernel: smbd(1086): Attempt to set a LOCK_MAND lock via flock(2). This support has been removed and the request ignored. Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: MPD Database updated - AlbumList cache refreshed Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: Mar 02 00:04:38 volumio-zero2w volumio[3394]: ---------------------------- MPD announces state update: database Mar 02 00:04:38 volumio-zero2w volumio[3394]: verbose: ControllerMpd::sendMpdCommand status Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: Mar 02 00:04:38 volumio-zero2w volumio[3394]: ---------------------------- MPD announces state update: update Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: ControllerMpd::getState Mar 02 00:04:38 volumio-zero2w volumio[3394]: verbose: ControllerMpd::sendMpdCommand status Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: ControllerMpd::getState Mar 02 00:04:38 volumio-zero2w volumio[3394]: verbose: ControllerMpd::sendMpdCommand status Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: MPD Database updated - AlbumList cache refreshed Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: Mar 02 00:04:38 volumio-zero2w volumio[3394]: ---------------------------- MPD announces state update: database Mar 02 00:04:38 volumio-zero2w volumio[3394]: verbose: ControllerMpd::sendMpdCommand status Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: Mar 02 00:04:38 volumio-zero2w volumio[3394]: ---------------------------- MPD announces state update: update Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: ControllerMpd::getState Mar 02 00:04:38 volumio-zero2w volumio[3394]: verbose: ControllerMpd::sendMpdCommand status Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: ControllerMpd::getState Mar 02 00:04:38 volumio-zero2w volumio[3394]: verbose: ControllerMpd::sendMpdCommand status Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: MPD Database updated - AlbumList cache refreshed Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: Mar 02 00:04:38 volumio-zero2w volumio[3394]: ---------------------------- MPD announces state update: database Mar 02 00:04:38 volumio-zero2w volumio[3394]: verbose: ControllerMpd::sendMpdCommand status Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: Mar 02 00:04:38 volumio-zero2w volumio[3394]: ---------------------------- MPD announces state update: update Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: ControllerMpd::getState Mar 02 00:04:38 volumio-zero2w volumio[3394]: verbose: ControllerMpd::sendMpdCommand status Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: ControllerMpd::getState Mar 02 00:04:38 volumio-zero2w volumio[3394]: verbose: ControllerMpd::sendMpdCommand status Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: sendMpdCommand status took 41 milliseconds Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: sendMpdCommand status took 39 milliseconds Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: sendMpdCommand status took 34 milliseconds Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: sendMpdCommand status took 33 milliseconds Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: sendMpdCommand status took 31 milliseconds Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: sendMpdCommand status took 30 milliseconds Mar 02 00:04:38 volumio-zero2w volumio[3394]: info: sendMpdCommand status took 24 milliseconds Mar 02 00:04:38 volumio-zero2w volumio[3394]: verbose: ControllerMpd::parseState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: Command Router : Notfying DB Updatefalse Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::Close All Modals sent Mar 02 00:04:39 volumio-zero2w volumio[3394]: verbose: ControllerMpd::parseState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: Command Router : Notfying DB Updatefalse Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::Close All Modals sent Mar 02 00:04:39 volumio-zero2w volumio[3394]: verbose: ControllerMpd::parseState Mar 02 00:04:39 volumio-zero2w volumio[3394]: verbose: ControllerMpd::parseState Mar 02 00:04:39 volumio-zero2w volumio[3394]: verbose: ControllerMpd::parseState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: Command Router : Notfying DB Updatefalse Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::Close All Modals sent Mar 02 00:04:39 volumio-zero2w volumio[3394]: verbose: ControllerMpd::parseState Mar 02 00:04:39 volumio-zero2w volumio[3394]: verbose: ControllerMpd::parseState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: ControllerMpd::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::servicePushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: 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} Mar 02 00:04:39 volumio-zero2w volumio[3394]: verbose: CURRENT POSITION 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState stateService stop Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState currentStatus stop Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: No code Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: ControllerMpd::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::servicePushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: 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} Mar 02 00:04:39 volumio-zero2w volumio[3394]: verbose: CURRENT POSITION 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState stateService stop Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState currentStatus stop Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: No code Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: ControllerMpd::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::servicePushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: 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} Mar 02 00:04:39 volumio-zero2w volumio[3394]: verbose: CURRENT POSITION 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState stateService stop Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState currentStatus stop Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: No code Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: ControllerMpd::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::servicePushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: 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} Mar 02 00:04:39 volumio-zero2w volumio[3394]: verbose: CURRENT POSITION 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState stateService stop Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState currentStatus stop Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: No code Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: ControllerMpd::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::servicePushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: 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} Mar 02 00:04:39 volumio-zero2w volumio[3394]: verbose: CURRENT POSITION 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState stateService stop Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState currentStatus stop Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: No code Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: ControllerMpd::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::servicePushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: 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} Mar 02 00:04:39 volumio-zero2w volumio[3394]: verbose: CURRENT POSITION 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState stateService stop Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::syncState currentStatus stop Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: No code Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreStateMachine::pushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CorePlayQueue::getTrack 6 Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: CoreCommandRouter::volumioPushState Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: ------------------------------ 728ms Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: ------------------------------ 728ms Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: ------------------------------ 722ms Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: ------------------------------ 722ms Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: ------------------------------ 719ms Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: ------------------------------ 719ms Mar 02 00:04:39 volumio-zero2w volumio[3394]: info: MyVolumio login type: Token Mar 02 00:04:40 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 02 00:04:44 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 02 00:04:45 volumio-zero2w volumio[3394]: info: Enabling MyMusic plugin bluetooth Mar 02 00:04:45 volumio-zero2w volumio[3394]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Mar 02 00:04:45 volumio-zero2w volumio[3394]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Mar 02 00:04:45 volumio-zero2w volumio[3394]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling Mar 02 00:04:45 volumio-zero2w volumio[3394]: error: Could not Enable MyMusic Plugin: Error Mar 02 00:04:48 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 02 00:04:52 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 02 00:04:54 volumio-zero2w kernel: hwmon hwmon1: Voltage normalised Mar 02 00:04:56 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 02 00:04:58 volumio-zero2w kernel: hwmon hwmon1: Undervoltage detected! Mar 02 00:05:00 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 02 00:05:04 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 02 00:05:08 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 02 00:05:09 volumio-zero2w volumio[3394]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 02 00:05:09 volumio-zero2w volumio[3394]: info: Retrying Login Due to Network Error Mar 02 00:05:12 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 02 00:05:15 volumio-zero2w kernel: hwmon hwmon1: Voltage normalised Mar 02 00:05:16 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 02 00:05:19 volumio-zero2w kernel: hwmon hwmon1: Undervoltage detected! Mar 02 00:05:19 volumio-zero2w volumio[3394]: info: MyVolumio login type: Token Mar 02 00:05:20 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 02 00:05:22 volumio-zero2w volumio[3394]: info: CALLMETHOD: miscellanea albumart saveAlbumartOptions [object Object] Mar 02 00:05:22 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: albumart , saveAlbumartOptions Mar 02 00:05:23 volumio-zero2w kernel: hwmon hwmon1: Voltage normalised Mar 02 00:05:24 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 02 00:05:27 volumio-zero2w kernel: hwmon hwmon1: Undervoltage detected! Mar 02 00:05:28 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 02 00:05:32 volumio-zero2w volumio[3394]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 02 00:05:32 volumio-zero2w volumio[3394]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 02 00:05:32 volumio-zero2w volumio[3394]: Error: connect ETIMEDOUT 108.160.167.148:443 Mar 02 00:05:32 volumio-zero2w volumio[3394]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Mar 02 00:05:32 volumio-zero2w volumio[3394]: errno: -110, Mar 02 00:05:32 volumio-zero2w volumio[3394]: code: 'ETIMEDOUT', Mar 02 00:05:32 volumio-zero2w volumio[3394]: syscall: 'connect', Mar 02 00:05:32 volumio-zero2w volumio[3394]: address: '108.160.167.148', Mar 02 00:05:32 volumio-zero2w volumio[3394]: port: 443 Mar 02 00:05:32 volumio-zero2w volumio[3394]: } Mar 02 00:05:32 volumio-zero2w volumio[3394]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 02 00:05:33 volumio-zero2w sudo[13644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-02 00:04 Mar 02 00:05:33 volumio-zero2w sudo[13644]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"