-- Logs begin at Mon 2025-04-28 23:16:46 MSK, end at Tue 2025-04-29 21:08:45 MSK. -- Apr 29 21:07:07 smsl-n100 volumio[981]: info: CoreStateMachine::pushState Apr 29 21:07:07 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:07:07 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 21:07:07 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 21:07:07 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 21:07:07 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 21:07:07 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 29 21:07:07 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 21:07:07 smsl-n100 volumio[981]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 29 21:07:07 smsl-n100 volumio[981]: info: CoreStateMachine::pushState Apr 29 21:07:07 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:07:07 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 21:07:07 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 21:07:07 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 21:07:07 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 21:07:07 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 29 21:07:07 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 21:07:07 smsl-n100 volumio[981]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 29 21:07:17 smsl-n100 ntpd[967]: 213.33.141.134 local addr 192.168.10.15 -> Apr 29 21:08:07 smsl-n100 volumio[981]: info: CoreStateMachine::pushState Apr 29 21:08:07 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:07 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 21:08:07 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 21:08:07 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 21:08:07 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 21:08:07 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 29 21:08:07 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 21:08:07 smsl-n100 volumio[981]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 29 21:08:07 smsl-n100 volumio[981]: info: CoreStateMachine::pushState Apr 29 21:08:07 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:07 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 21:08:07 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 21:08:07 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 21:08:07 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 21:08:07 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 29 21:08:07 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 21:08:07 smsl-n100 volumio[981]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 29 21:08:26 smsl-n100 ntpd[967]: 45.141.102.99 local addr 192.168.10.15 -> Apr 29 21:08:30 smsl-n100 kernel: usb usb2-port1: disabled by hub (EMI?), re-enabling... Apr 29 21:08:30 smsl-n100 kernel: usb 2-1: USB disconnect, device number 2 Apr 29 21:08:30 smsl-n100 kernel: usb 2-1.4: cannot submit urb (err = -19) Apr 29 21:08:30 smsl-n100 kernel: usb 2-1.1: USB disconnect, device number 5 Apr 29 21:08:30 smsl-n100 volumio[981]: info: Partition removed: {"syspath":"/sys/devices/platform/ff5c0000.usb/usb2/2-1/2-1.1/2-1.1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/ed926ea6-02 /dev/disk/by-id/usb-ST2000VM_003-1CT164_M6116018VE15-0:0-part2 /dev/disk/by-uuid/ef468d0b-cae4-4be4-b45e-2ec63867b5aa /dev/disk/by-path/platform-ff5c0000.usb-usb-0:1.1:1.0-scsi-0:0:0:0-part2","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/ff5c0000.usb/usb2/2-1/2-1.1/2-1.1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","ID_BUS":"usb","ID_FS_TYPE":"swap","ID_FS_USAGE":"other","ID_FS_UUID":"ef468d0b-cae4-4be4-b45e-2ec63867b5aa","ID_FS_UUID_ENC":"ef468d0b-cae4-4be4-b45e-2ec63867b5aa","ID_FS_VERSION":"1","ID_INSTANCE":"0:0","ID_MODEL":"003-1CT164","ID_MODEL_ENC":"003-1CT164\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"6116","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"3905690670","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"273105","ID_PART_ENTRY_TYPE":"0x82","ID_PART_ENTRY_UUID":"ed926ea6-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"ed926ea6","ID_PATH":"platform-ff5c0000.usb-usb-0:1.1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-ff5c0000_usb-usb-0_1_1_1_0-scsi-0_0_0_0","ID_REVISION":"0150","ID_SERIAL":"ST2000VM_003-1CT164_M6116018VE15-0:0","ID_SERIAL_SHORT":"M6116018VE15","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"ST2000VM","ID_VENDOR_ENC":"ST2000VM","ID_VENDOR_ID":"14cd","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"2328","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4975503"} Apr 29 21:08:30 smsl-n100 kernel: usb 2-1.4: cannot submit urb 0, error -19: no device Apr 29 21:08:30 smsl-n100 sudo[4473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda2 Apr 29 21:08:30 smsl-n100 sudo[4473]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:30 smsl-n100 sudo[4473]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:30 smsl-n100 kernel: usb 2-1.3: USB disconnect, device number 3 Apr 29 21:08:30 smsl-n100 volumio[981]: umount: /dev/sda2: no mount point specified. Apr 29 21:08:30 smsl-n100 volumio[981]: error: Failed to umount ef468d0b-cae4-4be4-b45e-2ec63867b5aa: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sda2" Apr 29 21:08:30 smsl-n100 volumio[981]: umount: /dev/sda2: no mount point specified. Apr 29 21:08:30 smsl-n100 avahi-daemon[556]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 29 21:08:30 smsl-n100 avahi-daemon[556]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.10.15. Apr 29 21:08:30 smsl-n100 volumio[981]: info: Partition removed: {"syspath":"/sys/devices/platform/ff5c0000.usb/usb2/2-1/2-1.1/2-1.1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda3","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-ST2000VM_003-1CT164_M6116018VE15-0:0-part3 /dev/disk/by-uuid/9538471d-a950-49b1-bed8-0a1aaa401ba5 /dev/disk/by-partuuid/ed926ea6-03 /dev/disk/by-path/platform-ff5c0000.usb-usb-0:1.1:1.0-scsi-0:0:0:0-part3","DEVNAME":"/dev/sda3","DEVPATH":"/devices/platform/ff5c0000.usb/usb2/2-1/2-1.1/2-1.1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda3","DEVTYPE":"partition","ID_BUS":"usb","ID_FS_TYPE":"ext2","ID_FS_USAGE":"filesystem","ID_FS_UUID":"9538471d-a950-49b1-bed8-0a1aaa401ba5","ID_FS_UUID_ENC":"9538471d-a950-49b1-bed8-0a1aaa401ba5","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"003-1CT164","ID_MODEL_ENC":"003-1CT164\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"6116","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"3","ID_PART_ENTRY_OFFSET":"3905963775","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"1060290","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"ed926ea6-03","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"ed926ea6","ID_PATH":"platform-ff5c0000.usb-usb-0:1.1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-ff5c0000_usb-usb-0_1_1_1_0-scsi-0_0_0_0","ID_REVISION":"0150","ID_SERIAL":"ST2000VM_003-1CT164_M6116018VE15-0:0","ID_SERIAL_SHORT":"M6116018VE15","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"ST2000VM","ID_VENDOR_ENC":"ST2000VM","ID_VENDOR_ID":"14cd","MAJOR":"8","MINOR":"3","PARTN":"3","SEQNUM":"2327","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4975478"} Apr 29 21:08:30 smsl-n100 wpa_supplicant[861]: wlan0: CTRL-EVENT-DISCONNECTED bssid=b8:80:35:c6:c8:ce reason=3 locally_generated=1 Apr 29 21:08:30 smsl-n100 dhcpcd[968]: wlan0: carrier lost Apr 29 21:08:30 smsl-n100 avahi-daemon[556]: Withdrawing address record for 192.168.10.15 on wlan0. Apr 29 21:08:30 smsl-n100 kernel: usb 2-1.4: USB disconnect, device number 4 Apr 29 21:08:30 smsl-n100 systemd[1]: Starting Load/Save RF Kill Switch Status... Apr 29 21:08:30 smsl-n100 systemd[1]: Stopping ifup for wlan0... Apr 29 21:08:30 smsl-n100 sudo[4490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda3 Apr 29 21:08:30 smsl-n100 sudo[4490]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:30 smsl-n100 systemd[1]: Started Load/Save RF Kill Switch Status. Apr 29 21:08:30 smsl-n100 dhcpcd[968]: wlan0: deleting route to 192.168.10.0/24 Apr 29 21:08:30 smsl-n100 dhcpcd[968]: wlan0: deleting default route via 192.168.10.1 Apr 29 21:08:30 smsl-n100 sudo[4490]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:30 smsl-n100 systemd[1]: media-9538471d\x2da950\x2d49b1\x2dbed8\x2d0a1aaa401ba5.mount: Succeeded. Apr 29 21:08:30 smsl-n100 wpa_supplicant[861]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Apr 29 21:08:30 smsl-n100 wpa_supplicant[861]: nl80211: Failed to set IPv4 unicast in multicast filter Apr 29 21:08:30 smsl-n100 kernel: usb 2-1: new high-speed USB device number 6 using ehci-platform Apr 29 21:08:30 smsl-n100 dhcpcd[968]: wlan0: removing interface Apr 29 21:08:30 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 21:08:30 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 21:08:30 smsl-n100 volumio[981]: info: Apr 29 21:08:30 smsl-n100 volumio[981]: ---------------------------- MPD announces state update: player Apr 29 21:08:30 smsl-n100 volumio[981]: info: ControllerMpd::getState Apr 29 21:08:30 smsl-n100 volumio[981]: verbose: ControllerMpd::sendMpdCommand status Apr 29 21:08:30 smsl-n100 volumio[981]: info: Apr 29 21:08:30 smsl-n100 volumio[981]: ---------------------------- MPD announces state update: player Apr 29 21:08:30 smsl-n100 volumio[981]: info: ControllerMpd::getState Apr 29 21:08:30 smsl-n100 volumio[981]: verbose: ControllerMpd::sendMpdCommand status Apr 29 21:08:30 smsl-n100 volumio[981]: info: Partition removed: {"syspath":"/sys/devices/platform/ff5c0000.usb/usb2/2-1/2-1.1/2-1.1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-ST2000VM_003-1CT164_M6116018VE15-0:0-part1 /dev/disk/by-path/platform-ff5c0000.usb-usb-0:1.1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-partuuid/ed926ea6-01 /dev/disk/by-uuid/180CDE6D0CDE4582","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/ff5c0000.usb/usb2/2-1/2-1.1/2-1.1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","ID_BUS":"usb","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"180CDE6D0CDE4582","ID_FS_UUID_ENC":"180CDE6D0CDE4582","ID_INSTANCE":"0:0","ID_MODEL":"003-1CT164","ID_MODEL_ENC":"003-1CT164\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"6116","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"16065","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"3905674605","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"ed926ea6-01","ID_PART_TABLE_TYPE":"atari","ID_PART_TABLE_UUID":"ed926ea6","ID_PATH":"platform-ff5c0000.usb-usb-0:1.1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-ff5c0000_usb-usb-0_1_1_1_0-scsi-0_0_0_0","ID_REVISION":"0150","ID_SERIAL":"ST2000VM_003-1CT164_M6116018VE15-0:0","ID_SERIAL_SHORT":"M6116018VE15","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"ST2000VM","ID_VENDOR_ENC":"ST2000VM","ID_VENDOR_ID":"14cd","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2329","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4975545"} Apr 29 21:08:30 smsl-n100 kernel: usb 2-1: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.11 Apr 29 21:08:30 smsl-n100 kernel: usb 2-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Apr 29 21:08:30 smsl-n100 kernel: usb 2-1: Product: USB 2.0 Hub Apr 29 21:08:30 smsl-n100 kernel: hub 2-1:1.0: USB hub found Apr 29 21:08:30 smsl-n100 kernel: hub 2-1:1.0: 4 ports detected Apr 29 21:08:30 smsl-n100 systemd[1]: ifup@wlan0.service: Succeeded. Apr 29 21:08:30 smsl-n100 systemd[1]: Stopped ifup for wlan0. Apr 29 21:08:31 smsl-n100 sudo[4556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Apr 29 21:08:31 smsl-n100 sudo[4556]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:31 smsl-n100 ntfs-3g[1166]: Unmounting /dev/sda1 () Apr 29 21:08:31 smsl-n100 ntfs-3g[1166]: Failed to sync device /dev/sda1: Input/output error Apr 29 21:08:31 smsl-n100 ntfs-3g[1166]: Failed to close volume /dev/sda1: Input/output error Apr 29 21:08:31 smsl-n100 sudo[4556]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:31 smsl-n100 volumio[981]: umount: /media/180CDE6D0CDE4582: target is busy. Apr 29 21:08:31 smsl-n100 volumio[981]: error: Failed to umount 180CDE6D0CDE4582: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sda1" Apr 29 21:08:31 smsl-n100 volumio[981]: umount: /media/180CDE6D0CDE4582: target is busy. Apr 29 21:08:31 smsl-n100 volumio[981]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 29 21:08:31 smsl-n100 volumio[981]: info: sendMpdCommand status took 158 milliseconds Apr 29 21:08:31 smsl-n100 volumio[981]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 29 21:08:31 smsl-n100 volumio[981]: info: sendMpdCommand status took 154 milliseconds Apr 29 21:08:31 smsl-n100 volumio[981]: verbose: ControllerMpd::parseState Apr 29 21:08:31 smsl-n100 volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 21:08:31 smsl-n100 volumio[981]: verbose: ControllerMpd::parseState Apr 29 21:08:31 smsl-n100 volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 21:08:31 smsl-n100 volumio[981]: info: sendMpdCommand clearerror took 28 milliseconds Apr 29 21:08:31 smsl-n100 volumio[981]: info: sendMpdCommand clearerror took 26 milliseconds Apr 29 21:08:31 smsl-n100 volumio[981]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 29 21:08:31 smsl-n100 volumio[981]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 29 21:08:31 smsl-n100 volumio[981]: verbose: ControllerMpd::parseTrackInfo Apr 29 21:08:31 smsl-n100 volumio[981]: verbose: ControllerMpd::parseTrackInfo Apr 29 21:08:31 smsl-n100 volumio[981]: info: ControllerMpd::pushState Apr 29 21:08:31 smsl-n100 volumio[981]: info: CoreCommandRouter::servicePushState Apr 29 21:08:31 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:31 smsl-n100 volumio[981]: verbose: STATE SERVICE {"status":"pause","position":2,"seek":184594,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1117 Kbps","isStreaming":false,"title":"The Wake","artist":"a-ha","album":"Cast In Steel","uri":"USB/180CDE6D0CDE4582/music/a-ha - Cast In Steel (Deluxe Edition) (2015) (flac)/Disc 1/03 The Wake.flac","trackType":"flac"} Apr 29 21:08:31 smsl-n100 volumio[981]: verbose: CURRENT POSITION 0 Apr 29 21:08:31 smsl-n100 volumio[981]: info: CoreStateMachine::syncState stateService pause Apr 29 21:08:31 smsl-n100 volumio[981]: info: CoreStateMachine::syncState currentStatus play Apr 29 21:08:31 smsl-n100 volumio[981]: info: CoreStateMachine::pushState Apr 29 21:08:31 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:31 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 21:08:31 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 21:08:31 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 21:08:31 smsl-n100 volumio[981]: info: ControllerMpd::pushState Apr 29 21:08:31 smsl-n100 volumio[981]: info: CoreCommandRouter::servicePushState Apr 29 21:08:31 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:31 smsl-n100 volumio[981]: verbose: STATE SERVICE {"status":"pause","position":2,"seek":184594,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1117 Kbps","isStreaming":false,"title":"The Wake","artist":"a-ha","album":"Cast In Steel","uri":"USB/180CDE6D0CDE4582/music/a-ha - Cast In Steel (Deluxe Edition) (2015) (flac)/Disc 1/03 The Wake.flac","trackType":"flac"} Apr 29 21:08:31 smsl-n100 volumio[981]: verbose: CURRENT POSITION 0 Apr 29 21:08:31 smsl-n100 volumio[981]: info: CoreStateMachine::syncState stateService pause Apr 29 21:08:31 smsl-n100 volumio[981]: info: CoreStateMachine::syncState currentStatus play Apr 29 21:08:31 smsl-n100 volumio[981]: info: CoreStateMachine::pushState Apr 29 21:08:31 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:31 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 21:08:31 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 21:08:31 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 21:08:31 smsl-n100 volumio[981]: info: ------------------------------ 286ms Apr 29 21:08:31 smsl-n100 volumio[981]: info: ------------------------------ 280ms Apr 29 21:08:31 smsl-n100 volumio[981]: info: Discovery: A device disappeared from network Apr 29 21:08:31 smsl-n100 volumio[981]: info: Apr 29 21:08:31 smsl-n100 volumio[981]: ---------------------------- USB Audio Device Detached Apr 29 21:08:31 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 29 21:08:31 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 29 21:08:31 smsl-n100 kernel: usb 2-1.3: new high-speed USB device number 7 using ehci-platform Apr 29 21:08:31 smsl-n100 volumio[981]: xcb_connection_has_error() returned true Apr 29 21:08:31 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 29 21:08:31 smsl-n100 volumio[981]: info: Stopping RAAT Plugin Apr 29 21:08:31 smsl-n100 sudo[4564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Apr 29 21:08:31 smsl-n100 sudo[4564]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:31 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Apr 29 21:08:31 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 29 21:08:31 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Apr 29 21:08:31 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Apr 29 21:08:31 smsl-n100 kernel: usb 2-1.3: New USB device found, idVendor=0bda, idProduct=c811, bcdDevice= 2.00 Apr 29 21:08:31 smsl-n100 kernel: usb 2-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 29 21:08:31 smsl-n100 kernel: usb 2-1.3: Product: 802.11ac NIC Apr 29 21:08:31 smsl-n100 kernel: usb 2-1.3: Manufacturer: Realtek Apr 29 21:08:31 smsl-n100 kernel: usb 2-1.3: SerialNumber: 123456 Apr 29 21:08:31 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 21:08:31 smsl-n100 sudo[4564]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:31 smsl-n100 volumio[981]: info: Raat Daemon stopped successfully Apr 29 21:08:31 smsl-n100 ntpd[967]: Deleting interface #3 wlan0, 192.168.10.15#123, interface stats: received=313, sent=323, dropped=1, active_time=730 secs Apr 29 21:08:31 smsl-n100 ntpd[967]: 195.133.5.5 local addr 192.168.10.15 -> Apr 29 21:08:31 smsl-n100 ntpd[967]: 80.66.81.211 local addr 192.168.10.15 -> Apr 29 21:08:31 smsl-n100 ntpd[967]: 89.179.240.219 local addr 192.168.10.15 -> Apr 29 21:08:31 smsl-n100 ntpd[967]: 151.0.2.53 local addr 192.168.10.15 -> Apr 29 21:08:31 smsl-n100 ntpd[967]: 45.90.217.6 local addr 192.168.10.15 -> Apr 29 21:08:31 smsl-n100 ntpd[967]: 162.159.200.123 local addr 192.168.10.15 -> Apr 29 21:08:31 smsl-n100 ntpd[967]: 92.255.126.18 local addr 192.168.10.15 -> Apr 29 21:08:31 smsl-n100 ntpd[967]: 92.241.18.100 local addr 192.168.10.15 -> Apr 29 21:08:31 smsl-n100 ntpd[967]: 89.109.251.25 local addr 192.168.10.15 -> Apr 29 21:08:31 smsl-n100 ntpd[967]: 95.31.7.160 local addr 192.168.10.15 -> Apr 29 21:08:31 smsl-n100 ntpd[967]: 94.247.111.10 local addr 192.168.10.15 -> Apr 29 21:08:31 smsl-n100 ntpd[967]: 89.248.192.178 local addr 192.168.10.15 -> Apr 29 21:08:31 smsl-n100 ntpd[967]: 185.211.244.47 local addr 192.168.10.15 -> Apr 29 21:08:31 smsl-n100 ntpd[967]: 217.170.87.229 local addr 192.168.10.15 -> Apr 29 21:08:32 smsl-n100 kernel: usb 2-1.4: new high-speed USB device number 8 using ehci-platform Apr 29 21:08:32 smsl-n100 kernel: usb 2-1.4: New USB device found, idVendor=20b1, idProduct=30c5, bcdDevice= 1.10 Apr 29 21:08:32 smsl-n100 kernel: usb 2-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Apr 29 21:08:32 smsl-n100 kernel: usb 2-1.4: Product: DS07 Apr 29 21:08:32 smsl-n100 kernel: usb 2-1.4: Manufacturer: FX-AUDIO Apr 29 21:08:32 smsl-n100 kernel: usb 2-1.4: 1:3 : unsupported format bits 0x100000000 Apr 29 21:08:32 smsl-n100 kernel: hid-generic 0003:20B1:30C5.0002: hiddev96,hidraw0: USB HID v1.10 Device [FX-AUDIO DS07] on usb-ff5c0000.usb-1.4/input3 Apr 29 21:08:32 smsl-n100 kernel: usb 2-1.1: new high-speed USB device number 9 using ehci-platform Apr 29 21:08:32 smsl-n100 kernel: usb 2-1.1: New USB device found, idVendor=14cd, idProduct=6116, bcdDevice= 1.50 Apr 29 21:08:32 smsl-n100 kernel: usb 2-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Apr 29 21:08:32 smsl-n100 kernel: usb 2-1.1: Product: USB 2.0 SATA BRIDGE Apr 29 21:08:32 smsl-n100 kernel: usb 2-1.1: Manufacturer: Super Top Apr 29 21:08:32 smsl-n100 kernel: usb 2-1.1: SerialNumber: M6116018VE15 Apr 29 21:08:32 smsl-n100 kernel: usb-storage 2-1.1:1.0: USB Mass Storage device detected Apr 29 21:08:32 smsl-n100 kernel: scsi host1: usb-storage 2-1.1:1.0 Apr 29 21:08:32 smsl-n100 systemd-udevd[4469]: Using default interface naming scheme 'v240'. Apr 29 21:08:33 smsl-n100 systemd-udevd[4469]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 29 21:08:33 smsl-n100 nmbd[753]: [2025/04/29 21:08:33.023324, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Apr 29 21:08:33 smsl-n100 nmbd[753]: reload_interfaces: No subnets to listen to. Waiting.. Apr 29 21:08:33 smsl-n100 systemd-udevd[4574]: Process '/sbin/crda' failed with exit code 255. Apr 29 21:08:33 smsl-n100 dhcpcd[968]: wlan0: waiting for carrier Apr 29 21:08:33 smsl-n100 volumio[981]: info: Apr 29 21:08:33 smsl-n100 volumio[981]: ---------------------------- USB Audio Device Attached Apr 29 21:08:33 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 29 21:08:33 smsl-n100 systemd[1]: Found device 802.11ac_NIC. Apr 29 21:08:33 smsl-n100 systemd[1]: Started ifup for wlan0. Apr 29 21:08:33 smsl-n100 volumio[981]: xcb_connection_has_error() returned true Apr 29 21:08:33 smsl-n100 volumio[981]: info: CoreCommandRouter::Close All Modals sent Apr 29 21:08:33 smsl-n100 volumio[981]: info: Preparing to save Alsa Options, stopping services first Apr 29 21:08:33 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioGetState Apr 29 21:08:33 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:33 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioPause Apr 29 21:08:33 smsl-n100 volumio[981]: info: CoreStateMachine::pause Apr 29 21:08:33 smsl-n100 volumio[981]: info: CoreStateMachine::stPlaybackTimer Apr 29 21:08:33 smsl-n100 volumio[981]: info: CoreStateMachine::servicePause Apr 29 21:08:33 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:33 smsl-n100 volumio[981]: info: Error: no service or no trackblock to pause Apr 29 21:08:33 smsl-n100 volumio[981]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"DS07","alsacard":"DS07"},"i2s":false} Apr 29 21:08:33 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 29 21:08:33 smsl-n100 volumio[981]: xcb_connection_has_error() returned true Apr 29 21:08:33 smsl-n100 volumio[981]: xcb_connection_has_error() returned true Apr 29 21:08:33 smsl-n100 sh[4602]: wlan0=wlan0 Apr 29 21:08:33 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 29 21:08:33 smsl-n100 volumio[981]: xcb_connection_has_error() returned true Apr 29 21:08:33 smsl-n100 volumio[981]: info: Device 5 does not have any Mixer Control Available Apr 29 21:08:33 smsl-n100 volumio[981]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Apr 29 21:08:33 smsl-n100 vtcs[1542]: [2025-04-29 21:08:33.697] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Apr 29 21:08:33 smsl-n100 vtcs[1542]: [2025-04-29 21:08:33.698] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Apr 29 21:08:33 smsl-n100 sudo[4653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 29 21:08:33 smsl-n100 sudo[4653]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:33 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Apr 29 21:08:33 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 29 21:08:33 smsl-n100 systemd[1]: vtcs.service: Succeeded. Apr 29 21:08:33 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Apr 29 21:08:33 smsl-n100 volumio[981]: xcb_connection_has_error() returned true Apr 29 21:08:33 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 29 21:08:33 smsl-n100 volumio[981]: info: Updating Volume Controller Parameters: Device: 5 Name: DS07 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 29 21:08:33 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 29 21:08:33 smsl-n100 sudo[4653]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:33 smsl-n100 volumio[981]: info: Disabling external Volume Control Apr 29 21:08:33 smsl-n100 volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 21:08:33 smsl-n100 volumio[981]: info: CoreStateMachine::pushState Apr 29 21:08:33 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:33 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 21:08:33 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 21:08:33 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 21:08:33 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 21:08:33 smsl-n100 volumio[981]: info: Preparing to generate the ALSA configuration file Apr 29 21:08:33 smsl-n100 kernel: scsi 1:0:0:0: Direct-Access ST2000VM 003-1CT164 PQ: 0 ANSI: 0 Apr 29 21:08:33 smsl-n100 kernel: sd 1:0:0:0: [sdb] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB) Apr 29 21:08:33 smsl-n100 kernel: sd 1:0:0:0: [sdb] Write Protect is off Apr 29 21:08:33 smsl-n100 kernel: sd 1:0:0:0: [sdb] Mode Sense: 03 00 00 00 Apr 29 21:08:33 smsl-n100 kernel: sd 1:0:0:0: [sdb] No Caching mode page found Apr 29 21:08:33 smsl-n100 kernel: sd 1:0:0:0: [sdb] Assuming drive cache: write through Apr 29 21:08:34 smsl-n100 kernel: sdb: sdb1 sdb2 sdb3 Apr 29 21:08:34 smsl-n100 kernel: sd 1:0:0:0: [sdb] Attached SCSI disk Apr 29 21:08:34 smsl-n100 volumio[981]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 29 21:08:34 smsl-n100 volumio[981]: info: Reading ALSA contributions from plugins. Apr 29 21:08:34 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 21:08:34 smsl-n100 sudo[4660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 29 21:08:34 smsl-n100 sudo[4660]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:34 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 21:08:34 smsl-n100 sudo[4660]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:34 smsl-n100 volumio[981]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 29 21:08:34 smsl-n100 volumio[981]: info: Updating RAAT Signal Path Apr 29 21:08:34 smsl-n100 volumio[981]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: Not Reporting Auto name since its the default one Apr 29 21:08:34 smsl-n100 volumio[981]: xcb_connection_has_error() returned true Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 29 21:08:34 smsl-n100 volumio[981]: xcb_connection_has_error() returned true Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 21:08:34 smsl-n100 volumio[981]: info: Not Reporting Auto name since its the default one Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 29 21:08:34 smsl-n100 volumio[981]: xcb_connection_has_error() returned true Apr 29 21:08:34 smsl-n100 volumio[981]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/9538471d-a950-49b1-bed8-0a1aaa401ba5 Apr 29 21:08:34 smsl-n100 sudo[4678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 29 21:08:34 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 29 21:08:34 smsl-n100 sudo[4678]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:34 smsl-n100 volumio[981]: xcb_connection_has_error() returned true Apr 29 21:08:35 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:35 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:35 smsl-n100 volumio[981]: info: Asound.conf file unchanged, so no further update is needed Apr 29 21:08:35 smsl-n100 volumio[981]: info: Output device has changed, restarting MPD Apr 29 21:08:35 smsl-n100 sudo[4683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 29 21:08:35 smsl-n100 sudo[4683]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:35 smsl-n100 sudo[4686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 29 21:08:35 smsl-n100 sudo[4686]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:35 smsl-n100 volumio[981]: info: Output device has changed, restarting Shairport Sync Apr 29 21:08:35 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:35 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 21:08:35 smsl-n100 sudo[4686]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:35 smsl-n100 sudo[4689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 29 21:08:35 smsl-n100 sudo[4689]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:35 smsl-n100 sudo[4678]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:35 smsl-n100 sudo[4683]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:35 smsl-n100 sudo[4694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 29 21:08:35 smsl-n100 sudo[4694]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:35 smsl-n100 sudo[4695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 29 21:08:35 smsl-n100 sudo[4695]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:35 smsl-n100 systemd[1]: Stopping Music Player Daemon... Apr 29 21:08:35 smsl-n100 volumio[981]: xcb_connection_has_error() returned true Apr 29 21:08:35 smsl-n100 volumio[981]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 29 21:08:35 smsl-n100 volumio[981]: xcb_connection_has_error() returned true Apr 29 21:08:35 smsl-n100 volumio[981]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 29 21:08:35 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:35 smsl-n100 systemd[1]: Started RAAT DAEMON. Apr 29 21:08:35 smsl-n100 sudo[4694]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:35 smsl-n100 sudo[4695]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:35 smsl-n100 systemd[1]: mpd.service: Succeeded. Apr 29 21:08:35 smsl-n100 systemd[1]: Stopped Music Player Daemon. Apr 29 21:08:35 smsl-n100 volumio[981]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 29 21:08:35 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:35 smsl-n100 systemd[1]: Starting Music Player Daemon... Apr 29 21:08:35 smsl-n100 sudo[4707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 29 21:08:35 smsl-n100 sudo[4707]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:35 smsl-n100 sudo[4707]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:35 smsl-n100 sudo[4715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 29 21:08:35 smsl-n100 sudo[4715]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:35 smsl-n100 sudo[4705]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 29 21:08:35 smsl-n100 sudo[4705]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:35 smsl-n100 sudo[4705]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:35 smsl-n100 volumio[981]: info: Mounting Device 180CDE6D0CDE4582 Apr 29 21:08:35 smsl-n100 sudo[4715]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:35 smsl-n100 volumio[981]: /bin/mkdir: cannot create directory ‘/mnt/USB/180CDE6D0CDE4582’: File exists Apr 29 21:08:35 smsl-n100 volumio[981]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/180CDE6D0CDE4582" Apr 29 21:08:35 smsl-n100 volumio[981]: /bin/mkdir: cannot create directory ‘/mnt/USB/180CDE6D0CDE4582’: File exists Apr 29 21:08:35 smsl-n100 sudo[4727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/180CDE6D0CDE4582 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 29 21:08:35 smsl-n100 sudo[4727]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:37 smsl-n100 wpa_supplicant[861]: wlan0: Trying to associate with b8:80:35:c6:c8:ce (SSID='SKYNET' freq=2412 MHz) Apr 29 21:08:38 smsl-n100 wpa_supplicant[861]: wlan0: Associated with b8:80:35:c6:c8:ce Apr 29 21:08:38 smsl-n100 wpa_supplicant[861]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 29 21:08:38 smsl-n100 wpa_supplicant[861]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=RU Apr 29 21:08:38 smsl-n100 systemd[1]: systemd-rfkill.service: Succeeded. Apr 29 21:08:38 smsl-n100 wpa_supplicant[861]: wlan0: WPA: Key negotiation completed with b8:80:35:c6:c8:ce [PTK=CCMP GTK=CCMP] Apr 29 21:08:38 smsl-n100 wpa_supplicant[861]: wlan0: CTRL-EVENT-CONNECTED - Connection to b8:80:35:c6:c8:ce completed [id=0 id_str=] Apr 29 21:08:38 smsl-n100 dhcpcd[968]: wlan0: carrier acquired Apr 29 21:08:38 smsl-n100 dhcpcd[968]: wlan0: IAID 2b:ee:c8:de Apr 29 21:08:38 smsl-n100 dhcpcd[968]: wlan0: adding address fe80::2af5:2bff:feee:c8de Apr 29 21:08:38 smsl-n100 dhcpcd[968]: ipv6_addaddr1: Permission denied Apr 29 21:08:38 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 29 21:08:38 smsl-n100 systemd[1]: mpd.service: Failed with result 'protocol'. Apr 29 21:08:38 smsl-n100 systemd[1]: Failed to start Music Player Daemon. Apr 29 21:08:38 smsl-n100 sudo[4689]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:38 smsl-n100 systemd[1]: Starting Music Player Daemon... Apr 29 21:08:38 smsl-n100 dhcpcd[968]: wlan0: soliciting an IPv6 router Apr 29 21:08:38 smsl-n100 sudo[4753]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 29 21:08:38 smsl-n100 sudo[4753]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:38 smsl-n100 sudo[4753]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:38 smsl-n100 sudo[4727]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:38 smsl-n100 volumio[981]: ntfs-3g-mount: failed to access mountpoint /media/180CDE6D0CDE4582: Transport endpoint is not connected Apr 29 21:08:38 smsl-n100 volumio[981]: error: Failed to mount 180CDE6D0CDE4582: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/180CDE6D0CDE4582" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 29 21:08:38 smsl-n100 volumio[981]: ntfs-3g-mount: failed to access mountpoint /media/180CDE6D0CDE4582: Transport endpoint is not connected Apr 29 21:08:38 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 21:08:38 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 21:08:38 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 21:08:38 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 21:08:38 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 21:08:38 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 21:08:38 smsl-n100 dhcpcd[968]: wlan0: rebinding lease of 192.168.10.15 Apr 29 21:08:38 smsl-n100 dhcpcd[968]: wlan0: probing address 192.168.10.15/24 Apr 29 21:08:38 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:38 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:38 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:38 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:38 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:38 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:38 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:38 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:38 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 21:08:38 smsl-n100 volumio[981]: info: Not Reporting Auto name since its the default one Apr 29 21:08:38 smsl-n100 volumio[981]: info: RAAT: Requesting Headphone Status Apr 29 21:08:38 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 29 21:08:38 smsl-n100 volumio[981]: info: RAAT: Requesting Headphone Status Apr 29 21:08:38 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 29 21:08:38 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 29 21:08:38 smsl-n100 volumio[981]: info: CoreStateMachine::getcurrentVolume Apr 29 21:08:38 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioRetrievevolume Apr 29 21:08:38 smsl-n100 volumio[981]: info: CoreStateMachine::pushState Apr 29 21:08:38 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:38 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 21:08:38 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 21:08:38 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 21:08:38 smsl-n100 volumio[981]: info: Mounting Device ef468d0b-cae4-4be4-b45e-2ec63867b5aa Apr 29 21:08:39 smsl-n100 sudo[4764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/ef468d0b-cae4-4be4-b45e-2ec63867b5aa -o noatime Apr 29 21:08:39 smsl-n100 sudo[4764]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:39 smsl-n100 sudo[4764]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:39 smsl-n100 volumio[981]: mount: /media/ef468d0b-cae4-4be4-b45e-2ec63867b5aa: unknown filesystem type 'swap'. Apr 29 21:08:39 smsl-n100 volumio[981]: error: Failed to mount ef468d0b-cae4-4be4-b45e-2ec63867b5aa: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb2" "/mnt/USB/ef468d0b-cae4-4be4-b45e-2ec63867b5aa" -o noatime Apr 29 21:08:39 smsl-n100 volumio[981]: mount: /media/ef468d0b-cae4-4be4-b45e-2ec63867b5aa: unknown filesystem type 'swap'. Apr 29 21:08:39 smsl-n100 volumio[981]: info: Reporting MCU Network Status: 0 Apr 29 21:08:39 smsl-n100 volumio[981]: info: Volumio Network Manager: Network status updated: 0 Apr 29 21:08:39 smsl-n100 volumio[981]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 29 21:08:39 smsl-n100 volumio[981]: info: Updating RAAT Signal Path Apr 29 21:08:39 smsl-n100 volumio[981]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 29 21:08:39 smsl-n100 volumio[981]: info: TidalConnect service stoped! Apr 29 21:08:39 smsl-n100 volumio[981]: info: TidalConnect service stoped! Apr 29 21:08:39 smsl-n100 volumio[981]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Apr 29 21:08:39 smsl-n100 volumio[981]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Apr 29 21:08:39 smsl-n100 volumio[981]: See "systemctl status mpd.service" and "journalctl -xe" for details. Apr 29 21:08:39 smsl-n100 volumio[981]: info: MPD Permissions set Apr 29 21:08:39 smsl-n100 volumio[981]: info: TidalConnect service stoped! Apr 29 21:08:39 smsl-n100 volumio[981]: info: TidalConnect service stoped! Apr 29 21:08:39 smsl-n100 volumio[981]: info: Raat Daemon started successfully Apr 29 21:08:39 smsl-n100 volumio[981]: info: Raat Daemon started successfully Apr 29 21:08:39 smsl-n100 volumio[981]: info: CoreStateMachine::pushState Apr 29 21:08:39 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:39 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 21:08:39 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 21:08:39 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 21:08:39 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 21:08:39 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 21:08:39 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 29 21:08:39 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 21:08:39 smsl-n100 volumio[981]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 29 21:08:39 smsl-n100 volumio[981]: info: CoreStateMachine::pushState Apr 29 21:08:39 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:39 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 21:08:39 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 21:08:39 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 21:08:39 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 21:08:39 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 29 21:08:39 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 21:08:39 smsl-n100 volumio[981]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 29 21:08:39 smsl-n100 volumio[981]: info: Mounting Device 9538471d-a950-49b1-bed8-0a1aaa401ba5 Apr 29 21:08:39 smsl-n100 sudo[4779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb3 /mnt/USB/9538471d-a950-49b1-bed8-0a1aaa401ba5 -o noatime Apr 29 21:08:39 smsl-n100 sudo[4779]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:39 smsl-n100 kernel: EXT4-fs (sdb3): mounting ext2 file system using the ext4 subsystem Apr 29 21:08:39 smsl-n100 kernel: EXT4-fs (sdb3): warning: mounting unchecked fs, running e2fsck is recommended Apr 29 21:08:39 smsl-n100 kernel: EXT4-fs (sdb3): mounted filesystem without journal. Opts: (null) Apr 29 21:08:39 smsl-n100 sudo[4779]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:39 smsl-n100 volumio[981]: info: Executing endpoint restartRAATSocket Apr 29 21:08:39 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 29 21:08:39 smsl-n100 volumio[981]: info: CoreStateMachine::pushState Apr 29 21:08:39 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:39 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 21:08:39 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 21:08:39 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 21:08:39 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 21:08:39 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 29 21:08:39 smsl-n100 volumio[981]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 21:08:39 smsl-n100 volumio[981]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 29 21:08:40 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:40 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:40 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:40 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:40 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:40 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:40 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:40 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:40 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 21:08:40 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 29 21:08:40 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 21:08:41 smsl-n100 mpd[4755]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 29 21:08:41 smsl-n100 mpd[4755]: output: No 'audio_output' defined in config file Apr 29 21:08:41 smsl-n100 mpd[4755]: output: Successfully detected a sndio audio device Apr 29 21:08:41 smsl-n100 mpd[4755]: zeroconf: No global port, disabling zeroconf Apr 29 21:08:41 smsl-n100 systemd[1]: Started Music Player Daemon. Apr 29 21:08:41 smsl-n100 mpd_monitor.sh[555]: MPD restarted due to no mpc output. Apr 29 21:08:41 smsl-n100 volumio[981]: MPD error: No database Apr 29 21:08:41 smsl-n100 volumio[981]: MPD error: No database Apr 29 21:08:41 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 29 21:08:42 smsl-n100 volumio[981]: xcb_connection_has_error() returned true Apr 29 21:08:42 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:42 smsl-n100 sudo[4807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 29 21:08:42 smsl-n100 volumio[981]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 29 21:08:42 smsl-n100 volumio[981]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 29 21:08:42 smsl-n100 sudo[4807]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:42 smsl-n100 sudo[4807]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:42 smsl-n100 sudo[4810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 29 21:08:42 smsl-n100 sudo[4810]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:42 smsl-n100 volumio[981]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 29 21:08:42 smsl-n100 volumio[981]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 29 21:08:42 smsl-n100 sudo[4811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 29 21:08:42 smsl-n100 sudo[4811]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:42 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Apr 29 21:08:42 smsl-n100 sudo[4815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 29 21:08:42 smsl-n100 sudo[4815]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:42 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Apr 29 21:08:42 smsl-n100 volumio[981]: MPD error: No database Apr 29 21:08:42 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 29 21:08:42 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Apr 29 21:08:42 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Apr 29 21:08:42 smsl-n100 volumio[981]: error: updateQueue error: null Apr 29 21:08:42 smsl-n100 sudo[4811]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:42 smsl-n100 volumio[981]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 29 21:08:42 smsl-n100 systemd[1]: Started RAAT DAEMON. Apr 29 21:08:42 smsl-n100 sudo[4810]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:42 smsl-n100 volumio[981]: info: Starting Shairport Sync Apr 29 21:08:42 smsl-n100 sudo[4815]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:42 smsl-n100 sudo[4833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 29 21:08:42 smsl-n100 sudo[4833]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 21:08:42 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 21:08:42 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 21:08:42 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 29 21:08:42 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Apr 29 21:08:42 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 29 21:08:42 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 29 21:08:42 smsl-n100 sudo[4833]: pam_unix(sudo:session): session closed for user root Apr 29 21:08:42 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 21:08:42 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 21:08:42 smsl-n100 volumio[981]: info: Shairport-Sync Started Apr 29 21:08:42 smsl-n100 volumio[981]: info: Raat Daemon started successfully Apr 29 21:08:42 smsl-n100 volumio[981]: info: Executing endpoint restartRAATSocket Apr 29 21:08:42 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 29 21:08:42 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:42 smsl-n100 volumio[981]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 29 21:08:42 smsl-n100 volumio[981]: info: Executing endpoint tc_getconfig Apr 29 21:08:42 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 29 21:08:42 smsl-n100 vtcs[4817]: STARTING TidalConnect services, version: 1.4.0.34 Apr 29 21:08:43 smsl-n100 vtcs[4817]: STARTED TidalConnect services. Apr 29 21:08:43 smsl-n100 volumio[981]: info: Executing endpoint tc_connect Apr 29 21:08:43 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 29 21:08:43 smsl-n100 volumio[981]: info: Connecting to TidalConnect Apr 29 21:08:43 smsl-n100 dhcpcd[968]: wlan0: leased 192.168.10.15 for 172800 seconds Apr 29 21:08:43 smsl-n100 dhcpcd[968]: wlan0: adding route to 192.168.10.0/24 Apr 29 21:08:43 smsl-n100 dhcpcd[968]: wlan0: adding default route via 192.168.10.1 Apr 29 21:08:43 smsl-n100 avahi-daemon[556]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.10.15. Apr 29 21:08:43 smsl-n100 avahi-daemon[556]: New relevant interface wlan0.IPv4 for mDNS. Apr 29 21:08:43 smsl-n100 avahi-daemon[556]: Registering new address record for 192.168.10.15 on wlan0.IPv4. Apr 29 21:08:43 smsl-n100 volumio[981]: info: CoreCommandRouter::servicePushState Apr 29 21:08:43 smsl-n100 volumio[981]: info: CoreStateMachine::pushState Apr 29 21:08:43 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:43 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 21:08:43 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 21:08:43 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 21:08:43 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:43 smsl-n100 volumio[981]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 29 21:08:43 smsl-n100 volumio[981]: verbose: CURRENT POSITION 0 Apr 29 21:08:43 smsl-n100 volumio[981]: info: CoreStateMachine::syncState stateService stop Apr 29 21:08:43 smsl-n100 volumio[981]: info: CoreStateMachine::syncState currentStatus pause Apr 29 21:08:43 smsl-n100 volumio[981]: info: CoreStateMachine::pushState Apr 29 21:08:43 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:43 smsl-n100 volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 21:08:43 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 21:08:43 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 21:08:43 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 21:08:43 smsl-n100 volumio[981]: info: CoreCommandRouter::servicePushState Apr 29 21:08:43 smsl-n100 volumio[981]: info: CoreStateMachine::pushState Apr 29 21:08:43 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:43 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 21:08:43 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 21:08:43 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 21:08:43 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:43 smsl-n100 volumio[981]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 29 21:08:43 smsl-n100 volumio[981]: verbose: CURRENT POSITION 0 Apr 29 21:08:43 smsl-n100 volumio[981]: info: CoreStateMachine::syncState stateService stop Apr 29 21:08:43 smsl-n100 volumio[981]: info: CoreStateMachine::syncState currentStatus pause Apr 29 21:08:43 smsl-n100 volumio[981]: info: CoreStateMachine::pushState Apr 29 21:08:43 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:43 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioPushState Apr 29 21:08:43 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output update for this device Apr 29 21:08:43 smsl-n100 volumio[981]: info: MRS: Pushing multiroomSync output Apr 29 21:08:43 smsl-n100 ntpd[967]: ntpd exiting on signal 15 (Terminated) Apr 29 21:08:43 smsl-n100 systemd[1]: Stopping Network Time Service... Apr 29 21:08:43 smsl-n100 systemd[1]: ntp.service: Succeeded. Apr 29 21:08:43 smsl-n100 systemd[1]: Stopped Network Time Service. Apr 29 21:08:43 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 21:08:43 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 21:08:43 smsl-n100 systemd[1]: Starting Network Time Service... Apr 29 21:08:43 smsl-n100 volumio[981]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 21:08:43 smsl-n100 ntpd[4890]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 29 21:08:43 smsl-n100 ntpd[4890]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 29 21:08:43 smsl-n100 systemd[1]: Started Network Time Service. Apr 29 21:08:43 smsl-n100 ntpd[4896]: proto: precision = 0.875 usec (-20) Apr 29 21:08:43 smsl-n100 ntpd[4896]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 29 21:08:43 smsl-n100 ntpd[4896]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 29 21:08:43 smsl-n100 ntpd[4896]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 854 days ago Apr 29 21:08:43 smsl-n100 ntpd[4896]: Listen and drop on 0 v6wildcard [::]:123 Apr 29 21:08:43 smsl-n100 ntpd[4896]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 29 21:08:43 smsl-n100 ntpd[4896]: Listen normally on 2 lo 127.0.0.1:123 Apr 29 21:08:43 smsl-n100 ntpd[4896]: Listen normally on 3 wlan0 192.168.10.15:123 Apr 29 21:08:43 smsl-n100 ntpd[4896]: Listening on routing socket on fd #20 for interface updates Apr 29 21:08:43 smsl-n100 ntpd[4896]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 29 21:08:43 smsl-n100 ntpd[4896]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 29 21:08:43 smsl-n100 volumio[981]: info: MRS: Found cast device: WiiM-Pro-Receiver-279773352883945c0742c6703e415a5a Apr 29 21:08:44 smsl-n100 volumio[981]: info: Discovery: adding 1308b783-00b6-4850-b453-1fe94ceae9f3 Apr 29 21:08:44 smsl-n100 volumio[981]: info: Discovery: Found device SMSL N100 Apr 29 21:08:44 smsl-n100 volumio[981]: info: CoreCommandRouter::volumioGetState Apr 29 21:08:44 smsl-n100 volumio[981]: info: CorePlayQueue::getTrack 0 Apr 29 21:08:44 smsl-n100 volumio[981]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 21:08:44 smsl-n100 volumio[981]: Error: The value NaN is not a number Apr 29 21:08:44 smsl-n100 volumio[981]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Apr 29 21:08:44 smsl-n100 volumio[981]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Apr 29 21:08:44 smsl-n100 volumio[981]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Apr 29 21:08:44 smsl-n100 volumio[981]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Apr 29 21:08:44 smsl-n100 volumio[981]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Apr 29 21:08:44 smsl-n100 volumio[981]: at Browser.emit (events.js:400:28) Apr 29 21:08:44 smsl-n100 volumio[981]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Apr 29 21:08:44 smsl-n100 volumio[981]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Apr 29 21:08:44 smsl-n100 volumio[981]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Apr 29 21:08:44 smsl-n100 volumio[981]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Apr 29 21:08:44 smsl-n100 volumio[981]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Apr 29 21:08:44 smsl-n100 volumio[981]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Apr 29 21:08:44 smsl-n100 volumio[981]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Apr 29 21:08:44 smsl-n100 volumio[981]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 21:08:44 smsl-n100 ntpd[4896]: Soliciting pool server 95.79.30.113 Apr 29 21:08:45 smsl-n100 sudo[4907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-29 21:07 Apr 29 21:08:45 smsl-n100 sudo[4907]: 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="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"