-- Logs begin at Tue 2024-04-02 18:37:21 CEST, end at Mon 2024-04-08 15:35:30 CEST. -- Apr 08 15:34:01 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:34:01 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:34:01 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:01 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:34:01 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:05 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:34:05 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:34:05 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:05 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:34:05 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:09 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:34:09 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:34:09 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:09 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:34:09 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:13 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:34:13 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:34:13 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:13 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:34:13 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:17 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:34:17 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:34:17 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:17 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:34:17 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:21 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:34:21 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:34:21 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:21 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:34:21 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:24 primov2 volumio[5637]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 08 15:34:25 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:34:25 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:34:25 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:25 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:34:25 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:29 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:34:29 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:34:29 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:29 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:34:29 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:29 primov2 kernel: usb 1-1.2: USB disconnect, device number 4 Apr 08 15:34:29 primov2 kernel: sd 1:0:0:0: [sda] Synchronizing SCSI cache Apr 08 15:34:29 primov2 kernel: sd 1:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x01 driverbyte=0x00 Apr 08 15:34:29 primov2 volumio[5637]: info: Partition removed: {"syspath":"/sys/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-Micron_CT1000X8SSD9_2323E32EFF67-0:0-part1 /dev/disk/by-path/platform-xhci-hcd.0.auto-usb-0:1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-partlabel/Microsoft\\x20reserved\\x20partition /dev/disk/by-partuuid/81c29a69-c50f-45bd-b760-347fc78a6dd5","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sda/sda1","DEVTYPE":"partition","ID_BUS":"usb","ID_INSTANCE":"0:0","ID_MODEL":"CT1000X8SSD9","ID_MODEL_ENC":"CT1000X8SSD9\\x20\\x20\\x20\\x20","ID_MODEL_ID":"5602","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NAME":"Microsoft\\x20reserved\\x20partition","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"34","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"262144","ID_PART_ENTRY_TYPE":"e3c9e316-0b5c-4db8-817d-f92df00215ae","ID_PART_ENTRY_UUID":"81c29a69-c50f-45bd-b760-347fc78a6dd5","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"12dc12b3-2190-4eaf-9e44-b4b9cccf6457","ID_PATH":"platform-xhci-hcd.0.auto-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-xhci-hcd_0_auto-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"PMAP","ID_SERIAL":"Micron_CT1000X8SSD9_2323E32EFF67-0:0","ID_SERIAL_SHORT":"2323E32EFF67","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Micron","ID_VENDOR_ENC":"Micron\\x20\\x20","ID_VENDOR_ID":"0634","MAJOR":"8","MINOR":"1","PARTN":"1","PARTNAME":"Microsoft reserved partition","SEQNUM":"5617","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"8464995"} Apr 08 15:34:29 primov2 volumio[5637]: error: Cannot associate FS Label, not mounting Apr 08 15:34:29 primov2 volumio[5637]: info: Partition removed: {"syspath":"/sys/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-xhci-hcd.0.auto-usb-0:1.2:1.0-scsi-0:0:0:0-part2 /dev/disk/by-label/Crucial\\x20X8 /dev/disk/by-partlabel/Basic\\x20data\\x20partition /dev/disk/by-partuuid/0462a42a-7171-43bd-97b6-1fa0dbaad436 /dev/disk/by-id/usb-Micron_CT1000X8SSD9_2323E32EFF67-0:0-part2 /dev/disk/by-uuid/54B3-EDEC","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sda/sda2","DEVTYPE":"partition","ID_BUS":"usb","ID_FS_LABEL":"Crucial_X8","ID_FS_LABEL_ENC":"Crucial\\x20X8","ID_FS_TYPE":"exfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"54B3-EDEC","ID_FS_UUID_ENC":"54B3-EDEC","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"CT1000X8SSD9","ID_MODEL_ENC":"CT1000X8SSD9\\x20\\x20\\x20\\x20","ID_MODEL_ID":"5602","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NAME":"Basic\\x20data\\x20partition","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"264192","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"1953259520","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"0462a42a-7171-43bd-97b6-1fa0dbaad436","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"12dc12b3-2190-4eaf-9e44-b4b9cccf6457","ID_PATH":"platform-xhci-hcd.0.auto-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-xhci-hcd_0_auto-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"PMAP","ID_SERIAL":"Micron_CT1000X8SSD9_2323E32EFF67-0:0","ID_SERIAL_SHORT":"2323E32EFF67","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Micron","ID_VENDOR_ENC":"Micron\\x20\\x20","ID_VENDOR_ID":"0634","MAJOR":"8","MINOR":"2","PARTN":"2","PARTNAME":"Basic data partition","SEQNUM":"5616","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"8459836"} Apr 08 15:34:29 primov2 sudo[8167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda2 Apr 08 15:34:29 primov2 sudo[8167]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 15:34:29 primov2 systemd[1]: media-Crucial_X8.mount: Succeeded. Apr 08 15:34:29 primov2 sudo[8167]: pam_unix(sudo:session): session closed for user root Apr 08 15:34:31 primov2 mount.exfat[6034]: failed to write super block Apr 08 15:34:33 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:34:33 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:34:33 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:33 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:34:33 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:33 primov2 volumio[5637]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/Crucial_X8 Apr 08 15:34:34 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 08 15:34:34 primov2 volumio[5637]: verbose: ControllerMpd::sendMpdCommand rescan Apr 08 15:34:34 primov2 volumio[5637]: MPD error: Connection refused Apr 08 15:34:37 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:34:37 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:34:37 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:37 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:34:37 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:41 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:34:41 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:34:41 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:41 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:34:41 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:43 primov2 sudo[8174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 08 15:34:43 primov2 sudo[8174]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 15:34:43 primov2 sudo[8174]: pam_unix(sudo:session): session closed for user root Apr 08 15:34:43 primov2 sudo[8178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 08 15:34:43 primov2 sudo[8178]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 15:34:43 primov2 sudo[8178]: pam_unix(sudo:session): session closed for user root Apr 08 15:34:43 primov2 volumio[5637]: verbose: New Socket.io Connection to 192.168.1.247 from 192.168.1.181 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 8 Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::volumioGetVisibleSources Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 08 15:34:43 primov2 volumio[5637]: info: Retrieving Cloud Streaming UI Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::volumioGetState Apr 08 15:34:43 primov2 volumio[5637]: info: CorePlayQueue::getTrack 0 Apr 08 15:34:43 primov2 volumio[5637]: info: Getting Tidal Cloud Configuration Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 08 15:34:43 primov2 volumio[5637]: info: Getting Qobuz Cloud Configuration Apr 08 15:34:43 primov2 volumio[5637]: info: Asking plugin for UI Config Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 08 15:34:43 primov2 volumio[5637]: info: Getting Spotify Cloud Configuration Apr 08 15:34:43 primov2 volumio[5637]: info: Asking plugin for UI Config Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 08 15:34:43 primov2 volumio[5637]: info: Saving Spotify Acccount Apr 08 15:34:43 primov2 volumio[5637]: info: Got it Apr 08 15:34:43 primov2 volumio[5637]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 08 15:34:43 primov2 volumio[5637]: info: Got Tidal Cloud Configuration Apr 08 15:34:43 primov2 volumio[5637]: info: Got it Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 08 15:34:43 primov2 volumio[5637]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::volumioGetBrowseSources Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::volumioGetBrowseSources Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::volumioGetBrowseSources Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 08 15:34:43 primov2 volumio[5637]: info: Received Get System Info Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 08 15:34:43 primov2 volumio[5637]: info: Discovery: Getting this device information Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::volumioGetState Apr 08 15:34:43 primov2 volumio[5637]: info: CorePlayQueue::getTrack 0 Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::volumioGetState Apr 08 15:34:43 primov2 volumio[5637]: info: CorePlayQueue::getTrack 0 Apr 08 15:34:43 primov2 volumio[5637]: info: Listing playlists Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:34:43 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:34:43 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:43 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:34:43 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 08 15:34:43 primov2 volumio[5637]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 08 15:34:44 primov2 volumio[5637]: info: CoreStateMachine::pushState Apr 08 15:34:44 primov2 volumio[5637]: info: CorePlayQueue::getTrack 0 Apr 08 15:34:44 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 15:34:44 primov2 volumio[5637]: info: CoreCommandRouter::volumioPushState Apr 08 15:34:44 primov2 volumio[5637]: info: MRS: Pushing multiroomSync output update for this device Apr 08 15:34:45 primov2 volumio[5637]: info: MRS: Pushing multiroomSync output Apr 08 15:34:45 primov2 volumio[5637]: info: Updating RAAT Signal Path Apr 08 15:34:45 primov2 volumio[5637]: info: MCU Signalled Sleep Mode Active Apr 08 15:34:45 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 08 15:34:45 primov2 sudo[8182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force off Apr 08 15:34:45 primov2 sudo[8182]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 15:34:45 primov2 volumio[5637]: info: Received Get System Info Apr 08 15:34:45 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 08 15:34:45 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 08 15:34:45 primov2 volumio[5637]: info: Discovery: Getting this device information Apr 08 15:34:45 primov2 volumio[5637]: info: CoreCommandRouter::volumioGetState Apr 08 15:34:45 primov2 volumio[5637]: info: CorePlayQueue::getTrack 0 Apr 08 15:34:45 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 08 15:34:45 primov2 kernel: fb: osd[0] enable: 1 (Xorg) Apr 08 15:34:45 primov2 kernel: fb: osd[0] enable: 0 (Xorg) Apr 08 15:34:45 primov2 kernel: fb: osd[0] enable: 0 (Xorg) Apr 08 15:34:45 primov2 sudo[8182]: pam_unix(sudo:session): session closed for user root Apr 08 15:34:45 primov2 volumio[5637]: info: Enabling Screen Saver via dpms Apr 08 15:34:45 primov2 volumio[5637]: info: Touch Event Listener Process Starting Apr 08 15:34:45 primov2 sudo[8185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Apr 08 15:34:45 primov2 sudo[8185]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 15:34:46 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 08 15:34:46 primov2 volumio[5637]: info: Received Get System Info Apr 08 15:34:46 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 08 15:34:46 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 08 15:34:46 primov2 volumio[5637]: info: Discovery: Getting this device information Apr 08 15:34:46 primov2 volumio[5637]: info: CoreCommandRouter::volumioGetState Apr 08 15:34:46 primov2 volumio[5637]: info: CorePlayQueue::getTrack 0 Apr 08 15:34:46 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 08 15:34:47 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:34:47 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:34:47 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:47 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:34:47 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:48 primov2 volumio[5637]: info: MCU Signalled Sleep Mode Disabled Apr 08 15:34:48 primov2 sudo[8188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Apr 08 15:34:48 primov2 sudo[8188]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 15:34:48 primov2 kernel: fb: osd[0] enable: 1 (Xorg) Apr 08 15:34:48 primov2 kernel: fb: osd[0] enable: 1 (Xorg) Apr 08 15:34:48 primov2 sudo[8188]: pam_unix(sudo:session): session closed for user root Apr 08 15:34:48 primov2 volumio[5637]: info: CoreStateMachine::pushState Apr 08 15:34:48 primov2 volumio[5637]: info: CorePlayQueue::getTrack 0 Apr 08 15:34:48 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 15:34:48 primov2 volumio[5637]: info: CoreCommandRouter::volumioPushState Apr 08 15:34:48 primov2 volumio[5637]: info: MRS: Pushing multiroomSync output update for this device Apr 08 15:34:48 primov2 volumio[5637]: info: MRS: Pushing multiroomSync output Apr 08 15:34:48 primov2 volumio[5637]: info: Updating RAAT Signal Path Apr 08 15:34:51 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:34:51 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:34:51 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:51 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:34:51 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:51 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 08 15:34:51 primov2 volumio[5637]: verbose: ControllerMpd::sendMpdCommand rescan Apr 08 15:34:55 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:34:55 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:34:55 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:55 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:34:55 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:59 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:34:59 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:34:59 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:34:59 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:34:59 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:35:01 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 08 15:35:01 primov2 volumio[5637]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 08 15:35:01 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 08 15:35:01 primov2 volumio[5637]: info: Received Get System Version Apr 08 15:35:01 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 15:35:01 primov2 volumio[5637]: info: Received Get System Info Apr 08 15:35:01 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 08 15:35:01 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 08 15:35:01 primov2 volumio[5637]: info: Discovery: Getting this device information Apr 08 15:35:01 primov2 volumio[5637]: info: CoreCommandRouter::volumioGetState Apr 08 15:35:01 primov2 volumio[5637]: info: CorePlayQueue::getTrack 0 Apr 08 15:35:01 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 08 15:35:04 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:35:04 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:35:04 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:35:04 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:35:04 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:35:07 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 08 15:35:07 primov2 volumio[5637]: error: MPD error: The expression evaluated to a falsy value: Apr 08 15:35:07 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:35:07 primov2 volumio[5637]: error: The expression evaluated to a falsy value: Apr 08 15:35:07 primov2 volumio[5637]: assert.ok(self.idling) Apr 08 15:35:10 primov2 volumio[5637]: info: CoreCommandRouter::volumioGetState Apr 08 15:35:10 primov2 volumio[5637]: info: CorePlayQueue::getTrack 0 Apr 08 15:35:16 primov2 volumio[5637]: info: CoreCommandRouter::volumioGetQueue Apr 08 15:35:16 primov2 volumio[5637]: info: CoreStateMachine::getQueue Apr 08 15:35:16 primov2 volumio[5637]: info: CorePlayQueue::getQueue Apr 08 15:35:18 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 08 15:35:18 primov2 volumio[5637]: info: CURURI: music-library/USB/Volume Apr 08 15:35:29 primov2 sudo[8235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/USB/Volume Apr 08 15:35:29 primov2 sudo[8235]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 15:35:29 primov2 systemd[1]: media-Volume.mount: Succeeded. Apr 08 15:35:29 primov2 ntfs-3g[5948]: Unmounting /dev/sdb2 (Volume) Apr 08 15:35:29 primov2 sudo[8235]: pam_unix(sudo:session): session closed for user root Apr 08 15:35:29 primov2 volumio[5637]: info: CoreCommandRouter::executeOnPlugin: mpd , updateMpdDB Apr 08 15:35:29 primov2 volumio[5637]: info: Update mpd DB Apr 08 15:35:29 primov2 volumio[5637]: verbose: ControllerMpd::sendMpdCommand update Apr 08 15:35:29 primov2 volumio[5637]: MPD error: Connection refused Apr 08 15:35:29 primov2 volumio[5637]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 15:35:29 primov2 volumio[5637]: Error: Command failed: /usr/bin/mpc update Apr 08 15:35:29 primov2 volumio[5637]: MPD error: Connection refused Apr 08 15:35:29 primov2 volumio[5637]: at checkExecSyncError (child_process.js:790:11) Apr 08 15:35:29 primov2 volumio[5637]: at execSync (child_process.js:863:15) Apr 08 15:35:29 primov2 volumio[5637]: at /volumio/app/index.js:1905:7 Apr 08 15:35:29 primov2 volumio[5637]: at ChildProcess.exithandler (child_process.js:374:7) Apr 08 15:35:29 primov2 volumio[5637]: at ChildProcess.emit (events.js:400:28) Apr 08 15:35:29 primov2 volumio[5637]: at maybeClose (internal/child_process.js:1088:16) Apr 08 15:35:29 primov2 volumio[5637]: at Process.ChildProcess._handle.onexit (internal/child_process.js:296:5) { Apr 08 15:35:29 primov2 volumio[5637]: status: 1, Apr 08 15:35:29 primov2 volumio[5637]: signal: null, Apr 08 15:35:29 primov2 volumio[5637]: output: [ null, '', 'MPD error: Connection refused\n' ], Apr 08 15:35:29 primov2 volumio[5637]: pid: 8241, Apr 08 15:35:29 primov2 volumio[5637]: stdout: '', Apr 08 15:35:29 primov2 volumio[5637]: stderr: 'MPD error: Connection refused\n' Apr 08 15:35:29 primov2 volumio[5637]: } Apr 08 15:35:29 primov2 volumio[5637]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 15:35:30 primov2 sudo[8252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-08 15:34 Apr 08 15:35:30 primov2 sudo[8252]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 12:01:04 PM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="728189e850980f21ef569472434ec23d"