-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Tue 2025-04-29 22:06:30 MSK. -- Apr 29 22:06:11 smsl-n100 kernel: usb usb2-port1: disabled by hub (EMI?), re-enabling... Apr 29 22:06:11 smsl-n100 kernel: usb 2-1: USB disconnect, device number 2 Apr 29 22:06:11 smsl-n100 kernel: usb 2-1.1: USB disconnect, device number 3 Apr 29 22:06:11 smsl-n100 volumio[1000]: 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-path/platform-ff5c0000.usb-usb-0:1.1:1.0-scsi-0:0:0:0-part2 /dev/disk/by-id/usb-ST2000VM_003-1CT164_M6116018VE15-0:0-part2 /dev/disk/by-uuid/ef468d0b-cae4-4be4-b45e-2ec63867b5aa","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":"2322","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4129869"} Apr 29 22:06:11 smsl-n100 sudo[8015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda2 Apr 29 22:06:11 smsl-n100 sudo[8015]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:11 smsl-n100 sudo[8015]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:11 smsl-n100 volumio[1000]: umount: /dev/sda2: no mount point specified. Apr 29 22:06:11 smsl-n100 volumio[1000]: error: Failed to umount ef468d0b-cae4-4be4-b45e-2ec63867b5aa: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sda2" Apr 29 22:06:11 smsl-n100 volumio[1000]: umount: /dev/sda2: no mount point specified. Apr 29 22:06:11 smsl-n100 volumio[1000]: 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-uuid/9538471d-a950-49b1-bed8-0a1aaa401ba5 /dev/disk/by-path/platform-ff5c0000.usb-usb-0:1.1:1.0-scsi-0:0:0:0-part3 /dev/disk/by-partuuid/ed926ea6-03 /dev/disk/by-id/usb-ST2000VM_003-1CT164_M6116018VE15-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":"2321","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4129892"} Apr 29 22:06:12 smsl-n100 sudo[8018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda3 Apr 29 22:06:12 smsl-n100 sudo[8018]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:12 smsl-n100 kernel: Buffer I/O error on dev sda3, logical block 0, lost sync page write Apr 29 22:06:12 smsl-n100 kernel: EXT4-fs (sda3): I/O error while writing superblock Apr 29 22:06:12 smsl-n100 sudo[8018]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:12 smsl-n100 systemd[1]: media-9538471d\x2da950\x2d49b1\x2dbed8\x2d0a1aaa401ba5.mount: Succeeded. Apr 29 22:06:13 smsl-n100 volumio[1000]: 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-partuuid/ed926ea6-01 /dev/disk/by-uuid/180CDE6D0CDE4582 /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","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":"2323","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4163419"} Apr 29 22:06:13 smsl-n100 kernel: usb 2-1.3: USB disconnect, device number 4 Apr 29 22:06:13 smsl-n100 avahi-daemon[567]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 29 22:06:13 smsl-n100 wpa_supplicant[859]: wlan0: CTRL-EVENT-DISCONNECTED bssid=b8:80:35:c6:c8:ce reason=3 locally_generated=1 Apr 29 22:06:13 smsl-n100 dhcpcd[999]: wlan0: carrier lost Apr 29 22:06:13 smsl-n100 avahi-daemon[567]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.10.15. Apr 29 22:06:13 smsl-n100 avahi-daemon[567]: Withdrawing address record for 192.168.10.15 on wlan0. Apr 29 22:06:13 smsl-n100 kernel: usb 2-1.4: USB disconnect, device number 5 Apr 29 22:06:13 smsl-n100 systemd[1]: Starting Load/Save RF Kill Switch Status... Apr 29 22:06:14 smsl-n100 systemd[1]: Stopping ifup for wlan0... Apr 29 22:06:14 smsl-n100 sudo[8041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Apr 29 22:06:14 smsl-n100 sudo[8041]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:14 smsl-n100 systemd[1]: Started Load/Save RF Kill Switch Status. Apr 29 22:06:14 smsl-n100 wpa_supplicant[859]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Apr 29 22:06:14 smsl-n100 wpa_supplicant[859]: nl80211: Failed to set IPv4 unicast in multicast filter Apr 29 22:06:14 smsl-n100 sudo[8041]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:14 smsl-n100 systemd[1]: media-180CDE6D0CDE4582.mount: Succeeded. Apr 29 22:06:14 smsl-n100 dhcpcd[999]: wlan0: deleting route to 192.168.10.0/24 Apr 29 22:06:14 smsl-n100 dhcpcd[999]: wlan0: deleting default route via 192.168.10.1 Apr 29 22:06:14 smsl-n100 kernel: usb 2-1: new high-speed USB device number 6 using ehci-platform Apr 29 22:06:14 smsl-n100 ntfs-3g[1086]: Unmounting /dev/sda1 () Apr 29 22:06:14 smsl-n100 ntfs-3g[1086]: Failed to sync device /dev/sda1: Input/output error Apr 29 22:06:14 smsl-n100 ntfs-3g[1086]: Failed to close volume /dev/sda1: Input/output error Apr 29 22:06:14 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 22:06:14 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 22:06:14 smsl-n100 kernel: usb 2-1: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.11 Apr 29 22:06:14 smsl-n100 kernel: usb 2-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Apr 29 22:06:14 smsl-n100 kernel: usb 2-1: Product: USB 2.0 Hub Apr 29 22:06:14 smsl-n100 kernel: hub 2-1:1.0: USB hub found Apr 29 22:06:14 smsl-n100 kernel: hub 2-1:1.0: 4 ports detected Apr 29 22:06:14 smsl-n100 dhcpcd[999]: wlan0: removing interface Apr 29 22:06:14 smsl-n100 volumio[1000]: info: Discovery: A device disappeared from network Apr 29 22:06:14 smsl-n100 volumio[1000]: info: Apr 29 22:06:14 smsl-n100 volumio[1000]: ---------------------------- USB Audio Device Detached Apr 29 22:06:14 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 29 22:06:14 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 29 22:06:14 smsl-n100 systemd[1]: ifup@wlan0.service: Succeeded. Apr 29 22:06:14 smsl-n100 systemd[1]: Stopped ifup for wlan0. Apr 29 22:06:14 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true Apr 29 22:06:14 smsl-n100 kernel: usb 2-1.1: new high-speed USB device number 7 using ehci-platform Apr 29 22:06:14 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 29 22:06:14 smsl-n100 volumio[1000]: info: Stopping RAAT Plugin Apr 29 22:06:14 smsl-n100 sudo[8109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Apr 29 22:06:14 smsl-n100 sudo[8109]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:14 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Apr 29 22:06:14 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 29 22:06:14 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Apr 29 22:06:14 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Apr 29 22:06:14 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 22:06:14 smsl-n100 sudo[8109]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:14 smsl-n100 kernel: usb 2-1.1: New USB device found, idVendor=14cd, idProduct=6116, bcdDevice= 1.50 Apr 29 22:06:14 smsl-n100 kernel: usb 2-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Apr 29 22:06:14 smsl-n100 kernel: usb 2-1.1: Product: USB 2.0 SATA BRIDGE Apr 29 22:06:14 smsl-n100 kernel: usb 2-1.1: Manufacturer: Super Top Apr 29 22:06:14 smsl-n100 kernel: usb 2-1.1: SerialNumber: M6116018VE15 Apr 29 22:06:14 smsl-n100 kernel: usb-storage 2-1.1:1.0: USB Mass Storage device detected Apr 29 22:06:14 smsl-n100 kernel: scsi host0: usb-storage 2-1.1:1.0 Apr 29 22:06:14 smsl-n100 volumio[1000]: info: Raat Daemon stopped successfully Apr 29 22:06:15 smsl-n100 kernel: usb 2-1.3: new high-speed USB device number 8 using ehci-platform Apr 29 22:06:15 smsl-n100 ntpd[997]: Deleting interface #3 wlan0, 192.168.10.15#123, interface stats: received=436, sent=441, dropped=1, active_time=2713 secs Apr 29 22:06:15 smsl-n100 ntpd[997]: 94.141.168.72 local addr 192.168.10.15 -> Apr 29 22:06:15 smsl-n100 ntpd[997]: 212.113.99.6 local addr 192.168.10.15 -> Apr 29 22:06:15 smsl-n100 ntpd[997]: 193.106.93.116 local addr 192.168.10.15 -> Apr 29 22:06:15 smsl-n100 ntpd[997]: 91.201.254.110 local addr 192.168.10.15 -> Apr 29 22:06:15 smsl-n100 ntpd[997]: 51.250.53.172 local addr 192.168.10.15 -> Apr 29 22:06:15 smsl-n100 ntpd[997]: 51.250.68.198 local addr 192.168.10.15 -> Apr 29 22:06:15 smsl-n100 ntpd[997]: 147.45.251.137 local addr 192.168.10.15 -> Apr 29 22:06:15 smsl-n100 ntpd[997]: 46.188.16.150 local addr 192.168.10.15 -> Apr 29 22:06:15 smsl-n100 ntpd[997]: 45.141.102.99 local addr 192.168.10.15 -> Apr 29 22:06:15 smsl-n100 ntpd[997]: 162.159.200.123 local addr 192.168.10.15 -> Apr 29 22:06:15 smsl-n100 ntpd[997]: 162.159.200.1 local addr 192.168.10.15 -> Apr 29 22:06:15 smsl-n100 kernel: usb 2-1.3: New USB device found, idVendor=0bda, idProduct=c811, bcdDevice= 2.00 Apr 29 22:06:15 smsl-n100 kernel: usb 2-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 29 22:06:15 smsl-n100 kernel: usb 2-1.3: Product: 802.11ac NIC Apr 29 22:06:15 smsl-n100 kernel: usb 2-1.3: Manufacturer: Realtek Apr 29 22:06:15 smsl-n100 kernel: usb 2-1.3: SerialNumber: 123456 Apr 29 22:06:15 smsl-n100 kernel: usb 2-1.4: new high-speed USB device number 9 using ehci-platform Apr 29 22:06:15 smsl-n100 kernel: scsi 0:0:0:0: Direct-Access ST2000VM 003-1CT164 PQ: 0 ANSI: 0 Apr 29 22:06:15 smsl-n100 kernel: sd 0:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB) Apr 29 22:06:15 smsl-n100 kernel: sd 0:0:0:0: [sda] Write Protect is off Apr 29 22:06:15 smsl-n100 kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Apr 29 22:06:15 smsl-n100 kernel: sd 0:0:0:0: [sda] No Caching mode page found Apr 29 22:06:15 smsl-n100 kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Apr 29 22:06:15 smsl-n100 kernel: sda: sda1 sda2 sda3 Apr 29 22:06:15 smsl-n100 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Apr 29 22:06:16 smsl-n100 kernel: usb 2-1.4: New USB device found, idVendor=20b1, idProduct=30c5, bcdDevice= 1.10 Apr 29 22:06:16 smsl-n100 kernel: usb 2-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Apr 29 22:06:16 smsl-n100 kernel: usb 2-1.4: Product: DS07 Apr 29 22:06:16 smsl-n100 kernel: usb 2-1.4: Manufacturer: FX-AUDIO Apr 29 22:06:16 smsl-n100 kernel: usb 2-1.4: 1:3 : unsupported format bits 0x100000000 Apr 29 22:06:16 smsl-n100 volumio[1000]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/9538471d-a950-49b1-bed8-0a1aaa401ba5 Apr 29 22:06:16 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 22:06:16 smsl-n100 systemd-udevd[8012]: Using default interface naming scheme 'v240'. Apr 29 22:06:16 smsl-n100 volumio[1000]: info: Apr 29 22:06:16 smsl-n100 volumio[1000]: ---------------------------- USB Audio Device Attached Apr 29 22:06:16 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 29 22:06:16 smsl-n100 dhcpcd[999]: wlan0: waiting for carrier Apr 29 22:06:16 smsl-n100 systemd-udevd[8012]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 29 22:06:16 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true Apr 29 22:06:16 smsl-n100 volumio[1000]: info: CoreCommandRouter::Close All Modals sent Apr 29 22:06:16 smsl-n100 volumio[1000]: info: Preparing to save Alsa Options, stopping services first Apr 29 22:06:16 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioGetState Apr 29 22:06:16 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:16 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPause Apr 29 22:06:16 smsl-n100 volumio[1000]: info: CoreStateMachine::pause Apr 29 22:06:16 smsl-n100 volumio[1000]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"DS07","alsacard":"DS07"},"i2s":false} Apr 29 22:06:16 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 29 22:06:16 smsl-n100 systemd-udevd[8128]: Process '/sbin/crda' failed with exit code 255. Apr 29 22:06:16 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true Apr 29 22:06:16 smsl-n100 systemd[1]: Found device 802.11ac_NIC. Apr 29 22:06:16 smsl-n100 systemd[1]: Started ifup for wlan0. Apr 29 22:06:16 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true Apr 29 22:06:16 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 29 22:06:17 smsl-n100 nmbd[748]: [2025/04/29 22:06:17.002995, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 29 22:06:17 smsl-n100 nmbd[748]: Packet send failed to 192.168.10.255(137) ERRNO=Network is unreachable Apr 29 22:06:17 smsl-n100 nmbd[748]: [2025/04/29 22:06:17.004023, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Apr 29 22:06:17 smsl-n100 nmbd[748]: send_netbios_packet: send_packet() to IP 192.168.10.255 port 137 failed Apr 29 22:06:17 smsl-n100 nmbd[748]: [2025/04/29 22:06:17.004376, 0] ../source3/nmbd/nmbd_namequery.c:245(query_name) Apr 29 22:06:17 smsl-n100 nmbd[748]: query_name: Failed to send packet trying to query name WORKGROUP<1d> Apr 29 22:06:17 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true Apr 29 22:06:17 smsl-n100 volumio[1000]: info: Device 5 does not have any Mixer Control Available Apr 29 22:06:17 smsl-n100 volumio[1000]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Apr 29 22:06:17 smsl-n100 vtcs[1455]: [2025-04-29 22:06:17.197] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Apr 29 22:06:17 smsl-n100 vtcs[1455]: [2025-04-29 22:06:17.193] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Apr 29 22:06:17 smsl-n100 sudo[8208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 29 22:06:17 smsl-n100 sudo[8208]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:17 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Apr 29 22:06:17 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 29 22:06:17 smsl-n100 systemd[1]: vtcs.service: Succeeded. Apr 29 22:06:17 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Apr 29 22:06:17 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true Apr 29 22:06:17 smsl-n100 sudo[8208]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:17 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 29 22:06:17 smsl-n100 volumio[1000]: info: Updating Volume Controller Parameters: Device: 5 Name: DS07 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 29 22:06:17 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 29 22:06:17 smsl-n100 volumio[1000]: info: Disabling external Volume Control Apr 29 22:06:17 smsl-n100 volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 22:06:17 smsl-n100 sh[8153]: wlan0=wlan0 Apr 29 22:06:17 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:17 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:17 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 22:06:17 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:17 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:17 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:17 smsl-n100 volumio[1000]: info: Preparing to generate the ALSA configuration file Apr 29 22:06:17 smsl-n100 sudo[8222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 29 22:06:17 smsl-n100 sudo[8222]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:17 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:17 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:17 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:17 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:17 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:17 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:17 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:17 smsl-n100 sudo[8222]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:17 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:17 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 22:06:17 smsl-n100 volumio[1000]: info: Not Reporting Auto name since its the default one Apr 29 22:06:17 smsl-n100 volumio[1000]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 29 22:06:17 smsl-n100 volumio[1000]: info: Reading ALSA contributions from plugins. Apr 29 22:06:17 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 22:06:17 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 22:06:17 smsl-n100 volumio[1000]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 29 22:06:17 smsl-n100 volumio[1000]: info: Updating RAAT Signal Path Apr 29 22:06:17 smsl-n100 volumio[1000]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 29 22:06:17 smsl-n100 volumio[1000]: info: Reporting MCU Network Status: 0 Apr 29 22:06:18 smsl-n100 volumio[1000]: info: Volumio Network Manager: Network status updated: 0 Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: Not Reporting Auto name since its the default one Apr 29 22:06:18 smsl-n100 volumio[1000]: info: Mounting Device 9538471d-a950-49b1-bed8-0a1aaa401ba5 Apr 29 22:06:18 smsl-n100 sudo[8240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda3 /mnt/USB/9538471d-a950-49b1-bed8-0a1aaa401ba5 -o noatime Apr 29 22:06:18 smsl-n100 sudo[8240]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:18 smsl-n100 kernel: EXT4-fs (sda3): mounting ext2 file system using the ext4 subsystem Apr 29 22:06:18 smsl-n100 kernel: EXT4-fs (sda3): warning: mounting unchecked fs, running e2fsck is recommended Apr 29 22:06:18 smsl-n100 kernel: EXT4-fs (sda3): mounted filesystem without journal. Opts: (null) Apr 29 22:06:18 smsl-n100 sudo[8240]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:18 smsl-n100 volumio[1000]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/180CDE6D0CDE4582 Apr 29 22:06:18 smsl-n100 volumio[1000]: info: Mounting Device ef468d0b-cae4-4be4-b45e-2ec63867b5aa Apr 29 22:06:18 smsl-n100 sudo[8247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/ef468d0b-cae4-4be4-b45e-2ec63867b5aa -o noatime Apr 29 22:06:18 smsl-n100 sudo[8247]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:18 smsl-n100 sudo[8247]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:18 smsl-n100 volumio[1000]: mount: /media/ef468d0b-cae4-4be4-b45e-2ec63867b5aa: unknown filesystem type 'swap'. Apr 29 22:06:18 smsl-n100 volumio[1000]: error: Failed to mount ef468d0b-cae4-4be4-b45e-2ec63867b5aa: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda2" "/mnt/USB/ef468d0b-cae4-4be4-b45e-2ec63867b5aa" -o noatime Apr 29 22:06:18 smsl-n100 volumio[1000]: mount: /media/ef468d0b-cae4-4be4-b45e-2ec63867b5aa: unknown filesystem type 'swap'. Apr 29 22:06:18 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 29 22:06:18 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Apr 29 22:06:18 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 29 22:06:19 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true Apr 29 22:06:19 smsl-n100 sudo[8257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 29 22:06:19 smsl-n100 sudo[8257]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:19 smsl-n100 volumio[1000]: info: Scanning removed location : "USB/9538471d-a950-49b1-bed8-0a1aaa401ba5" Apr 29 22:06:19 smsl-n100 sudo[8257]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:19 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:19 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:19 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 22:06:19 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:19 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:19 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:19 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 22:06:19 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 29 22:06:19 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 22:06:19 smsl-n100 volumio[1000]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 29 22:06:19 smsl-n100 sudo[8262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 29 22:06:19 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 0, message: undefined Apr 29 22:06:19 smsl-n100 sudo[8262]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:19 smsl-n100 systemd[1]: Started RAAT DAEMON. Apr 29 22:06:19 smsl-n100 sudo[8262]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:19 smsl-n100 volumio[1000]: info: Mounting Device 180CDE6D0CDE4582 Apr 29 22:06:19 smsl-n100 sudo[8273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/180CDE6D0CDE4582 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 29 22:06:19 smsl-n100 sudo[8273]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:21 smsl-n100 systemd[1]: systemd-rfkill.service: Succeeded. Apr 29 22:06:21 smsl-n100 wpa_supplicant[859]: wlan0: Trying to associate with b8:80:35:c6:c8:ce (SSID='SKYNET' freq=2412 MHz) Apr 29 22:06:21 smsl-n100 wpa_supplicant[859]: wlan0: Associated with b8:80:35:c6:c8:ce Apr 29 22:06:21 smsl-n100 wpa_supplicant[859]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 29 22:06:21 smsl-n100 wpa_supplicant[859]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=RU Apr 29 22:06:21 smsl-n100 wpa_supplicant[859]: wlan0: WPA: Key negotiation completed with b8:80:35:c6:c8:ce [PTK=CCMP GTK=CCMP] Apr 29 22:06:21 smsl-n100 wpa_supplicant[859]: wlan0: CTRL-EVENT-CONNECTED - Connection to b8:80:35:c6:c8:ce completed [id=0 id_str=] Apr 29 22:06:21 smsl-n100 dhcpcd[999]: wlan0: carrier acquired Apr 29 22:06:21 smsl-n100 dhcpcd[999]: wlan0: IAID 2b:ee:c8:de Apr 29 22:06:21 smsl-n100 dhcpcd[999]: wlan0: adding address fe80::2af5:2bff:feee:c8de Apr 29 22:06:21 smsl-n100 dhcpcd[999]: ipv6_addaddr1: Permission denied Apr 29 22:06:21 smsl-n100 dhcpcd[999]: wlan0: soliciting an IPv6 router Apr 29 22:06:22 smsl-n100 ntfs-3g[8284]: Version 2017.3.23AR.3 integrated FUSE 28 Apr 29 22:06:22 smsl-n100 ntfs-3g[8284]: Mounted /dev/sda1 (Read-Write, label "", NTFS 3.1) Apr 29 22:06:22 smsl-n100 ntfs-3g[8284]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 29 22:06:22 smsl-n100 ntfs-3g[8284]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Apr 29 22:06:22 smsl-n100 ntfs-3g[8284]: Global ownership and permissions enforced, configuration type 7 Apr 29 22:06:22 smsl-n100 sudo[8273]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::getcurrentVolume Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioRetrievevolume Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:22 smsl-n100 volumio[1000]: info: RAAT: Requesting Headphone Status Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 29 22:06:22 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:22 smsl-n100 volumio[1000]: info: Apr 29 22:06:22 smsl-n100 volumio[1000]: ---------------------------- MPD announces state update: update Apr 29 22:06:22 smsl-n100 volumio[1000]: info: ControllerMpd::getState Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:22 smsl-n100 volumio[1000]: info: Apr 29 22:06:22 smsl-n100 volumio[1000]: ---------------------------- MPD announces state update: update Apr 29 22:06:22 smsl-n100 volumio[1000]: info: ControllerMpd::getState Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:22 smsl-n100 sudo[8288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 29 22:06:22 smsl-n100 sudo[8288]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:22 smsl-n100 volumio[1000]: info: Apr 29 22:06:22 smsl-n100 volumio[1000]: ---------------------------- MPD announces state update: update Apr 29 22:06:22 smsl-n100 volumio[1000]: info: ControllerMpd::getState Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:22 smsl-n100 sudo[8288]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:22 smsl-n100 volumio[1000]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 29 22:06:22 smsl-n100 volumio[1000]: info: Updating RAAT Signal Path Apr 29 22:06:22 smsl-n100 volumio[1000]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 29 22:06:22 smsl-n100 sudo[8290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 29 22:06:22 smsl-n100 sudo[8290]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:22 smsl-n100 volumio[1000]: info: Raat Daemon started successfully Apr 29 22:06:22 smsl-n100 volumio[1000]: info: TidalConnect service stoped! Apr 29 22:06:22 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Apr 29 22:06:22 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 29 22:06:22 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Apr 29 22:06:22 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Apr 29 22:06:22 smsl-n100 volumio[1000]: info: TidalConnect service stoped! Apr 29 22:06:22 smsl-n100 systemd[1]: Started RAAT DAEMON. Apr 29 22:06:22 smsl-n100 sudo[8290]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:22 smsl-n100 volumio[1000]: info: Scanning new location : "USB/9538471d-a950-49b1-bed8-0a1aaa401ba5" Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:22 smsl-n100 volumio[1000]: info: Apr 29 22:06:22 smsl-n100 volumio[1000]: ---------------------------- MPD announces state update: update Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:22 smsl-n100 volumio[1000]: info: Apr 29 22:06:22 smsl-n100 volumio[1000]: ---------------------------- MPD announces state update: update Apr 29 22:06:22 smsl-n100 volumio[1000]: info: ControllerMpd::getState Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:22 smsl-n100 volumio[1000]: info: sendMpdCommand status took 277 milliseconds Apr 29 22:06:22 smsl-n100 volumio[1000]: info: sendMpdCommand status took 269 milliseconds Apr 29 22:06:22 smsl-n100 volumio[1000]: info: sendMpdCommand status took 266 milliseconds Apr 29 22:06:22 smsl-n100 volumio[1000]: info: sendMpdCommand status took 262 milliseconds Apr 29 22:06:22 smsl-n100 volumio[1000]: info: sendMpdCommand status took 261 milliseconds Apr 29 22:06:22 smsl-n100 volumio[1000]: info: sendMpdCommand status took 255 milliseconds Apr 29 22:06:22 smsl-n100 volumio[1000]: info: ControllerMpd::getState Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:22 smsl-n100 dhcpcd[999]: wlan0: rebinding lease of 192.168.10.15 Apr 29 22:06:22 smsl-n100 dhcpcd[999]: wlan0: probing address 192.168.10.15/24 Apr 29 22:06:22 smsl-n100 volumio[1000]: info: Command Router : Notfying DB Updatefalse Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::Close All Modals sent Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: Command Router : Notfying DB Updatefalse Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::Close All Modals sent Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: Command Router : Notfying DB Updatefalse Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::Close All Modals sent Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: ControllerMpd::pushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::servicePushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: CURRENT POSITION 0 Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState stateService stop Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:22 smsl-n100 volumio[1000]: info: No code Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:22 smsl-n100 volumio[1000]: info: ControllerMpd::pushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::servicePushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: CURRENT POSITION 0 Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState stateService stop Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:22 smsl-n100 volumio[1000]: info: No code Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:22 smsl-n100 volumio[1000]: info: ControllerMpd::pushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::servicePushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 29 22:06:22 smsl-n100 volumio[1000]: verbose: CURRENT POSITION 0 Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState stateService stop Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:22 smsl-n100 volumio[1000]: info: No code Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:22 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:22 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:23 smsl-n100 volumio[1000]: info: ------------------------------ 769ms Apr 29 22:06:23 smsl-n100 volumio[1000]: info: ------------------------------ 766ms Apr 29 22:06:23 smsl-n100 volumio[1000]: info: ------------------------------ 760ms Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Apr 29 22:06:23 smsl-n100 volumio[1000]: ---------------------------- MPD announces state update: update Apr 29 22:06:23 smsl-n100 volumio[1000]: info: ControllerMpd::getState Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Apr 29 22:06:23 smsl-n100 volumio[1000]: ---------------------------- MPD announces state update: update Apr 29 22:06:23 smsl-n100 volumio[1000]: info: ControllerMpd::getState Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:23 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 22:06:23 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Executing endpoint restartRAATSocket Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:23 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 22:06:23 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 29 22:06:23 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Apr 29 22:06:23 smsl-n100 volumio[1000]: ---------------------------- MPD announces state update: update Apr 29 22:06:23 smsl-n100 volumio[1000]: info: sendMpdCommand status took 658 milliseconds Apr 29 22:06:23 smsl-n100 volumio[1000]: info: ControllerMpd::getState Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:23 smsl-n100 volumio[1000]: info: sendMpdCommand status took 657 milliseconds Apr 29 22:06:23 smsl-n100 volumio[1000]: info: sendMpdCommand status took 650 milliseconds Apr 29 22:06:23 smsl-n100 volumio[1000]: info: sendMpdCommand status took 638 milliseconds Apr 29 22:06:23 smsl-n100 volumio[1000]: info: sendMpdCommand status took 140 milliseconds Apr 29 22:06:23 smsl-n100 volumio[1000]: info: sendMpdCommand status took 135 milliseconds Apr 29 22:06:23 smsl-n100 volumio[1000]: info: sendMpdCommand status took 132 milliseconds Apr 29 22:06:23 smsl-n100 volumio[1000]: info: sendMpdCommand status took 127 milliseconds Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Command Router : Notfying DB Updatetrue Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::Close All Modals sent Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Command Router : Notfying DB Updatefalse Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::Close All Modals sent Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Command Router : Notfying DB Updatefalse Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::Close All Modals sent Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Command Router : Notfying DB Updatefalse Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::Close All Modals sent Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: ControllerMpd::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::servicePushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: CURRENT POSITION 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState stateService stop Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:23 smsl-n100 volumio[1000]: info: No code Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:23 smsl-n100 volumio[1000]: info: ControllerMpd::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::servicePushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: CURRENT POSITION 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState stateService stop Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:23 smsl-n100 volumio[1000]: info: No code Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:23 smsl-n100 volumio[1000]: info: ControllerMpd::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::servicePushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: CURRENT POSITION 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState stateService stop Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:23 smsl-n100 volumio[1000]: info: No code Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:23 smsl-n100 volumio[1000]: info: ControllerMpd::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::servicePushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: CURRENT POSITION 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState stateService stop Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:23 smsl-n100 volumio[1000]: info: No code Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:23 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:23 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:23 smsl-n100 volumio[1000]: info: ------------------------------ 1174ms Apr 29 22:06:23 smsl-n100 volumio[1000]: info: ------------------------------ 1172ms Apr 29 22:06:23 smsl-n100 volumio[1000]: info: ------------------------------ 655ms Apr 29 22:06:23 smsl-n100 volumio[1000]: info: ------------------------------ 648ms Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Apr 29 22:06:23 smsl-n100 volumio[1000]: ---------------------------- MPD announces state update: update Apr 29 22:06:23 smsl-n100 volumio[1000]: info: ControllerMpd::getState Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Apr 29 22:06:23 smsl-n100 volumio[1000]: ---------------------------- MPD announces state update: update Apr 29 22:06:23 smsl-n100 volumio[1000]: info: ControllerMpd::getState Apr 29 22:06:23 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Updating RAAT Signal Path Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Updating RAAT Signal Path Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Updating RAAT Signal Path Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Updating RAAT Signal Path Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Updating RAAT Signal Path Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Updating RAAT Signal Path Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Updating RAAT Signal Path Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Updating RAAT Signal Path Apr 29 22:06:23 smsl-n100 volumio[1000]: info: Updating RAAT Signal Path Apr 29 22:06:24 smsl-n100 volumio[1000]: info: Raat Daemon started successfully Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:24 smsl-n100 volumio[1000]: info: Scanning new location : "USB/180CDE6D0CDE4582" Apr 29 22:06:24 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:24 smsl-n100 volumio[1000]: info: Apr 29 22:06:24 smsl-n100 volumio[1000]: ---------------------------- MPD announces state update: update Apr 29 22:06:24 smsl-n100 volumio[1000]: info: sendMpdCommand status took 935 milliseconds Apr 29 22:06:24 smsl-n100 volumio[1000]: info: sendMpdCommand status took 926 milliseconds Apr 29 22:06:24 smsl-n100 volumio[1000]: info: sendMpdCommand status took 400 milliseconds Apr 29 22:06:24 smsl-n100 volumio[1000]: info: sendMpdCommand status took 397 milliseconds Apr 29 22:06:24 smsl-n100 volumio[1000]: info: sendMpdCommand status took 395 milliseconds Apr 29 22:06:24 smsl-n100 volumio[1000]: info: sendMpdCommand status took 392 milliseconds Apr 29 22:06:24 smsl-n100 volumio[1000]: info: ControllerMpd::getState Apr 29 22:06:24 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:24 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: Command Router : Notfying DB Updatefalse Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::Close All Modals sent Apr 29 22:06:24 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:24 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: Command Router : Notfying DB Updatefalse Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::Close All Modals sent Apr 29 22:06:24 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:24 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: Command Router : Notfying DB Updatefalse Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::Close All Modals sent Apr 29 22:06:24 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: ControllerMpd::pushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::servicePushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:24 smsl-n100 volumio[1000]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 29 22:06:24 smsl-n100 volumio[1000]: verbose: CURRENT POSITION 0 Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState stateService stop Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:24 smsl-n100 volumio[1000]: info: No code Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:24 smsl-n100 volumio[1000]: info: ControllerMpd::pushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::servicePushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:24 smsl-n100 volumio[1000]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 29 22:06:24 smsl-n100 volumio[1000]: verbose: CURRENT POSITION 0 Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState stateService stop Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:24 smsl-n100 volumio[1000]: info: No code Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:24 smsl-n100 volumio[1000]: info: ControllerMpd::pushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::servicePushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:24 smsl-n100 volumio[1000]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 29 22:06:24 smsl-n100 volumio[1000]: verbose: CURRENT POSITION 0 Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState stateService stop Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:24 smsl-n100 volumio[1000]: info: No code Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:24 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:24 smsl-n100 volumio[1000]: info: ------------------------------ 1379ms Apr 29 22:06:24 smsl-n100 volumio[1000]: info: ------------------------------ 850ms Apr 29 22:06:24 smsl-n100 volumio[1000]: info: ------------------------------ 846ms Apr 29 22:06:24 smsl-n100 volumio[1000]: info: Executing endpoint restartRAATSocket Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 29 22:06:24 smsl-n100 volumio[1000]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 29 22:06:24 smsl-n100 volumio[1000]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 29 22:06:24 smsl-n100 volumio[1000]: info: Asound.conf file unchanged, so no further update is needed Apr 29 22:06:24 smsl-n100 volumio[1000]: info: Output device has changed, restarting MPD Apr 29 22:06:24 smsl-n100 sudo[8338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 29 22:06:24 smsl-n100 sudo[8338]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:24 smsl-n100 sudo[8341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 29 22:06:24 smsl-n100 sudo[8341]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:24 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Apr 29 22:06:24 smsl-n100 volumio[1000]: info: Output device has changed, restarting Shairport Sync Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:24 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 22:06:24 smsl-n100 sudo[8338]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:24 smsl-n100 sudo[8341]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:24 smsl-n100 sudo[8345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 29 22:06:24 smsl-n100 sudo[8345]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:24 smsl-n100 systemd[1]: Stopping Music Player Daemon... Apr 29 22:06:25 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true Apr 29 22:06:25 smsl-n100 volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 29 22:06:25 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true Apr 29 22:06:25 smsl-n100 volumio[1000]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:25 smsl-n100 volumio[1000]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:25 smsl-n100 sudo[8355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 29 22:06:25 smsl-n100 sudo[8355]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:25 smsl-n100 volumio[1000]: info: sendMpdCommand status took 1046 milliseconds Apr 29 22:06:25 smsl-n100 volumio[1000]: info: sendMpdCommand status took 1035 milliseconds Apr 29 22:06:25 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:25 smsl-n100 sudo[8357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 29 22:06:25 smsl-n100 sudo[8357]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:25 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Apr 29 22:06:25 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 29 22:06:25 smsl-n100 systemd[1]: vtcs.service: Succeeded. Apr 29 22:06:25 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Apr 29 22:06:25 smsl-n100 volumio[1000]: info: Command Router : Notfying DB Updatetrue Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::Close All Modals sent Apr 29 22:06:25 smsl-n100 volumio[1000]: verbose: ControllerMpd::parseState Apr 29 22:06:25 smsl-n100 sudo[8355]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:25 smsl-n100 volumio[1000]: info: ControllerMpd::pushState Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::servicePushState Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:25 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:25 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:25 smsl-n100 volumio[1000]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 29 22:06:25 smsl-n100 volumio[1000]: verbose: CURRENT POSITION 0 Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState stateService stop Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:25 smsl-n100 sudo[8357]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:25 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:25 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:25 smsl-n100 volumio[1000]: info: No code Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:25 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:25 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:25 smsl-n100 volumio[1000]: info: ------------------------------ 1299ms Apr 29 22:06:25 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:25 smsl-n100 volumio[1000]: info: Apr 29 22:06:25 smsl-n100 volumio[1000]: ---------------------------- MPD announces state update: update Apr 29 22:06:25 smsl-n100 volumio[1000]: info: ControllerMpd::getState Apr 29 22:06:25 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:25 smsl-n100 volumio[1000]: error: MPD error: Error: write EPIPE Apr 29 22:06:25 smsl-n100 volumio[1000]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Apr 29 22:06:25 smsl-n100 volumio[1000]: error: MPD error: Error: write EPIPE Apr 29 22:06:25 smsl-n100 volumio[1000]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Apr 29 22:06:25 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:25 smsl-n100 volumio[1000]: info: Apr 29 22:06:25 smsl-n100 volumio[1000]: ---------------------------- MPD announces state update: update Apr 29 22:06:25 smsl-n100 volumio[1000]: info: ControllerMpd::getState Apr 29 22:06:25 smsl-n100 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Apr 29 22:06:25 smsl-n100 volumio[1000]: error: MPD error: Error: write EPIPE Apr 29 22:06:25 smsl-n100 volumio[1000]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:25 smsl-n100 volumio[1000]: info: RAAT: Requesting Headphone Status Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 22:06:25 smsl-n100 volumio[1000]: info: Not Reporting Auto name since its the default one Apr 29 22:06:25 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 22:06:25 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 22:06:25 smsl-n100 volumio[1000]: 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 22:06:25 smsl-n100 volumio[1000]: info: MPD Permissions set Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreStateMachine::pushState Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:25 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioPushState Apr 29 22:06:25 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output update for this device Apr 29 22:06:25 smsl-n100 volumio[1000]: info: MRS: Pushing multiroomSync output Apr 29 22:06:25 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 22:06:25 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 29 22:06:25 smsl-n100 volumio[1000]: error: Serial API: Failed to decode command: 100, message: undefined Apr 29 22:06:25 smsl-n100 volumio[1000]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 29 22:06:26 smsl-n100 volumio[1000]: 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 22:06:26 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:26 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:26 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:26 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:26 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:26 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:26 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:26 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:26 smsl-n100 systemd[1]: mpd.service: Succeeded. Apr 29 22:06:26 smsl-n100 systemd[1]: Stopped Music Player Daemon. Apr 29 22:06:26 smsl-n100 systemd[1]: Starting Music Player Daemon... Apr 29 22:06:26 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 22:06:26 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 29 22:06:26 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 22:06:26 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 29 22:06:26 smsl-n100 sudo[8372]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 29 22:06:26 smsl-n100 volumio[1000]: xcb_connection_has_error() returned true Apr 29 22:06:26 smsl-n100 sudo[8372]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:26 smsl-n100 sudo[8372]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:26 smsl-n100 sudo[8380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 29 22:06:26 smsl-n100 sudo[8380]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:26 smsl-n100 sudo[8380]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:26 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 22:06:26 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 22:06:26 smsl-n100 volumio[1000]: info: Starting Shairport Sync Apr 29 22:06:26 smsl-n100 sudo[8382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 29 22:06:26 smsl-n100 sudo[8382]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:26 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Apr 29 22:06:26 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 29 22:06:26 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Apr 29 22:06:26 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Apr 29 22:06:26 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 22:06:26 smsl-n100 systemd[1]: Started RAAT DAEMON. Apr 29 22:06:26 smsl-n100 sudo[8385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 29 22:06:26 smsl-n100 sudo[8385]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:26 smsl-n100 sudo[8382]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:26 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 22:06:26 smsl-n100 volumio[1000]: info: Raat Daemon started successfully Apr 29 22:06:26 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 29 22:06:26 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 22:06:26 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Apr 29 22:06:26 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 29 22:06:26 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 29 22:06:26 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 22:06:26 smsl-n100 sudo[8385]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:26 smsl-n100 volumio[1000]: info: Executing endpoint restartRAATSocket Apr 29 22:06:26 smsl-n100 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 29 22:06:26 smsl-n100 volumio[1000]: info: Shairport-Sync Started Apr 29 22:06:26 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:26 smsl-n100 volumio[1000]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 29 22:06:27 smsl-n100 volumio[1000]: info: TidalConnect service started! Apr 29 22:06:27 smsl-n100 dhcpcd[999]: wlan0: leased 192.168.10.15 for 172800 seconds Apr 29 22:06:27 smsl-n100 dhcpcd[999]: wlan0: adding route to 192.168.10.0/24 Apr 29 22:06:27 smsl-n100 dhcpcd[999]: wlan0: adding default route via 192.168.10.1 Apr 29 22:06:28 smsl-n100 avahi-daemon[567]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.10.15. Apr 29 22:06:28 smsl-n100 avahi-daemon[567]: New relevant interface wlan0.IPv4 for mDNS. Apr 29 22:06:28 smsl-n100 avahi-daemon[567]: Registering new address record for 192.168.10.15 on wlan0.IPv4. Apr 29 22:06:28 smsl-n100 ntpd[997]: ntpd exiting on signal 15 (Terminated) Apr 29 22:06:28 smsl-n100 systemd[1]: Stopping Network Time Service... Apr 29 22:06:28 smsl-n100 systemd[1]: ntp.service: Succeeded. Apr 29 22:06:28 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 22:06:28 smsl-n100 systemd[1]: Stopped Network Time Service. Apr 29 22:06:28 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 29 22:06:28 smsl-n100 systemd[1]: Starting Network Time Service... Apr 29 22:06:28 smsl-n100 volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 29 22:06:28 smsl-n100 volumio[1000]: info: TidalConnect service stoped! Apr 29 22:06:28 smsl-n100 volumio[1000]: info: TidalConnect service stoped! Apr 29 22:06:28 smsl-n100 ntpd[8452]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 29 22:06:28 smsl-n100 ntpd[8452]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 29 22:06:28 smsl-n100 systemd[1]: Started Network Time Service. Apr 29 22:06:28 smsl-n100 ntpd[8459]: proto: precision = 1.166 usec (-20) Apr 29 22:06:28 smsl-n100 volumio[1000]: info: MRS: Found cast device: WiiM-Pro-Receiver-279773352883945c0742c6703e415a5a Apr 29 22:06:28 smsl-n100 ntpd[8459]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 29 22:06:28 smsl-n100 ntpd[8459]: 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 22:06:28 smsl-n100 ntpd[8459]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 854 days ago Apr 29 22:06:28 smsl-n100 ntpd[8459]: Listen and drop on 0 v6wildcard [::]:123 Apr 29 22:06:28 smsl-n100 ntpd[8459]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 29 22:06:28 smsl-n100 ntpd[8459]: Listen normally on 2 lo 127.0.0.1:123 Apr 29 22:06:28 smsl-n100 ntpd[8459]: Listen normally on 3 wlan0 192.168.10.15:123 Apr 29 22:06:28 smsl-n100 ntpd[8459]: Listening on routing socket on fd #20 for interface updates Apr 29 22:06:28 smsl-n100 ntpd[8459]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 29 22:06:28 smsl-n100 ntpd[8459]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 29 22:06:28 smsl-n100 volumio[1000]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 29 22:06:28 smsl-n100 volumio[1000]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 29 22:06:28 smsl-n100 sudo[8469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 29 22:06:28 smsl-n100 sudo[8469]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:28 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Apr 29 22:06:28 smsl-n100 sudo[8469]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:29 smsl-n100 volumio[1000]: info: Discovery: adding 1308b783-00b6-4850-b453-1fe94ceae9f3 Apr 29 22:06:29 smsl-n100 volumio[1000]: info: Discovery: Found device SMSL N100 Apr 29 22:06:29 smsl-n100 volumio[1000]: info: CoreCommandRouter::volumioGetState Apr 29 22:06:29 smsl-n100 volumio[1000]: info: CorePlayQueue::getTrack 0 Apr 29 22:06:29 smsl-n100 volumio[1000]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 22:06:29 smsl-n100 volumio[1000]: Error: The value NaN is not a number Apr 29 22:06:29 smsl-n100 volumio[1000]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Apr 29 22:06:29 smsl-n100 volumio[1000]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Apr 29 22:06:29 smsl-n100 volumio[1000]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Apr 29 22:06:29 smsl-n100 volumio[1000]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Apr 29 22:06:29 smsl-n100 volumio[1000]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Apr 29 22:06:29 smsl-n100 volumio[1000]: at Browser.emit (events.js:400:28) Apr 29 22:06:29 smsl-n100 volumio[1000]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Apr 29 22:06:29 smsl-n100 volumio[1000]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Apr 29 22:06:29 smsl-n100 volumio[1000]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Apr 29 22:06:29 smsl-n100 volumio[1000]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Apr 29 22:06:29 smsl-n100 volumio[1000]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Apr 29 22:06:29 smsl-n100 volumio[1000]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Apr 29 22:06:29 smsl-n100 volumio[1000]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Apr 29 22:06:29 smsl-n100 volumio[1000]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 22:06:29 smsl-n100 ntpd[8459]: Soliciting pool server 176.123.165.98 Apr 29 22:06:29 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 29 22:06:29 smsl-n100 systemd[1]: mpd.service: Failed with result 'protocol'. Apr 29 22:06:29 smsl-n100 systemd[1]: Failed to start Music Player Daemon. Apr 29 22:06:29 smsl-n100 sudo[8345]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:30 smsl-n100 systemd[1]: Starting Music Player Daemon... Apr 29 22:06:30 smsl-n100 sudo[8485]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 29 22:06:30 smsl-n100 sudo[8485]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 22:06:30 smsl-n100 sudo[8485]: pam_unix(sudo:session): session closed for user root Apr 29 22:06:30 smsl-n100 ntpd[8459]: Soliciting pool server 185.217.199.21 Apr 29 22:06:30 smsl-n100 ntpd[8459]: Soliciting pool server 217.69.139.133 Apr 29 22:06:30 smsl-n100 sudo[8489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-29 22:05 Apr 29 22:06:30 smsl-n100 sudo[8489]: 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"