-- Logs begin at Thu 2019-02-14 15:41:58 +0530, end at Thu 2025-10-16 02:43:46 +0530. -- Oct 16 02:42:10 volumio ntpd[805]: Soliciting pool server 2606:4700:f1::1 Oct 16 02:42:22 volumio sudo[12860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 02:42:22 volumio sudo[12860]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 02:42:22 volumio sudo[12860]: pam_unix(sudo:session): session closed for user root Oct 16 02:42:22 volumio sudo[12862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 02:42:22 volumio sudo[12862]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 02:42:22 volumio sudo[12862]: pam_unix(sudo:session): session closed for user root Oct 16 02:42:22 volumio volumio[901]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.166 UA: Mozilla/5.0 (X11; Linux x86_64; rv:143.0) Gecko/20100101 Firefox/143.0 Engine version: 3 Transport: polling Total Clients: 4 Oct 16 02:42:22 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 02:42:22 volumio volumio[901]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 02:42:22 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 02:42:22 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 16 02:42:22 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:42:22 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 02:42:22 volumio volumio[901]: info: CoreCommandRouter::volumioGetQueue Oct 16 02:42:22 volumio volumio[901]: info: CoreStateMachine::getQueue Oct 16 02:42:22 volumio volumio[901]: info: CorePlayQueue::getQueue Oct 16 02:42:22 volumio volumio[901]: info: Listing playlists Oct 16 02:42:22 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 02:42:22 volumio volumio[901]: info: Received Get System Info Oct 16 02:42:22 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 02:42:22 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 02:42:22 volumio volumio[901]: info: Discovery: Getting this device information Oct 16 02:42:22 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 16 02:42:22 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:42:22 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 02:42:22 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 16 02:42:22 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:42:22 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 16 02:42:22 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 02:42:22 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 02:42:24 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 02:42:24 volumio volumio[901]: info: Received Get System Info Oct 16 02:42:24 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 02:42:24 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 02:42:24 volumio volumio[901]: info: Discovery: Getting this device information Oct 16 02:42:24 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 16 02:42:24 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:42:24 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 02:42:24 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 16 02:42:24 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 16 02:42:26 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 02:42:26 volumio volumio[901]: info: Received Get System Info Oct 16 02:42:26 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 02:42:26 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 02:42:26 volumio volumio[901]: info: Discovery: Getting this device information Oct 16 02:42:26 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 16 02:42:26 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:42:26 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 02:42:29 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 02:42:29 volumio volumio[901]: info: Received Get System Info Oct 16 02:42:29 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 02:42:29 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 02:42:29 volumio volumio[901]: info: Discovery: Getting this device information Oct 16 02:42:29 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 16 02:42:29 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:42:29 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 02:42:30 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 02:42:30 volumio volumio[901]: info: Received Get System Info Oct 16 02:42:30 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 02:42:30 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 02:42:30 volumio volumio[901]: info: Discovery: Getting this device information Oct 16 02:42:30 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 16 02:42:30 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:42:30 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 02:42:32 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 16 02:42:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 02:42:41 volumio volumio[901]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 16 02:42:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 16 02:42:41 volumio volumio[901]: info: Received Get System Version Oct 16 02:42:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 02:42:41 volumio volumio[901]: info: Received Get System Info Oct 16 02:42:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 02:42:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 02:42:41 volumio volumio[901]: info: Discovery: Getting this device information Oct 16 02:42:41 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 16 02:42:41 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:42:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 02:42:41 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 16 02:42:41 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:42:45 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 02:42:45 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 16 02:42:45 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 02:42:45 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 02:42:45 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 02:42:45 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 02:42:45 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 02:42:45 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 02:42:45 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 02:42:45 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 02:42:45 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 02:42:45 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 16 02:42:45 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 02:42:46 volumio kernel: usb 1-1.4: new high-speed USB device number 5 using xhci_hcd Oct 16 02:42:46 volumio kernel: usb 1-1.4: New USB device found, idVendor=12d1, idProduct=14fe, bcdDevice= 1.02 Oct 16 02:42:46 volumio kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 16 02:42:46 volumio kernel: usb 1-1.4: Product: HUAWEI_MOBILE Oct 16 02:42:46 volumio kernel: usb 1-1.4: Manufacturer: HUAWEI_MOBILE Oct 16 02:42:46 volumio kernel: usb 1-1.4: SerialNumber: 0123456789ABCDEF Oct 16 02:42:46 volumio kernel: usb-storage 1-1.4:1.0: USB Mass Storage device detected Oct 16 02:42:46 volumio kernel: scsi host1: usb-storage 1-1.4:1.0 Oct 16 02:42:47 volumio kernel: scsi 1:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2 Oct 16 02:42:47 volumio kernel: sr 1:0:0:0: Power-on or device reset occurred Oct 16 02:42:47 volumio kernel: sr 1:0:0:0: [sr0] scsi-1 drive Oct 16 02:42:47 volumio kernel: sr 1:0:0:0: Attached scsi CD-ROM sr0 Oct 16 02:42:47 volumio kernel: sr 1:0:0:0: Attached scsi generic sg0 type 5 Oct 16 02:42:47 volumio kernel: scsi 1:0:0:1: Direct-Access HUAWEI TF CARD Storage 2.31 PQ: 0 ANSI: 2 Oct 16 02:42:47 volumio kernel: sd 1:0:0:1: Attached scsi generic sg1 type 0 Oct 16 02:42:47 volumio kernel: sd 1:0:0:1: Power-on or device reset occurred Oct 16 02:42:47 volumio kernel: sd 1:0:0:1: [sdb] Media removed, stopped polling Oct 16 02:42:47 volumio kernel: sd 1:0:0:1: [sdb] Attached SCSI removable disk Oct 16 02:42:49 volumio kernel: sd 1:0:0:1: [sdb] 122138624 512-byte logical blocks: (62.5 GB/58.2 GiB) Oct 16 02:42:49 volumio kernel: sd 1:0:0:1: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Oct 16 02:42:49 volumio kernel: sdb: detected capacity change from 0 to 122138624 Oct 16 02:42:49 volumio kernel: sdb: sdb1 Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 Sense Key : 0x3 [current] Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 ASC=0x11 ASCQ=0x0 Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 CDB: opcode=0x28 28 00 00 00 8d fc 00 00 02 00 Oct 16 02:42:49 volumio kernel: critical medium error, dev sr0, sector 145392 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 Sense Key : 0x3 [current] Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 ASC=0x11 ASCQ=0x0 Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 CDB: opcode=0x28 28 00 00 00 8c 80 00 00 3c 00 Oct 16 02:42:49 volumio kernel: critical medium error, dev sr0, sector 143872 op 0x0:(READ) flags 0x84700 phys_seg 30 prio class 2 Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 Sense Key : 0x3 [current] Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 ASC=0x11 ASCQ=0x0 Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 CDB: opcode=0x28 28 00 00 00 8c bc 00 00 04 00 Oct 16 02:42:49 volumio kernel: critical medium error, dev sr0, sector 144112 op 0x0:(READ) flags 0x80700 phys_seg 2 prio class 2 Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 Sense Key : 0x3 [current] Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 ASC=0x11 ASCQ=0x0 Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 CDB: opcode=0x28 28 00 00 00 8c 80 00 00 02 00 Oct 16 02:42:49 volumio kernel: critical medium error, dev sr0, sector 143872 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Oct 16 02:42:49 volumio kernel: Buffer I/O error on dev sr0, logical block 17984, async page read Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 Sense Key : 0x3 [current] Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 ASC=0x11 ASCQ=0x0 Oct 16 02:42:49 volumio kernel: sr 1:0:0:0: [sr0] tag#0 CDB: opcode=0x28 28 00 00 00 8d fa 00 00 02 00 Oct 16 02:42:49 volumio kernel: critical medium error, dev sr0, sector 145384 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Oct 16 02:42:49 volumio volumio[901]: info: Mounting Device 20A0-F914 Oct 16 02:42:49 volumio volumio[901]: /bin/mkdir: cannot create directory ‘/mnt/USB/20A0-F914’: File exists Oct 16 02:42:49 volumio volumio[901]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/20A0-F914" Oct 16 02:42:49 volumio volumio[901]: /bin/mkdir: cannot create directory ‘/mnt/USB/20A0-F914’: File exists Oct 16 02:42:49 volumio sudo[12976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/20A0-F914 -o noatime Oct 16 02:42:49 volumio sudo[12976]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 02:42:50 volumio sudo[12976]: pam_unix(sudo:session): session closed for user root Oct 16 02:42:50 volumio volumio[901]: fuse: bad mount point `/mnt/USB/20A0-F914': Transport endpoint is not connected Oct 16 02:42:50 volumio volumio[901]: error: Failed to mount 20A0-F914: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/20A0-F914" -o noatime Oct 16 02:42:50 volumio volumio[901]: fuse: bad mount point `/mnt/USB/20A0-F914': Transport endpoint is not connected Oct 16 02:42:52 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 16 02:42:52 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:42:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 16 02:42:58 volumio volumio[901]: info: CURURI: music-library Oct 16 02:42:58 volumio volumio[901]: error: Failed LSINFO: null Oct 16 02:42:58 volumio volumio[901]: info: Preload queue cleared Oct 16 02:43:02 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 16 02:43:02 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:03 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 02:43:03 volumio volumio[901]: info: Retrieving Cloud Streaming UI Oct 16 02:43:03 volumio volumio[901]: info: Getting Tidal Cloud Configuration Oct 16 02:43:03 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 02:43:03 volumio volumio[901]: info: Getting Qobuz Cloud Configuration Oct 16 02:43:03 volumio volumio[901]: info: Asking plugin for UI Config Oct 16 02:43:03 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 02:43:03 volumio volumio[901]: info: Getting Spotify Cloud Configuration Oct 16 02:43:03 volumio volumio[901]: info: Asking plugin for UI Config Oct 16 02:43:03 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 02:43:03 volumio volumio[901]: info: Saving Spotify Acccount Oct 16 02:43:03 volumio volumio[901]: info: Got Tidal Cloud Configuration Oct 16 02:43:03 volumio volumio[901]: info: Got it Oct 16 02:43:03 volumio volumio[901]: info: Got it Oct 16 02:43:03 volumio volumio[901]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 16 02:43:03 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 02:43:03 volumio volumio[901]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 16 02:43:03 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 02:43:03 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 02:43:03 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 02:43:03 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 02:43:03 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 02:43:03 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 02:43:03 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 02:43:03 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 02:43:03 volumio volumio[901]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 02:43:03 volumio volumio[901]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 02:43:03 volumio volumio[901]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 02:43:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 02:43:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 16 02:43:05 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 16 02:43:05 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand update Oct 16 02:43:05 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 16 02:43:05 volumio volumio[901]: info: Oct 16 02:43:05 volumio volumio[901]: ---------------------------- MPD announces state update: update Oct 16 02:43:05 volumio volumio[901]: info: sendMpdCommand update took 3 milliseconds Oct 16 02:43:05 volumio volumio[901]: info: ControllerMpd::getState Oct 16 02:43:05 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 16 02:43:05 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 16 02:43:05 volumio volumio[901]: info: Oct 16 02:43:05 volumio volumio[901]: ---------------------------- MPD announces state update: update Oct 16 02:43:05 volumio volumio[901]: info: sendMpdCommand status took 4 milliseconds Oct 16 02:43:05 volumio volumio[901]: info: ControllerMpd::getState Oct 16 02:43:05 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 16 02:43:05 volumio volumio[901]: info: sendMpdCommand status took 4 milliseconds Oct 16 02:43:05 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 16 02:43:05 volumio volumio[901]: info: Command Router : Notfying DB Updatetrue Oct 16 02:43:05 volumio volumio[901]: info: CoreCommandRouter::Close All Modals sent Oct 16 02:43:05 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 16 02:43:05 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 02:43:05 volumio volumio[901]: info: sendMpdCommand status took 59 milliseconds Oct 16 02:43:05 volumio volumio[901]: info: sendMpdCommand status took 59 milliseconds Oct 16 02:43:05 volumio volumio[901]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 02:43:05 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 16 02:43:05 volumio volumio[901]: info: Command Router : Notfying DB Updatefalse Oct 16 02:43:05 volumio volumio[901]: info: CoreCommandRouter::Close All Modals sent Oct 16 02:43:05 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 16 02:43:05 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 02:43:05 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 16 02:43:05 volumio volumio[901]: info: ControllerMpd::pushState Oct 16 02:43:05 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 16 02:43:05 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:05 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":36333,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"840 Kbps","isStreaming":false,"title":"Searchin","artist":"Eminem","album":"Infinite","uri":"USB/20A0-F914/Music/B3. Searchin.flac","trackType":"flac"} Oct 16 02:43:05 volumio volumio[901]: verbose: CURRENT POSITION 8 Oct 16 02:43:05 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Oct 16 02:43:05 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Oct 16 02:43:05 volumio volumio[901]: info: CoreStateMachine::pushState Oct 16 02:43:05 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:05 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 16 02:43:05 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 16 02:43:05 volumio volumio[901]: info: ------------------------------ 91ms Oct 16 02:43:05 volumio volumio[901]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 16 02:43:05 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 16 02:43:05 volumio volumio[901]: info: ControllerMpd::pushState Oct 16 02:43:05 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 16 02:43:05 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:05 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":36333,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"840 Kbps","isStreaming":false,"title":"Searchin","artist":"Eminem","album":"Infinite","uri":"USB/20A0-F914/Music/B3. Searchin.flac","trackType":"flac"} Oct 16 02:43:05 volumio volumio[901]: verbose: CURRENT POSITION 8 Oct 16 02:43:05 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Oct 16 02:43:05 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Oct 16 02:43:05 volumio volumio[901]: info: CoreStateMachine::pushState Oct 16 02:43:05 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:05 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 16 02:43:05 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 16 02:43:05 volumio volumio[901]: info: ------------------------------ 95ms Oct 16 02:43:07 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 16 02:43:07 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand rescan Oct 16 02:43:07 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 16 02:43:07 volumio volumio[901]: info: Oct 16 02:43:07 volumio volumio[901]: ---------------------------- MPD announces state update: update Oct 16 02:43:07 volumio volumio[901]: info: sendMpdCommand rescan took 2 milliseconds Oct 16 02:43:07 volumio volumio[901]: info: ControllerMpd::getState Oct 16 02:43:07 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 16 02:43:07 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 16 02:43:07 volumio volumio[901]: info: Oct 16 02:43:07 volumio volumio[901]: ---------------------------- MPD announces state update: update Oct 16 02:43:07 volumio volumio[901]: info: ControllerMpd::getState Oct 16 02:43:07 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 16 02:43:07 volumio volumio[901]: info: sendMpdCommand status took 1 milliseconds Oct 16 02:43:07 volumio volumio[901]: info: sendMpdCommand status took 2 milliseconds Oct 16 02:43:07 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 16 02:43:07 volumio volumio[901]: info: Command Router : Notfying DB Updatefalse Oct 16 02:43:07 volumio volumio[901]: info: CoreCommandRouter::Close All Modals sent Oct 16 02:43:07 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 16 02:43:07 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 02:43:07 volumio volumio[901]: info: sendMpdCommand status took 25 milliseconds Oct 16 02:43:07 volumio volumio[901]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 16 02:43:07 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 16 02:43:07 volumio volumio[901]: info: Command Router : Notfying DB Updatefalse Oct 16 02:43:07 volumio volumio[901]: info: CoreCommandRouter::Close All Modals sent Oct 16 02:43:07 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 16 02:43:07 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 02:43:07 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 16 02:43:07 volumio volumio[901]: info: ControllerMpd::pushState Oct 16 02:43:07 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 16 02:43:07 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:07 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":36333,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"840 Kbps","isStreaming":false,"title":"Searchin","artist":"Eminem","album":"Infinite","uri":"USB/20A0-F914/Music/B3. Searchin.flac","trackType":"flac"} Oct 16 02:43:07 volumio volumio[901]: verbose: CURRENT POSITION 8 Oct 16 02:43:07 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Oct 16 02:43:07 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Oct 16 02:43:07 volumio volumio[901]: info: CoreStateMachine::pushState Oct 16 02:43:07 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:07 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 16 02:43:07 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 16 02:43:07 volumio volumio[901]: info: ------------------------------ 55ms Oct 16 02:43:07 volumio volumio[901]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 16 02:43:07 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 16 02:43:07 volumio volumio[901]: info: ControllerMpd::pushState Oct 16 02:43:07 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 16 02:43:07 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:07 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":36333,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"840 Kbps","isStreaming":false,"title":"Searchin","artist":"Eminem","album":"Infinite","uri":"USB/20A0-F914/Music/B3. Searchin.flac","trackType":"flac"} Oct 16 02:43:07 volumio volumio[901]: verbose: CURRENT POSITION 8 Oct 16 02:43:07 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Oct 16 02:43:07 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Oct 16 02:43:07 volumio volumio[901]: info: CoreStateMachine::pushState Oct 16 02:43:07 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:07 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 16 02:43:07 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 16 02:43:07 volumio volumio[901]: info: ------------------------------ 66ms Oct 16 02:43:08 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 02:43:12 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 02:43:15 volumio ntpd[805]: Soliciting pool server 2606:4700:f1::123 Oct 16 02:43:16 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 02:43:16 volumio volumio[901]: info: Disabling MyMusic plugin upnp_browser Oct 16 02:43:16 volumio volumio[901]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Oct 16 02:43:16 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 02:43:16 volumio volumio[901]: info: Disabling plugin upnp_browser Oct 16 02:43:16 volumio volumio[901]: info: Done. Oct 16 02:43:17 volumio volumio[901]: info: Disabling MyMusic plugin airplay_emulation Oct 16 02:43:17 volumio volumio[901]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Oct 16 02:43:17 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 02:43:17 volumio volumio[901]: info: Disabling plugin airplay_emulation Oct 16 02:43:17 volumio volumio[901]: info: Done. Oct 16 02:43:17 volumio sudo[13023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Oct 16 02:43:17 volumio sudo[13023]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 02:43:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 16 02:43:17 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 16 02:43:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 16 02:43:17 volumio sudo[13023]: pam_unix(sudo:session): session closed for user root Oct 16 02:43:17 volumio volumio[901]: info: Shairport-Sync Stopped Oct 16 02:43:18 volumio volumio[901]: info: Disabling MyMusic plugin upnp Oct 16 02:43:18 volumio sudo[13043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 16 02:43:18 volumio sudo[13043]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 02:43:18 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 16 02:43:18 volumio volumio[901]: error: Upnp client error: Error: This socket has been ended by the other party Oct 16 02:43:20 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 02:43:24 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 02:43:28 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 02:43:31 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 16 02:43:31 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand update Oct 16 02:43:31 volumio volumio[901]: info: sendMpdCommand update took 1 milliseconds Oct 16 02:43:31 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 16 02:43:31 volumio volumio[901]: info: Oct 16 02:43:31 volumio volumio[901]: ---------------------------- MPD announces state update: update Oct 16 02:43:31 volumio volumio[901]: info: ControllerMpd::getState Oct 16 02:43:31 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 16 02:43:31 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 16 02:43:31 volumio volumio[901]: info: Oct 16 02:43:31 volumio volumio[901]: ---------------------------- MPD announces state update: update Oct 16 02:43:31 volumio volumio[901]: info: ControllerMpd::getState Oct 16 02:43:31 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 16 02:43:31 volumio volumio[901]: info: sendMpdCommand status took 1 milliseconds Oct 16 02:43:31 volumio volumio[901]: info: sendMpdCommand status took 2 milliseconds Oct 16 02:43:31 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 16 02:43:31 volumio volumio[901]: info: Command Router : Notfying DB Updatefalse Oct 16 02:43:31 volumio volumio[901]: info: CoreCommandRouter::Close All Modals sent Oct 16 02:43:31 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 16 02:43:31 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 02:43:31 volumio volumio[901]: info: sendMpdCommand status took 45 milliseconds Oct 16 02:43:31 volumio volumio[901]: info: sendMpdCommand status took 45 milliseconds Oct 16 02:43:31 volumio volumio[901]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 02:43:31 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 16 02:43:31 volumio volumio[901]: info: Command Router : Notfying DB Updatefalse Oct 16 02:43:31 volumio volumio[901]: info: CoreCommandRouter::Close All Modals sent Oct 16 02:43:31 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 16 02:43:31 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 02:43:31 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 16 02:43:31 volumio volumio[901]: info: ControllerMpd::pushState Oct 16 02:43:31 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 16 02:43:31 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:31 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":36333,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"840 Kbps","isStreaming":false,"title":"Searchin","artist":"Eminem","album":"Infinite","uri":"USB/20A0-F914/Music/B3. Searchin.flac","trackType":"flac"} Oct 16 02:43:31 volumio volumio[901]: verbose: CURRENT POSITION 8 Oct 16 02:43:31 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Oct 16 02:43:31 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Oct 16 02:43:31 volumio volumio[901]: info: CoreStateMachine::pushState Oct 16 02:43:31 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:31 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 16 02:43:31 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 16 02:43:31 volumio volumio[901]: info: ------------------------------ 74ms Oct 16 02:43:31 volumio volumio[901]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 16 02:43:31 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 16 02:43:31 volumio volumio[901]: info: ControllerMpd::pushState Oct 16 02:43:31 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 16 02:43:31 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:31 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":36333,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"840 Kbps","isStreaming":false,"title":"Searchin","artist":"Eminem","album":"Infinite","uri":"USB/20A0-F914/Music/B3. Searchin.flac","trackType":"flac"} Oct 16 02:43:31 volumio volumio[901]: verbose: CURRENT POSITION 8 Oct 16 02:43:31 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Oct 16 02:43:31 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Oct 16 02:43:31 volumio volumio[901]: info: CoreStateMachine::pushState Oct 16 02:43:31 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:31 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 16 02:43:31 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 16 02:43:31 volumio volumio[901]: info: ------------------------------ 83ms Oct 16 02:43:32 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 02:43:32 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 16 02:43:32 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand rescan Oct 16 02:43:32 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 16 02:43:32 volumio volumio[901]: info: Oct 16 02:43:32 volumio volumio[901]: ---------------------------- MPD announces state update: update Oct 16 02:43:32 volumio volumio[901]: info: sendMpdCommand rescan took 1 milliseconds Oct 16 02:43:32 volumio volumio[901]: info: ControllerMpd::getState Oct 16 02:43:32 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 16 02:43:32 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 16 02:43:32 volumio volumio[901]: info: Oct 16 02:43:32 volumio volumio[901]: ---------------------------- MPD announces state update: update Oct 16 02:43:32 volumio volumio[901]: info: ControllerMpd::getState Oct 16 02:43:32 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 16 02:43:32 volumio volumio[901]: info: sendMpdCommand status took 2 milliseconds Oct 16 02:43:32 volumio volumio[901]: info: sendMpdCommand status took 1 milliseconds Oct 16 02:43:32 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 16 02:43:32 volumio volumio[901]: info: Command Router : Notfying DB Updatefalse Oct 16 02:43:32 volumio volumio[901]: info: CoreCommandRouter::Close All Modals sent Oct 16 02:43:32 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 16 02:43:32 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 02:43:32 volumio volumio[901]: info: sendMpdCommand status took 30 milliseconds Oct 16 02:43:32 volumio volumio[901]: info: sendMpdCommand status took 39 milliseconds Oct 16 02:43:32 volumio volumio[901]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 16 02:43:32 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 16 02:43:32 volumio volumio[901]: info: Command Router : Notfying DB Updatefalse Oct 16 02:43:32 volumio volumio[901]: info: CoreCommandRouter::Close All Modals sent Oct 16 02:43:32 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 16 02:43:32 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 02:43:32 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 16 02:43:32 volumio volumio[901]: info: ControllerMpd::pushState Oct 16 02:43:32 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 16 02:43:32 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:32 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":36333,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"840 Kbps","isStreaming":false,"title":"Searchin","artist":"Eminem","album":"Infinite","uri":"USB/20A0-F914/Music/B3. Searchin.flac","trackType":"flac"} Oct 16 02:43:32 volumio volumio[901]: verbose: CURRENT POSITION 8 Oct 16 02:43:32 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Oct 16 02:43:32 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Oct 16 02:43:32 volumio volumio[901]: info: CoreStateMachine::pushState Oct 16 02:43:32 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:32 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 16 02:43:32 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 16 02:43:32 volumio volumio[901]: info: ------------------------------ 71ms Oct 16 02:43:32 volumio volumio[901]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 16 02:43:32 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 16 02:43:32 volumio volumio[901]: info: ControllerMpd::pushState Oct 16 02:43:32 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 16 02:43:32 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:32 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":36333,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"840 Kbps","isStreaming":false,"title":"Searchin","artist":"Eminem","album":"Infinite","uri":"USB/20A0-F914/Music/B3. Searchin.flac","trackType":"flac"} Oct 16 02:43:32 volumio volumio[901]: verbose: CURRENT POSITION 8 Oct 16 02:43:32 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Oct 16 02:43:32 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Oct 16 02:43:32 volumio volumio[901]: info: CoreStateMachine::pushState Oct 16 02:43:32 volumio volumio[901]: info: CorePlayQueue::getTrack 8 Oct 16 02:43:32 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 16 02:43:32 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 16 02:43:32 volumio volumio[901]: info: ------------------------------ 78ms Oct 16 02:43:33 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 16 02:43:33 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 16 02:43:36 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 02:43:40 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 02:43:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 02:43:46 volumio volumio[901]: info: Enabling MyMusic plugin upnp Oct 16 02:43:46 volumio volumio[901]: info: Enabling plugin upnp Oct 16 02:43:46 volumio volumio[901]: info: Loading plugin "upnp"... Oct 16 02:43:46 volumio volumio[901]: info: [1760562826317] Starting Upmpd Daemon Oct 16 02:43:46 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 02:43:46 volumio volumio[901]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 02:43:46 volumio volumio[901]: Error: listen EADDRINUSE: address already in use :::6599 Oct 16 02:43:46 volumio volumio[901]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Oct 16 02:43:46 volumio volumio[901]: at listenInCluster (net.js:1366:12) Oct 16 02:43:46 volumio volumio[901]: at Server.listen (net.js:1452:7) Oct 16 02:43:46 volumio volumio[901]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 16 02:43:46 volumio volumio[901]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 16 02:43:46 volumio volumio[901]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 16 02:43:46 volumio volumio[901]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 16 02:43:46 volumio volumio[901]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 16 02:43:46 volumio volumio[901]: code: 'EADDRINUSE', Oct 16 02:43:46 volumio volumio[901]: errno: -98, Oct 16 02:43:46 volumio volumio[901]: syscall: 'listen', Oct 16 02:43:46 volumio volumio[901]: address: '::', Oct 16 02:43:46 volumio volumio[901]: port: 6599 Oct 16 02:43:46 volumio volumio[901]: } Oct 16 02:43:46 volumio volumio[901]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 02:43:46 volumio sudo[13137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-16 02:42 Oct 16 02:43:46 volumio sudo[13137]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"