-- Logs begin at Tue 2025-09-16 15:48:57 CEST, end at Tue 2025-09-16 16:01:53 CEST. -- Sep 16 16:00:02 music volumio[1268]: info: CoreCommandRouter::volumioGetState Sep 16 16:00:02 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:00:06 music volumio[1268]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 16:00:06 music volumio[1268]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 16 16:00:06 music volumio[1268]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 16 16:00:06 music volumio[1268]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 16 16:00:06 music volumio[1268]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Sep 16 16:00:06 music volumio[1268]: info: Received Get System Version Sep 16 16:00:06 music volumio[1268]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 16:00:24 music volumio[1268]: info: CoreCommandRouter::volumioGetState Sep 16 16:00:24 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:00:29 music volumio[1268]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 16:00:29 music volumio[1268]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 16 16:00:29 music volumio[1268]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Sep 16 16:00:29 music sudo[3994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Sep 16 16:00:29 music sudo[3994]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 16:00:29 music sudo[3994]: pam_unix(sudo:session): session closed for user root Sep 16 16:00:29 music sudo[4001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 16 16:00:29 music sudo[4001]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 16:00:29 music sudo[4001]: pam_unix(sudo:session): session closed for user root Sep 16 16:00:29 music sudo[4009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 16 16:00:29 music sudo[4009]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 16:00:29 music sudo[4009]: pam_unix(sudo:session): session closed for user root Sep 16 16:00:29 music sudo[4014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 16 16:00:29 music sudo[4014]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 16:00:29 music sudo[4014]: pam_unix(sudo:session): session closed for user root Sep 16 16:00:29 music sudo[4020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 16:00:29 music sudo[4020]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 16:00:29 music sudo[4020]: pam_unix(sudo:session): session closed for user root Sep 16 16:00:29 music volumio[1268]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 16 16:00:29 music volumio[1268]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 16 16:00:29 music sudo[4023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 16:00:29 music sudo[4023]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 16:00:29 music sudo[4023]: pam_unix(sudo:session): session closed for user root Sep 16 16:00:29 music sudo[4026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 16 16:00:29 music sudo[4026]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 16:00:29 music sudo[4026]: pam_unix(sudo:session): session closed for user root Sep 16 16:00:29 music volumio[1268]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 16 16:00:29 music volumio[1268]: wlan0 Interface doesn't support scanning : Operation not supported Sep 16 16:00:29 music volumio[1268]: info: Cannot use regular scanning, forcing with ap-force Sep 16 16:00:29 music sudo[4029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 16 16:00:29 music sudo[4029]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 16:00:29 music sudo[4029]: pam_unix(sudo:session): session closed for user root Sep 16 16:00:29 music volumio[1268]: command failed: Operation not supported (-95) Sep 16 16:00:29 music volumio[1268]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 16 16:00:29 music volumio[1268]: command failed: Operation not supported (-95) Sep 16 16:01:41 music kernel: usb 10-1: new SuperSpeed USB device number 2 using xhci_hcd Sep 16 16:01:41 music kernel: usb 10-1: New USB device found, idVendor=0781, idProduct=5595, bcdDevice= 1.00 Sep 16 16:01:41 music kernel: usb 10-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 16 16:01:41 music kernel: usb 10-1: Product: SanDisk 3.2Gen1 Sep 16 16:01:41 music kernel: usb 10-1: Manufacturer: USB Sep 16 16:01:41 music kernel: usb 10-1: SerialNumber: 00005406012121011211 Sep 16 16:01:41 music kernel: usb-storage 10-1:1.0: USB Mass Storage device detected Sep 16 16:01:41 music kernel: scsi host0: usb-storage 10-1:1.0 Sep 16 16:01:42 music kernel: scsi 0:0:0:0: Direct-Access USB SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6 Sep 16 16:01:42 music kernel: sd 0:0:0:0: [sda] 60063744 512-byte logical blocks: (30.8 GB/28.6 GiB) Sep 16 16:01:42 music kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 16 16:01:42 music kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 Sep 16 16:01:42 music kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Sep 16 16:01:42 music kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 16 16:01:42 music kernel: sda: sda1 Sep 16 16:01:42 music kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Sep 16 16:01:43 music volumio[1268]: info: Mounting Device HBCD_PE_x64 Sep 16 16:01:43 music sudo[4207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/HBCD_PE_x64 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 16 16:01:43 music sudo[4207]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 16:01:43 music ntfs-3g[4210]: Version 2017.3.23AR.3 integrated FUSE 28 Sep 16 16:01:43 music ntfs-3g[4210]: Mounted /dev/sda1 (Read-Write, label "HBCD_PE_x64", NTFS 3.1) Sep 16 16:01:43 music ntfs-3g[4210]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 16 16:01:43 music ntfs-3g[4210]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Sep 16 16:01:43 music ntfs-3g[4210]: Global ownership and permissions enforced, configuration type 7 Sep 16 16:01:43 music sudo[4207]: pam_unix(sudo:session): session closed for user root Sep 16 16:01:43 music volumio[1268]: info: Scanning new location : "USB/HBCD_PE_x64" Sep 16 16:01:43 music volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:01:43 music volumio[1268]: info: Sep 16 16:01:43 music volumio[1268]: ---------------------------- MPD announces state update: update Sep 16 16:01:43 music volumio[1268]: info: ControllerMpd::getState Sep 16 16:01:43 music volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:01:43 music volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:01:43 music volumio[1268]: info: Sep 16 16:01:43 music volumio[1268]: ---------------------------- MPD announces state update: update Sep 16 16:01:43 music volumio[1268]: info: ControllerMpd::getState Sep 16 16:01:43 music volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:01:43 music volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:01:43 music volumio[1268]: info: Sep 16 16:01:43 music volumio[1268]: ---------------------------- MPD announces state update: update Sep 16 16:01:43 music volumio[1268]: info: ControllerMpd::getState Sep 16 16:01:43 music volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:01:43 music volumio[1268]: info: sendMpdCommand status took 2 milliseconds Sep 16 16:01:43 music volumio[1268]: info: sendMpdCommand status took 1 milliseconds Sep 16 16:01:43 music volumio[1268]: info: sendMpdCommand status took 1 milliseconds Sep 16 16:01:43 music volumio[1268]: info: sendMpdCommand status took 1 milliseconds Sep 16 16:01:43 music volumio[1268]: info: sendMpdCommand status took 1 milliseconds Sep 16 16:01:43 music volumio[1268]: info: sendMpdCommand status took 1 milliseconds Sep 16 16:01:43 music volumio[1268]: verbose: ControllerMpd::parseState Sep 16 16:01:43 music volumio[1268]: info: Command Router : Notfying DB Updatetrue Sep 16 16:01:43 music volumio[1268]: info: CoreCommandRouter::Close All Modals sent Sep 16 16:01:43 music volumio[1268]: verbose: ControllerMpd::parseState Sep 16 16:01:43 music volumio[1268]: verbose: ControllerMpd::parseState Sep 16 16:01:43 music volumio[1268]: info: Command Router : Notfying DB Updatetrue Sep 16 16:01:43 music volumio[1268]: info: CoreCommandRouter::Close All Modals sent Sep 16 16:01:43 music volumio[1268]: verbose: ControllerMpd::parseState Sep 16 16:01:43 music volumio[1268]: verbose: ControllerMpd::parseState Sep 16 16:01:43 music volumio[1268]: info: Command Router : Notfying DB Updatetrue Sep 16 16:01:43 music volumio[1268]: info: CoreCommandRouter::Close All Modals sent Sep 16 16:01:43 music volumio[1268]: verbose: ControllerMpd::parseState Sep 16 16:01:43 music volumio[1268]: info: ControllerMpd::pushState Sep 16 16:01:43 music volumio[1268]: info: CoreCommandRouter::servicePushState Sep 16 16:01:43 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:43 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:43 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:43 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:43 music volumio[1268]: 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 16 16:01:43 music volumio[1268]: verbose: CURRENT POSITION 0 Sep 16 16:01:43 music volumio[1268]: info: CoreStateMachine::syncState stateService stop Sep 16 16:01:43 music volumio[1268]: info: CoreStateMachine::syncState currentStatus stop Sep 16 16:01:43 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:43 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:43 music volumio[1268]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 16:01:43 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:43 music volumio[1268]: info: No code Sep 16 16:01:43 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:43 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:43 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:43 music volumio[1268]: info: ControllerMpd::pushState Sep 16 16:01:43 music volumio[1268]: info: CoreCommandRouter::servicePushState Sep 16 16:01:43 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:43 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:43 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:43 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:43 music volumio[1268]: 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 16 16:01:43 music volumio[1268]: verbose: CURRENT POSITION 0 Sep 16 16:01:43 music volumio[1268]: info: CoreStateMachine::syncState stateService stop Sep 16 16:01:43 music volumio[1268]: info: CoreStateMachine::syncState currentStatus stop Sep 16 16:01:43 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:43 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:43 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:43 music volumio[1268]: info: No code Sep 16 16:01:43 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:43 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:43 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:43 music volumio[1268]: info: ControllerMpd::pushState Sep 16 16:01:43 music volumio[1268]: info: CoreCommandRouter::servicePushState Sep 16 16:01:43 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:43 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:43 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:43 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:43 music volumio[1268]: 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 16 16:01:43 music volumio[1268]: verbose: CURRENT POSITION 0 Sep 16 16:01:43 music volumio[1268]: info: CoreStateMachine::syncState stateService stop Sep 16 16:01:43 music volumio[1268]: info: CoreStateMachine::syncState currentStatus stop Sep 16 16:01:43 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:43 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:43 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:43 music volumio[1268]: info: No code Sep 16 16:01:43 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:43 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:43 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:43 music volumio[1268]: info: ------------------------------ 59ms Sep 16 16:01:43 music volumio[1268]: info: ------------------------------ 58ms Sep 16 16:01:43 music volumio[1268]: info: ------------------------------ 58ms Sep 16 16:01:43 music ntpd[1890]: 82.141.152.3 local addr 192.168.1.36 -> Sep 16 16:01:51 music volumio[1268]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 16 16:01:51 music volumio[1268]: info: CURURI: music-library/USB Sep 16 16:01:51 music volumio[1268]: info: Preload queue cleared Sep 16 16:01:52 music volumio[1268]: info: MPD Database updated - AlbumList cache refreshed Sep 16 16:01:52 music volumio[1268]: info: Sep 16 16:01:52 music volumio[1268]: ---------------------------- MPD announces state update: database Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:01:52 music volumio[1268]: info: Sep 16 16:01:52 music volumio[1268]: ---------------------------- MPD announces state update: update Sep 16 16:01:52 music volumio[1268]: info: ControllerMpd::getState Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:01:52 music volumio[1268]: info: ControllerMpd::getState Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:01:52 music volumio[1268]: info: MPD Database updated - AlbumList cache refreshed Sep 16 16:01:52 music volumio[1268]: info: Sep 16 16:01:52 music volumio[1268]: ---------------------------- MPD announces state update: database Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:01:52 music volumio[1268]: info: Sep 16 16:01:52 music volumio[1268]: ---------------------------- MPD announces state update: update Sep 16 16:01:52 music volumio[1268]: info: ControllerMpd::getState Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:01:52 music volumio[1268]: info: ControllerMpd::getState Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:01:52 music volumio[1268]: info: MPD Database updated - AlbumList cache refreshed Sep 16 16:01:52 music volumio[1268]: info: Sep 16 16:01:52 music volumio[1268]: ---------------------------- MPD announces state update: database Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:01:52 music volumio[1268]: info: Sep 16 16:01:52 music volumio[1268]: ---------------------------- MPD announces state update: update Sep 16 16:01:52 music volumio[1268]: info: ControllerMpd::getState Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:01:52 music volumio[1268]: info: ControllerMpd::getState Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:01:52 music volumio[1268]: info: sendMpdCommand status took 5 milliseconds Sep 16 16:01:52 music volumio[1268]: info: sendMpdCommand status took 6 milliseconds Sep 16 16:01:52 music volumio[1268]: info: sendMpdCommand status took 6 milliseconds Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::parseState Sep 16 16:01:52 music volumio[1268]: info: Command Router : Notfying DB Updatefalse Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::Close All Modals sent Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::parseState Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::parseState Sep 16 16:01:52 music volumio[1268]: info: ControllerMpd::pushState Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::servicePushState Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: 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 16 16:01:52 music volumio[1268]: verbose: CURRENT POSITION 0 Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::syncState stateService stop Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::syncState currentStatus stop Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: No code Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: ControllerMpd::pushState Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::servicePushState Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: 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 16 16:01:52 music volumio[1268]: verbose: CURRENT POSITION 0 Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::syncState stateService stop Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::syncState currentStatus stop Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: No code Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: ------------------------------ 38ms Sep 16 16:01:52 music volumio[1268]: info: ------------------------------ 39ms Sep 16 16:01:52 music volumio[1268]: info: sendMpdCommand status took 43 milliseconds Sep 16 16:01:52 music volumio[1268]: info: sendMpdCommand status took 43 milliseconds Sep 16 16:01:52 music volumio[1268]: info: sendMpdCommand status took 43 milliseconds Sep 16 16:01:52 music volumio[1268]: info: sendMpdCommand status took 43 milliseconds Sep 16 16:01:52 music volumio[1268]: info: sendMpdCommand status took 43 milliseconds Sep 16 16:01:52 music volumio[1268]: info: sendMpdCommand status took 43 milliseconds Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::parseState Sep 16 16:01:52 music volumio[1268]: info: Command Router : Notfying DB Updatefalse Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::Close All Modals sent Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::parseState Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::parseState Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::parseState Sep 16 16:01:52 music volumio[1268]: info: Command Router : Notfying DB Updatefalse Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::Close All Modals sent Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::parseState Sep 16 16:01:52 music volumio[1268]: verbose: ControllerMpd::parseState Sep 16 16:01:52 music volumio[1268]: info: ControllerMpd::pushState Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::servicePushState Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: 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 16 16:01:52 music volumio[1268]: verbose: CURRENT POSITION 0 Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::syncState stateService stop Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::syncState currentStatus stop Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: No code Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: ControllerMpd::pushState Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::servicePushState Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: 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 16 16:01:52 music volumio[1268]: verbose: CURRENT POSITION 0 Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::syncState stateService stop Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::syncState currentStatus stop Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: No code Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: ControllerMpd::pushState Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::servicePushState Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: 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 16 16:01:52 music volumio[1268]: verbose: CURRENT POSITION 0 Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::syncState stateService stop Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::syncState currentStatus stop Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: No code Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: ControllerMpd::pushState Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::servicePushState Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: 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 16 16:01:52 music volumio[1268]: verbose: CURRENT POSITION 0 Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::syncState stateService stop Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::syncState currentStatus stop Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: No code Sep 16 16:01:52 music volumio[1268]: info: CoreStateMachine::pushState Sep 16 16:01:52 music volumio[1268]: info: CorePlayQueue::getTrack 0 Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::volumioPushState Sep 16 16:01:52 music volumio[1268]: info: ------------------------------ 95ms Sep 16 16:01:52 music volumio[1268]: info: ------------------------------ 95ms Sep 16 16:01:52 music volumio[1268]: info: ------------------------------ 95ms Sep 16 16:01:52 music volumio[1268]: info: ------------------------------ 95ms Sep 16 16:01:52 music volumio[1268]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 16 16:01:52 music volumio[1268]: info: CURURI: music-library/USB/HBCD_PE_x64 Sep 16 16:01:52 music volumio[1268]: info: Preload queue cleared Sep 16 16:01:52 music volumio[1268]: info: Preloading song: music-library/USB/HBCD_PE_x64/Bud.Spencer.A.Seriff.Es.Az.Idegenek.XviD.Hun-Coopter.avi Sep 16 16:01:52 music volumio[1268]: info: Preloading song: music-library/USB/HBCD_PE_x64/The.Wild.Robot.2024.BDRip.x264.HuN-No1.mkv Sep 16 16:01:53 music volumio[1268]: info: Exploding uri music-library/USB/HBCD_PE_x64/Bud.Spencer.A.Seriff.Es.Az.Idegenek.XviD.Hun-Coopter.avi in service mpd Sep 16 16:01:53 music volumio[1268]: info: ALBUMART /albumart?cacheid=959&web=//extralarge&path=%2Fmnt%2FUSB%2FHBCD_PE_x64%2FBud.Spencer.A.Seriff.Es.Az.Idegenek.XviD.Hun-Coopter.avi&metadata=false Sep 16 16:01:53 music volumio[1268]: info: URI /mnt/USB/HBCD_PE_x64/Bud.Spencer.A.Seriff.Es.Az.Idegenek.XviD.Hun-Coopter.avi Sep 16 16:01:53 music volumio[1268]: info: Exploding uri music-library/USB/HBCD_PE_x64/The.Wild.Robot.2024.BDRip.x264.HuN-No1.mkv in service mpd Sep 16 16:01:53 music volumio[1268]: info: ALBUMART /albumart?cacheid=959&web=//extralarge&path=%2Fmnt%2FUSB%2FHBCD_PE_x64%2FThe.Wild.Robot.2024.BDRip.x264.HuN-No1.mkv&metadata=false Sep 16 16:01:53 music volumio[1268]: info: URI /mnt/USB/HBCD_PE_x64/The.Wild.Robot.2024.BDRip.x264.HuN-No1.mkv Sep 16 16:01:53 music volumio[1268]: info: Preload queue cleared Sep 16 16:01:53 music volumio[1268]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 16 16:01:53 music volumio[1268]: info: CoreStateMachine::ClearQueue Sep 16 16:01:53 music volumio[1268]: info: CoreStateMachine::stop Sep 16 16:01:53 music volumio[1268]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:01:53 music volumio[1268]: info: CorePlayQueue::clearPlayQueue Sep 16 16:01:53 music volumio[1268]: info: CorePlayQueue::saveQueue Sep 16 16:01:53 music volumio[1268]: info: CoreCommandRouter::volumioPushQueue Sep 16 16:01:53 music volumio[1268]: info: CoreStateMachine::addQueueItems Sep 16 16:01:53 music volumio[1268]: info: CorePlayQueue::addQueueItems Sep 16 16:01:53 music volumio[1268]: info: Preload queue cleared Sep 16 16:01:53 music volumio[1268]: info: Adding Item to queue: music-library/USB/HBCD_PE_x64/Bud.Spencer.A.Seriff.Es.Az.Idegenek.XviD.Hun-Coopter.avi Sep 16 16:01:53 music volumio[1268]: info: Using cached record of: music-library/USB/HBCD_PE_x64/Bud.Spencer.A.Seriff.Es.Az.Idegenek.XviD.Hun-Coopter.avi Sep 16 16:01:53 music volumio[1268]: info: Adding Item to queue: music-library/USB/HBCD_PE_x64/The.Wild.Robot.2024.BDRip.x264.HuN-No1.mkv Sep 16 16:01:53 music volumio[1268]: info: Using cached record of: music-library/USB/HBCD_PE_x64/The.Wild.Robot.2024.BDRip.x264.HuN-No1.mkv Sep 16 16:01:53 music volumio[1268]: info: CoreCommandRouter::volumioPushQueue Sep 16 16:01:53 music volumio[1268]: info: CorePlayQueue::saveQueue Sep 16 16:01:53 music volumio[1268]: info: CoreStateMachine::updateTrackBlock Sep 16 16:01:53 music volumio[1268]: info: CorePlayQueue::getTrackBlock Sep 16 16:01:53 music volumio[1268]: info: CoreCommandRouter::volumioPlay Sep 16 16:01:53 music volumio[1268]: info: CoreStateMachine::play index 1 Sep 16 16:01:53 music volumio[1268]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:01:53 music volumio[1268]: info: CoreStateMachine::addQueueItems Sep 16 16:01:53 music volumio[1268]: info: CorePlayQueue::addQueueItems Sep 16 16:01:53 music volumio[1268]: info: Preload queue cleared Sep 16 16:01:53 music volumio[1268]: info: Adding Item to queue: music-library/USB/HBCD_PE_x64/_HW Sep 16 16:01:53 music volumio[1268]: info: Exploding uri music-library/USB/HBCD_PE_x64/_HW in service mpd Sep 16 16:01:53 music volumio[1268]: info: Adding Item to queue: music-library/USB/HBCD_PE_x64/_ZSU Sep 16 16:01:53 music volumio[1268]: info: Exploding uri music-library/USB/HBCD_PE_x64/_ZSU in service mpd Sep 16 16:01:53 music volumio[1268]: info: Adding Item to queue: music-library/USB/HBCD_PE_x64/zene Sep 16 16:01:53 music volumio[1268]: info: Exploding uri music-library/USB/HBCD_PE_x64/zene in service mpd Sep 16 16:01:53 music volumio[1268]: info: CoreStateMachine::stop Sep 16 16:01:53 music volumio[1268]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:01:53 music volumio[1268]: info: CoreStateMachine::play index undefined Sep 16 16:01:53 music volumio[1268]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:01:53 music volumio[1268]: info: CorePlayQueue::getTrack 1 Sep 16 16:01:53 music volumio[1268]: info: CoreStateMachine::startPlaybackTimer Sep 16 16:01:53 music volumio[1268]: info: CorePlayQueue::getTrack 1 Sep 16 16:01:53 music volumio[1268]: verbose: ControllerMpd::clearAddPlayTracks USB/HBCD_PE_x64/The.Wild.Robot.2024.BDRip.x264.HuN-No1.mkv Sep 16 16:01:53 music volumio[1268]: verbose: ControllerMpd::sendMpdCommand stop Sep 16 16:01:53 music volumio[1268]: info: ALBUMART /albumart?cacheid=959&web=//extralarge&path=%2Fmnt%2FUSB%2FHBCD_PE_x64%2F_HW%2FHard_Disk_Sentinel%2F4.40%2F4.40.ZIP&metadata=false Sep 16 16:01:53 music volumio[1268]: info: URI /mnt/USB/HBCD_PE_x64/_HW/Hard_Disk_Sentinel/4.40/4.40.ZIP Sep 16 16:01:53 music volumio[1268]: info: ALBUMART /albumart?cacheid=959&web=//extralarge&path=%2Fmnt%2FUSB%2FHBCD_PE_x64%2F_HW%2FHard_Disk_Sentinel%2F4.40%2F4.40.ZIP&metadata=false Sep 16 16:01:53 music volumio[1268]: info: URI /mnt/USB/HBCD_PE_x64/_HW/Hard_Disk_Sentinel/4.40/4.40.ZIP Sep 16 16:01:53 music volumio[1268]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 16:01:53 music volumio[1268]: Error: Unable to resolve or reject the same promise twice Sep 16 16:01:53 music volumio[1268]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Sep 16 16:01:53 music volumio[1268]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Sep 16 16:01:53 music volumio[1268]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Sep 16 16:01:53 music volumio[1268]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Sep 16 16:01:53 music volumio[1268]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Sep 16 16:01:53 music volumio[1268]: at Socket.emit (events.js:400:28) Sep 16 16:01:53 music volumio[1268]: at addChunk (internal/streams/readable.js:293:12) Sep 16 16:01:53 music volumio[1268]: at readableAddChunk (internal/streams/readable.js:263:11) Sep 16 16:01:53 music volumio[1268]: at Socket.Readable.push (internal/streams/readable.js:206:10) Sep 16 16:01:53 music volumio[1268]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Sep 16 16:01:53 music volumio[1268]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 16:01:53 music sudo[4253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 16:00 Sep 16 16:01:53 music sudo[4253]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"