-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Sun 2025-05-25 17:36:13 +07. -- May 25 17:35:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 17:35:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 17:35:05 smsl-n100 volumio[4659]: info: Discovery: Getting this device information May 25 17:35:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetState May 25 17:35:05 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:35:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 17:35:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 17:35:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 17:35:05 smsl-n100 volumio[4659]: info: Discovery: Getting this device information May 25 17:35:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetState May 25 17:35:05 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:35:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 17:35:05 smsl-n100 volumio[4659]: verbose: New Socket.io Connection to 192.168.1.21:3000 from 192.168.1.22 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 May 25 17:35:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetState May 25 17:35:05 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:35:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 17:35:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 17:35:08 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetState May 25 17:35:08 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:35:09 smsl-n100 sudo[22749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 17:35:09 smsl-n100 sudo[22749]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 17:35:09 smsl-n100 sudo[22747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 17:35:09 smsl-n100 sudo[22747]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 17:35:09 smsl-n100 sudo[22747]: pam_unix(sudo:session): session closed for user root May 25 17:35:09 smsl-n100 sudo[22749]: pam_unix(sudo:session): session closed for user root May 25 17:35:10 smsl-n100 volumio[4659]: verbose: New Socket.io Connection to 192.168.1.21 from 192.168.1.22 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 May 25 17:35:10 smsl-n100 sudo[22755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 17:35:10 smsl-n100 sudo[22755]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 17:35:10 smsl-n100 sudo[22755]: pam_unix(sudo:session): session closed for user root May 25 17:35:10 smsl-n100 sudo[22758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 17:35:10 smsl-n100 sudo[22758]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 17:35:10 smsl-n100 sudo[22758]: pam_unix(sudo:session): session closed for user root May 25 17:35:10 smsl-n100 volumio[4659]: verbose: New Socket.io Connection to 192.168.1.21 from 192.168.1.22 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 May 25 17:35:10 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 17:35:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetVisibleSources May 25 17:35:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 17:35:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetState May 25 17:35:11 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:35:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 25 17:35:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetQueue May 25 17:35:11 smsl-n100 volumio[4659]: info: CoreStateMachine::getQueue May 25 17:35:11 smsl-n100 volumio[4659]: info: CorePlayQueue::getQueue May 25 17:35:11 smsl-n100 volumio[4659]: info: Listing playlists May 25 17:35:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 25 17:35:11 smsl-n100 volumio[4659]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 25 17:35:11 smsl-n100 volumio[4659]: info: Received Get System Info May 25 17:35:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 17:35:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 17:35:11 smsl-n100 volumio[4659]: info: Discovery: Getting this device information May 25 17:35:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetState May 25 17:35:11 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:35:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 17:35:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetState May 25 17:35:11 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:35:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 17:35:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 17:35:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 25 17:35:12 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 17:35:12 smsl-n100 volumio[4659]: info: Received Get System Info May 25 17:35:12 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 17:35:12 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 17:35:12 smsl-n100 volumio[4659]: info: Discovery: Getting this device information May 25 17:35:12 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetState May 25 17:35:12 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:35:12 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 17:35:12 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 25 17:35:12 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 25 17:35:15 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 17:35:15 smsl-n100 volumio[4659]: info: Received Get System Info May 25 17:35:15 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 17:35:15 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 17:35:15 smsl-n100 volumio[4659]: info: Discovery: Getting this device information May 25 17:35:15 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetState May 25 17:35:15 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:35:15 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 17:35:16 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 17:35:16 smsl-n100 volumio[4659]: info: Received Get System Info May 25 17:35:16 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 17:35:16 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 17:35:16 smsl-n100 volumio[4659]: info: Discovery: Getting this device information May 25 17:35:16 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetState May 25 17:35:16 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:35:16 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 17:35:20 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 25 17:35:29 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 17:35:29 smsl-n100 volumio[4659]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 25 17:35:29 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 25 17:35:29 smsl-n100 volumio[4659]: info: Received Get System Version May 25 17:35:29 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 17:35:29 smsl-n100 volumio[4659]: info: Received Get System Info May 25 17:35:29 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 17:35:29 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 17:35:29 smsl-n100 volumio[4659]: info: Discovery: Getting this device information May 25 17:35:29 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetState May 25 17:35:29 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:35:29 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 17:35:30 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 17:35:30 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 17:35:30 smsl-n100 volumio[4659]: info: Discovery: Getting this device information May 25 17:35:30 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetState May 25 17:35:30 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:35:30 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 17:35:51 smsl-n100 kernel: usb usb2-port1: disabled by hub (EMI?), re-enabling... May 25 17:35:51 smsl-n100 kernel: usb 2-1: USB disconnect, device number 2 May 25 17:35:51 smsl-n100 kernel: usb 2-1.1: USB disconnect, device number 3 May 25 17:35:51 smsl-n100 thd[574]: Error reading device '/dev/input/event1' May 25 17:35:51 smsl-n100 kernel: usb 2-1.3: USB disconnect, device number 4 May 25 17:35:51 smsl-n100 dhcpcd[908]: wlan0: removing interface May 25 17:35:51 smsl-n100 kernel: usb 2-1.4: USB disconnect, device number 5 May 25 17:35:52 smsl-n100 systemd[1]: Stopping ifup for wlan0... May 25 17:35:52 smsl-n100 systemd[1]: Starting Load/Save RF Kill Switch Status... May 25 17:35:52 smsl-n100 systemd[1]: Started Load/Save RF Kill Switch Status. May 25 17:35:52 smsl-n100 volumio[4659]: info: May 25 17:35:52 smsl-n100 volumio[4659]: ---------------------------- USB Audio Device Detached May 25 17:35:52 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach May 25 17:35:52 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 25 17:35:52 smsl-n100 systemd[1]: ifup@wlan0.service: Succeeded. May 25 17:35:52 smsl-n100 systemd[1]: Stopped ifup for wlan0. May 25 17:35:52 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: raat , onStop May 25 17:35:54 smsl-n100 kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache May 25 17:35:54 smsl-n100 volumio[4659]: info: Partition removed: {"syspath":"/sys/devices/platform/ff5c0000.usb/usb2/2-1/2-1.4/2-1.4:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-Seagate_BarracudaFastSSD_00000000NABF05PS-0:0-part1 /dev/disk/by-label/Seagate /dev/disk/by-path/platform-ff5c0000.usb-usb-0:1.4:1.0-scsi-0:0:0:0-part1 /dev/disk/by-partuuid/292597f0-01 /dev/disk/by-uuid/B061-8101","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/ff5c0000.usb/usb2/2-1/2-1.4/2-1.4:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","ID_BUS":"usb","ID_FS_LABEL":"Seagate","ID_FS_LABEL_ENC":"Seagate","ID_FS_TYPE":"exfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"B061-8101","ID_FS_UUID_ENC":"B061-8101","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"BarracudaFastSSD","ID_MODEL_ENC":"BarracudaFastSSD","ID_MODEL_ID":"aa16","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"1953519616","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"292597f0-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"292597f0","ID_PATH":"platform-ff5c0000.usb-usb-0:1.4:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-ff5c0000_usb-usb-0_1_4_1_0-scsi-0_0_0_0","ID_REVISION":"0002","ID_SERIAL":"Seagate_BarracudaFastSSD_00000000NABF05PS-0:0","ID_SERIAL_SHORT":"00000000NABF05PS","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Seagate","ID_VENDOR_ENC":"Seagate\\x20","ID_VENDOR_ID":"0bc2","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2344","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"955318970"} May 25 17:35:54 smsl-n100 kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=0x00 May 25 17:35:54 smsl-n100 sudo[23015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 May 25 17:35:54 smsl-n100 sudo[23015]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 17:35:54 smsl-n100 sudo[23015]: pam_unix(sudo:session): session closed for user root May 25 17:35:54 smsl-n100 volumio[4659]: umount: /media/Seagate: target is busy. May 25 17:35:54 smsl-n100 volumio[4659]: error: Failed to umount Seagate: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sda1" May 25 17:35:54 smsl-n100 volumio[4659]: umount: /media/Seagate: target is busy. May 25 17:35:54 smsl-n100 mount.exfat[4761]: non-zero reference counter (1) for '04 - I'm Just a Country Boy - Don Williams.wav' May 25 17:35:54 smsl-n100 mount.exfat[4761]: failed to write super block May 25 17:35:54 smsl-n100 kernel: usb 2-1: new high-speed USB device number 6 using ehci-platform May 25 17:35:54 smsl-n100 kernel: usb 2-1: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.11 May 25 17:35:54 smsl-n100 kernel: usb 2-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 May 25 17:35:54 smsl-n100 kernel: usb 2-1: Product: USB 2.0 Hub May 25 17:35:54 smsl-n100 kernel: hub 2-1:1.0: USB hub found May 25 17:35:54 smsl-n100 kernel: hub 2-1:1.0: 4 ports detected May 25 17:35:55 smsl-n100 kernel: usb 2-1.1: new high-speed USB device number 7 using ehci-platform May 25 17:35:55 smsl-n100 kernel: usb 2-1.1: New USB device found, idVendor=2622, idProduct=0041, bcdDevice= 6.64 May 25 17:35:55 smsl-n100 kernel: usb 2-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 May 25 17:35:55 smsl-n100 kernel: usb 2-1.1: Product: AUDIOLAB USB Audio 2.0 May 25 17:35:55 smsl-n100 kernel: usb 2-1.1: Manufacturer: AUDIOLAB May 25 17:35:55 smsl-n100 kernel: usb 2-1.1: 1:3 : unsupported format bits 0x100000000 May 25 17:35:55 smsl-n100 kernel: input: AUDIOLAB AUDIOLAB USB Audio 2.0 as /devices/platform/ff5c0000.usb/usb2/2-1/2-1.1/2-1.1:1.3/0003:2622:0041.0002/input/input2 May 25 17:35:55 smsl-n100 kernel: hid-generic 0003:2622:0041.0002: input,hidraw0: USB HID v1.10 Device [AUDIOLAB AUDIOLAB USB Audio 2.0] on usb-ff5c0000.usb-1.1/input3 May 25 17:35:55 smsl-n100 volumio[4659]: info: May 25 17:35:55 smsl-n100 volumio[4659]: ---------------------------- USB Audio Device Attached May 25 17:35:55 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach May 25 17:35:55 smsl-n100 systemd-udevd[22952]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 25 17:35:55 smsl-n100 kernel: usb 2-1.3: new high-speed USB device number 8 using ehci-platform May 25 17:35:55 smsl-n100 volumio[4659]: info: CoreCommandRouter::Close All Modals sent May 25 17:35:55 smsl-n100 volumio[4659]: info: Preparing to save Alsa Options, stopping services first May 25 17:35:55 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetState May 25 17:35:55 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:35:55 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioPause May 25 17:35:55 smsl-n100 volumio[4659]: info: CoreStateMachine::pause May 25 17:35:55 smsl-n100 volumio[4659]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"AUDIOLAB USB Audio 2.0","alsacard":"A20"},"i2s":false} May 25 17:35:55 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 25 17:35:56 smsl-n100 kernel: usb 2-1.3: New USB device found, idVendor=0bda, idProduct=c811, bcdDevice= 2.00 May 25 17:35:56 smsl-n100 kernel: usb 2-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 25 17:35:56 smsl-n100 kernel: usb 2-1.3: Product: 802.11ac NIC May 25 17:35:56 smsl-n100 kernel: usb 2-1.3: Manufacturer: Realtek May 25 17:35:56 smsl-n100 kernel: usb 2-1.3: SerialNumber: 123456 May 25 17:35:56 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 25 17:35:56 smsl-n100 volumio[4659]: info: Setting mixer AUDIOLAB USB Audio 2.0 Output Playback Swit for card AUDIOLAB USB Audio 2.0 May 25 17:35:56 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 25 17:35:56 smsl-n100 volumio[4659]: info: Updating Volume Controller Parameters: Device: 5 Name: AUDIOLAB USB Audio 2.0 Mixer: AUDIOLAB USB Audio 2.0 Output Playback Swit Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 25 17:35:56 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 25 17:35:56 smsl-n100 volumio[4659]: info: Disabling external Volume Control May 25 17:35:56 smsl-n100 volumio[4659]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 17:35:56 smsl-n100 volumio[4659]: info: Preparing to generate the ALSA configuration file May 25 17:35:56 smsl-n100 systemd-udevd[22952]: Using default interface naming scheme 'v240'. May 25 17:35:56 smsl-n100 systemd-udevd[22952]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 25 17:35:56 smsl-n100 kernel: usb 2-1.4: new high-speed USB device number 9 using ehci-platform May 25 17:35:56 smsl-n100 volumio[4659]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 25 17:35:56 smsl-n100 volumio[4659]: info: Reading ALSA contributions from plugins. May 25 17:35:56 smsl-n100 volumio[4659]: Unable to parse: May 25 17:35:56 smsl-n100 volumio[4659]: Simple mixer control 'AUDIOLAB USB Audio 2.0 Output Playback Swit',0 May 25 17:35:56 smsl-n100 volumio[4659]: Capabilities: pswitch May 25 17:35:56 smsl-n100 volumio[4659]: Playback channels: Front Left - Front Right May 25 17:35:56 smsl-n100 volumio[4659]: Mono: May 25 17:35:56 smsl-n100 volumio[4659]: Front Left: Playback [on] May 25 17:35:56 smsl-n100 volumio[4659]: Front Right: Playback [on] May 25 17:35:56 smsl-n100 volumio[4659]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 25 17:35:56 smsl-n100 kernel: usb 2-1.4: New USB device found, idVendor=0bc2, idProduct=aa16, bcdDevice= 1.00 May 25 17:35:56 smsl-n100 kernel: usb 2-1.4: New USB device strings: Mfr=2, Product=3, SerialNumber=1 May 25 17:35:56 smsl-n100 kernel: usb 2-1.4: Product: BarracudaFastSSD May 25 17:35:56 smsl-n100 kernel: usb 2-1.4: Manufacturer: Seagate May 25 17:35:56 smsl-n100 kernel: usb 2-1.4: SerialNumber: 00000000NABF05PS May 25 17:35:56 smsl-n100 kernel: scsi host1: uas May 25 17:35:56 smsl-n100 kernel: scsi 1:0:0:0: Direct-Access Seagate BarracudaFastSSD 0002 PQ: 0 ANSI: 6 May 25 17:35:56 smsl-n100 kernel: sd 1:0:0:0: [sdb] 1953525167 512-byte logical blocks: (1.00 TB/932 GiB) May 25 17:35:56 smsl-n100 kernel: sd 1:0:0:0: [sdb] Write Protect is off May 25 17:35:56 smsl-n100 kernel: sd 1:0:0:0: [sdb] Mode Sense: 4f 00 00 00 May 25 17:35:56 smsl-n100 kernel: sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA May 25 17:35:56 smsl-n100 kernel: sd 1:0:0:0: [sdb] Optimal transfer size 33553920 bytes May 25 17:35:56 smsl-n100 kernel: sdb: sdb1 May 25 17:35:56 smsl-n100 kernel: sd 1:0:0:0: [sdb] Attached SCSI disk May 25 17:35:56 smsl-n100 systemd[1]: Found device 802.11ac_NIC. May 25 17:35:56 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat May 25 17:35:56 smsl-n100 systemd[1]: Started ifup for wlan0. May 25 17:35:57 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 25 17:35:57 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 17:35:57 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 25 17:35:57 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 17:35:57 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 17:35:57 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 17:35:57 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 17:35:57 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 17:35:57 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 17:35:57 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 17:35:57 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 25 17:35:57 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 25 17:35:57 smsl-n100 volumio[4659]: Unable to parse: May 25 17:35:57 smsl-n100 volumio[4659]: Simple mixer control 'AUDIOLAB USB Audio 2.0 Output Playback Swit',0 May 25 17:35:57 smsl-n100 volumio[4659]: Capabilities: pswitch May 25 17:35:57 smsl-n100 volumio[4659]: Playback channels: Front Left - Front Right May 25 17:35:57 smsl-n100 volumio[4659]: Mono: May 25 17:35:57 smsl-n100 volumio[4659]: Front Left: Playback [on] May 25 17:35:57 smsl-n100 volumio[4659]: Front Right: Playback [on] May 25 17:35:57 smsl-n100 volumio[4659]: info: VolumeController:: Volume=undefined Mute =false May 25 17:35:57 smsl-n100 volumio[4659]: info: CoreStateMachine::pushState May 25 17:35:57 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:35:57 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 17:35:57 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioPushState May 25 17:35:57 smsl-n100 volumio[4659]: info: MRS: Pushing multiroomSync output update for this device May 25 17:35:57 smsl-n100 volumio[4659]: info: MRS: Pushing multiroomSync output May 25 17:35:57 smsl-n100 volumio[4659]: info: Mounting Device Seagate May 25 17:35:57 smsl-n100 volumio[4659]: /bin/mkdir: cannot create directory ‘/mnt/USB/Seagate’: File exists May 25 17:35:57 smsl-n100 volumio[4659]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/Seagate" May 25 17:35:57 smsl-n100 volumio[4659]: /bin/mkdir: cannot create directory ‘/mnt/USB/Seagate’: File exists May 25 17:35:57 smsl-n100 dhcpcd[908]: wlan0: waiting for carrier May 25 17:35:57 smsl-n100 dhcpcd[908]: wlan0: carrier acquired May 25 17:35:57 smsl-n100 sudo[23084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/Seagate -o noatime May 25 17:35:57 smsl-n100 sudo[23084]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 17:35:57 smsl-n100 dhcpcd[908]: wlan0: IAID 2b:ee:c7:c9 May 25 17:35:57 smsl-n100 dhcpcd[908]: wlan0: adding address fe80::2af5:2bff:feee:c7c9 May 25 17:35:57 smsl-n100 dhcpcd[908]: ipv6_addaddr1: Permission denied May 25 17:35:57 smsl-n100 dhcpcd[908]: wlan0: soliciting a DHCP lease May 25 17:35:58 smsl-n100 dhcpcd[908]: wlan0: soliciting an IPv6 router May 25 17:35:58 smsl-n100 mount.exfat[23109]: volume was not unmounted cleanly May 25 17:35:58 smsl-n100 sudo[23084]: pam_unix(sudo:session): session closed for user root May 25 17:35:58 smsl-n100 volumio[4659]: WARN: volume was not unmounted cleanly. May 25 17:35:58 smsl-n100 volumio[4659]: fuse: bad mount point `/mnt/USB/Seagate': Transport endpoint is not connected May 25 17:35:58 smsl-n100 volumio[4659]: error: Failed to mount Seagate: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/Seagate" -o noatime May 25 17:35:58 smsl-n100 volumio[4659]: WARN: volume was not unmounted cleanly. May 25 17:35:58 smsl-n100 volumio[4659]: fuse: bad mount point `/mnt/USB/Seagate': Transport endpoint is not connected May 25 17:35:58 smsl-n100 volumio[4659]: info: Asound.conf file unchanged, so no further update is needed May 25 17:35:58 smsl-n100 volumio[4659]: info: Output device has changed, restarting MPD May 25 17:35:58 smsl-n100 sh[23070]: wlan0=wlan0 May 25 17:35:58 smsl-n100 sudo[23130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 17:35:58 smsl-n100 sudo[23130]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 17:35:58 smsl-n100 sudo[23130]: pam_unix(sudo:session): session closed for user root May 25 17:35:58 smsl-n100 volumio[4659]: info: Output device has changed, restarting Shairport Sync May 25 17:35:58 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 17:35:58 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 17:35:58 smsl-n100 sudo[23133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 17:35:58 smsl-n100 sudo[23133]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 17:35:58 smsl-n100 systemd[1]: Stopping Music Player Daemon... May 25 17:35:58 smsl-n100 volumio[4659]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 17:35:58 smsl-n100 volumio[4659]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 25 17:35:58 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 17:35:58 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 17:35:58 smsl-n100 volumio[4659]: info: MPD Permissions set May 25 17:35:58 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 17:35:58 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 17:35:58 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 17:35:58 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 17:35:58 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 17:35:58 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 17:35:58 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 17:35:58 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 17:35:58 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 17:35:58 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 25 17:35:58 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 17:35:58 smsl-n100 volumio[4659]: info: Starting Shairport Sync May 25 17:35:58 smsl-n100 sudo[23163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 17:35:58 smsl-n100 sudo[23163]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 17:35:58 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 25 17:35:58 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. May 25 17:35:58 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 25 17:35:58 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 25 17:35:58 smsl-n100 sudo[23163]: pam_unix(sudo:session): session closed for user root May 25 17:35:58 smsl-n100 volumio[4659]: info: Shairport-Sync Started May 25 17:35:59 smsl-n100 volumio[4659]: error: Upnp client error: Error: This socket has been ended by the other party May 25 17:35:59 smsl-n100 systemd[1]: mpd.service: Succeeded. May 25 17:35:59 smsl-n100 systemd[1]: Stopped Music Player Daemon. May 25 17:36:00 smsl-n100 systemd[1]: Starting Music Player Daemon... May 25 17:36:00 smsl-n100 sudo[23172]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 17:36:00 smsl-n100 sudo[23172]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 17:36:00 smsl-n100 sudo[23172]: pam_unix(sudo:session): session closed for user root May 25 17:36:01 smsl-n100 systemd[1]: systemd-rfkill.service: Succeeded. May 25 17:36:02 smsl-n100 kernel: usb 5-1: new SuperSpeed Gen 1 USB device number 2 using xhci-hcd May 25 17:36:02 smsl-n100 kernel: usb 5-1: New USB device found, idVendor=2109, idProduct=0815, bcdDevice= 7.04 May 25 17:36:02 smsl-n100 kernel: usb 5-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 25 17:36:02 smsl-n100 kernel: usb 5-1: Product: USB3.0 Hub May 25 17:36:02 smsl-n100 kernel: usb 5-1: Manufacturer: VIA Labs, Inc. May 25 17:36:02 smsl-n100 kernel: hub 5-1:1.0: USB hub found May 25 17:36:02 smsl-n100 kernel: hub 5-1:1.0: 4 ports detected May 25 17:36:02 smsl-n100 kernel: usb 4-1: new high-speed USB device number 2 using xhci-hcd May 25 17:36:02 smsl-n100 kernel: usb 4-1: New USB device found, idVendor=2109, idProduct=2815, bcdDevice= 7.04 May 25 17:36:02 smsl-n100 kernel: usb 4-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 25 17:36:02 smsl-n100 kernel: usb 4-1: Product: USB2.0 Hub May 25 17:36:02 smsl-n100 kernel: usb 4-1: Manufacturer: VIA Labs, Inc. May 25 17:36:02 smsl-n100 kernel: hub 4-1:1.0: USB hub found May 25 17:36:02 smsl-n100 kernel: hub 4-1:1.0: 4 ports detected May 25 17:36:02 smsl-n100 kernel: usb 5-1.1: new SuperSpeed Gen 1 USB device number 3 using xhci-hcd May 25 17:36:02 smsl-n100 kernel: usb 5-1.1: New USB device found, idVendor=1058, idProduct=107c, bcdDevice=10.65 May 25 17:36:02 smsl-n100 kernel: usb 5-1.1: New USB device strings: Mfr=2, Product=3, SerialNumber=1 May 25 17:36:02 smsl-n100 kernel: usb 5-1.1: Product: Elements 107C May 25 17:36:02 smsl-n100 kernel: usb 5-1.1: Manufacturer: Western Digital May 25 17:36:02 smsl-n100 kernel: usb 5-1.1: SerialNumber: 574343344532564E39453450 May 25 17:36:02 smsl-n100 kernel: usb-storage 5-1.1:1.0: USB Mass Storage device detected May 25 17:36:02 smsl-n100 kernel: scsi host2: usb-storage 5-1.1:1.0 May 25 17:36:02 smsl-n100 dhcpcd[908]: wlan0: probing for an IPv4LL address May 25 17:36:03 smsl-n100 mpd[23174]: May 25 17:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 17:36:04 smsl-n100 kernel: scsi 2:0:0:0: Direct-Access WD Elements 107C 1065 PQ: 0 ANSI: 6 May 25 17:36:04 smsl-n100 kernel: sd 2:0:0:0: [sdc] Spinning up disk... May 25 17:36:04 smsl-n100 systemd[1]: Started Music Player Daemon. May 25 17:36:04 smsl-n100 sudo[23133]: pam_unix(sudo:session): session closed for user root May 25 17:36:04 smsl-n100 volumio[4659]: info: Scanning removed location : "USB/Seagate" May 25 17:36:04 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 25 17:36:04 smsl-n100 volumio[4659]: info: CoreStateMachine::getcurrentVolume May 25 17:36:04 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioRetrievevolume May 25 17:36:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 17:36:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 17:36:05 smsl-n100 volumio[4659]: info: Discovery: Getting this device information May 25 17:36:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetState May 25 17:36:05 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:36:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 17:36:05 smsl-n100 volumio[4659]: Unable to parse: May 25 17:36:05 smsl-n100 volumio[4659]: Simple mixer control 'AUDIOLAB USB Audio 2.0 Output Playback Swit',0 May 25 17:36:05 smsl-n100 volumio[4659]: Capabilities: pswitch May 25 17:36:05 smsl-n100 volumio[4659]: Playback channels: Front Left - Front Right May 25 17:36:05 smsl-n100 volumio[4659]: Mono: May 25 17:36:05 smsl-n100 volumio[4659]: Front Left: Playback [on] May 25 17:36:05 smsl-n100 volumio[4659]: Front Right: Playback [on] May 25 17:36:05 smsl-n100 volumio[4659]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 25 17:36:05 smsl-n100 volumio[4659]: Unable to parse: May 25 17:36:05 smsl-n100 volumio[4659]: Simple mixer control 'AUDIOLAB USB Audio 2.0 Output Playback Swit',0 May 25 17:36:05 smsl-n100 volumio[4659]: Capabilities: pswitch May 25 17:36:05 smsl-n100 volumio[4659]: Playback channels: Front Left - Front Right May 25 17:36:05 smsl-n100 volumio[4659]: Mono: May 25 17:36:05 smsl-n100 volumio[4659]: Front Left: Playback [on] May 25 17:36:05 smsl-n100 volumio[4659]: Front Right: Playback [on] May 25 17:36:05 smsl-n100 volumio[4659]: info: VolumeController:: Volume=undefined Mute =false May 25 17:36:05 smsl-n100 volumio[4659]: info: CoreStateMachine::pushState May 25 17:36:05 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:36:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioPushState May 25 17:36:05 smsl-n100 volumio[4659]: info: MRS: Pushing multiroomSync output update for this device May 25 17:36:05 smsl-n100 volumio[4659]: info: MRS: Pushing multiroomSync output May 25 17:36:05 smsl-n100 volumio[4659]: info: CoreStateMachine::updateTrackBlock May 25 17:36:05 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrackBlock May 25 17:36:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioRetrievevolume May 25 17:36:05 smsl-n100 volumio[4659]: Unable to parse: May 25 17:36:05 smsl-n100 volumio[4659]: Simple mixer control 'AUDIOLAB USB Audio 2.0 Output Playback Swit',0 May 25 17:36:05 smsl-n100 volumio[4659]: Capabilities: pswitch May 25 17:36:05 smsl-n100 volumio[4659]: Playback channels: Front Left - Front Right May 25 17:36:05 smsl-n100 volumio[4659]: Mono: May 25 17:36:05 smsl-n100 volumio[4659]: Front Left: Playback [on] May 25 17:36:05 smsl-n100 volumio[4659]: Front Right: Playback [on] May 25 17:36:05 smsl-n100 volumio[4659]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 25 17:36:05 smsl-n100 volumio[4659]: Unable to parse: May 25 17:36:05 smsl-n100 volumio[4659]: Simple mixer control 'AUDIOLAB USB Audio 2.0 Output Playback Swit',0 May 25 17:36:05 smsl-n100 volumio[4659]: Capabilities: pswitch May 25 17:36:05 smsl-n100 volumio[4659]: Playback channels: Front Left - Front Right May 25 17:36:05 smsl-n100 volumio[4659]: Mono: May 25 17:36:05 smsl-n100 volumio[4659]: Front Left: Playback [on] May 25 17:36:05 smsl-n100 volumio[4659]: Front Right: Playback [on] May 25 17:36:05 smsl-n100 volumio[4659]: info: VolumeController:: Volume=undefined Mute =false May 25 17:36:05 smsl-n100 volumio[4659]: info: CoreStateMachine::pushState May 25 17:36:05 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:36:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioPushState May 25 17:36:05 smsl-n100 volumio[4659]: info: MRS: Pushing multiroomSync output update for this device May 25 17:36:05 smsl-n100 volumio[4659]: info: MRS: Pushing multiroomSync output May 25 17:36:05 smsl-n100 volumio[4659]: Upnp client error: Error: This socket has been ended by the other party May 25 17:36:05 smsl-n100 volumio[4659]: error: updateQueue error: null May 25 17:36:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 17:36:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 17:36:05 smsl-n100 volumio[4659]: info: Discovery: Getting this device information May 25 17:36:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetState May 25 17:36:05 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:36:05 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 17:36:06 smsl-n100 volumio[4659]: info: MPD Database updated - AlbumList cache refreshed May 25 17:36:06 smsl-n100 volumio[4659]: info: May 25 17:36:06 smsl-n100 volumio[4659]: ---------------------------- MPD announces state update: database May 25 17:36:06 smsl-n100 volumio[4659]: verbose: ControllerMpd::sendMpdCommand status May 25 17:36:06 smsl-n100 volumio[4659]: info: May 25 17:36:06 smsl-n100 volumio[4659]: ---------------------------- MPD announces state update: update May 25 17:36:06 smsl-n100 volumio[4659]: info: ControllerMpd::getState May 25 17:36:06 smsl-n100 volumio[4659]: verbose: ControllerMpd::sendMpdCommand status May 25 17:36:06 smsl-n100 volumio[4659]: info: ControllerMpd::getState May 25 17:36:06 smsl-n100 volumio[4659]: verbose: ControllerMpd::sendMpdCommand status May 25 17:36:07 smsl-n100 dhcpcd[908]: wlan0: using IPv4LL address 169.254.45.78 May 25 17:36:07 smsl-n100 dhcpcd[908]: wlan0: adding route to 169.254.0.0/16 May 25 17:36:07 smsl-n100 avahi-daemon[555]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.45.78. May 25 17:36:07 smsl-n100 avahi-daemon[555]: New relevant interface wlan0.IPv4 for mDNS. May 25 17:36:07 smsl-n100 avahi-daemon[555]: Registering new address record for 169.254.45.78 on wlan0.IPv4. May 25 17:36:09 smsl-n100 volumio[4659]: info: Reporting MCU Network Status: 3 May 25 17:36:09 smsl-n100 volumio[4659]: info: Volumio Network Manager: Network status updated: 3 May 25 17:36:09 smsl-n100 ntpd[906]: Listen normally on 4 wlan0 169.254.45.78:123 May 25 17:36:09 smsl-n100 ntpd[906]: new interface(s) found: waking up resolver May 25 17:36:10 smsl-n100 volumio[4659]: Cannot compose Albumart path May 25 17:36:10 smsl-n100 volumio[4659]: Cannot compose Albumart path May 25 17:36:10 smsl-n100 volumio[4659]: Cannot compose Albumart path May 25 17:36:11 smsl-n100 volumio[4659]: info: sendMpdCommand status took 5397 milliseconds May 25 17:36:11 smsl-n100 volumio[4659]: info: sendMpdCommand status took 5394 milliseconds May 25 17:36:11 smsl-n100 volumio[4659]: info: sendMpdCommand status took 5393 milliseconds May 25 17:36:11 smsl-n100 volumio[4659]: verbose: ControllerMpd::parseState May 25 17:36:11 smsl-n100 volumio[4659]: info: Command Router : Notfying DB Updatefalse May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::Close All Modals sent May 25 17:36:11 smsl-n100 volumio[4659]: verbose: ControllerMpd::parseState May 25 17:36:11 smsl-n100 volumio[4659]: verbose: ControllerMpd::parseState May 25 17:36:11 smsl-n100 volumio[4659]: info: ControllerMpd::pushState May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::servicePushState May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreStateMachine::pushState May 25 17:36:11 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioPushState May 25 17:36:11 smsl-n100 volumio[4659]: info: MRS: Pushing multiroomSync output update for this device May 25 17:36:11 smsl-n100 volumio[4659]: info: MRS: Pushing multiroomSync output May 25 17:36:11 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:36:11 smsl-n100 volumio[4659]: 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} May 25 17:36:11 smsl-n100 volumio[4659]: verbose: CURRENT POSITION 3 May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreStateMachine::syncState stateService stop May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreStateMachine::syncState currentStatus pause May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreStateMachine::pushState May 25 17:36:11 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioPushState May 25 17:36:11 smsl-n100 volumio[4659]: info: MRS: Pushing multiroomSync output update for this device May 25 17:36:11 smsl-n100 volumio[4659]: info: MRS: Pushing multiroomSync output May 25 17:36:11 smsl-n100 volumio[4659]: info: ControllerMpd::pushState May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::servicePushState May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreStateMachine::pushState May 25 17:36:11 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioPushState May 25 17:36:11 smsl-n100 volumio[4659]: info: MRS: Pushing multiroomSync output update for this device May 25 17:36:11 smsl-n100 volumio[4659]: info: MRS: Pushing multiroomSync output May 25 17:36:11 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:36:11 smsl-n100 volumio[4659]: 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} May 25 17:36:11 smsl-n100 volumio[4659]: verbose: CURRENT POSITION 3 May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreStateMachine::syncState stateService stop May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreStateMachine::syncState currentStatus pause May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreStateMachine::pushState May 25 17:36:11 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioPushState May 25 17:36:11 smsl-n100 volumio[4659]: info: MRS: Pushing multiroomSync output update for this device May 25 17:36:11 smsl-n100 volumio[4659]: info: MRS: Pushing multiroomSync output May 25 17:36:11 smsl-n100 volumio[4659]: info: ------------------------------ 5641ms May 25 17:36:11 smsl-n100 volumio[4659]: info: ------------------------------ 5640ms May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreStateMachine::pushState May 25 17:36:11 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioPushState May 25 17:36:11 smsl-n100 volumio[4659]: info: MRS: Pushing multiroomSync output update for this device May 25 17:36:11 smsl-n100 volumio[4659]: info: MRS: Pushing multiroomSync output May 25 17:36:11 smsl-n100 volumio[4659]: error: Serial API: Failed to decode command: 100, message: undefined May 25 17:36:11 smsl-n100 volumio[4659]: error: Serial API: Failed to decode command: MAXVOL, message: May 25 17:36:11 smsl-n100 volumio[4659]: error: Serial API: Failed to decode command: 100, message: undefined May 25 17:36:11 smsl-n100 volumio[4659]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 25 17:36:11 smsl-n100 volumio[4659]: error: Serial API: Failed to decode command: 3, message: undefined May 25 17:36:11 smsl-n100 volumio[4659]: info: Discovery: this is already registered, fd0ed229-9a9b-4cc9-aea0-5b282fd0433c May 25 17:36:11 smsl-n100 volumio[4659]: info: Discovery: Found device SMSL N100 May 25 17:36:11 smsl-n100 volumio[4659]: info: CoreCommandRouter::volumioGetState May 25 17:36:11 smsl-n100 volumio[4659]: info: CorePlayQueue::getTrack 3 May 25 17:36:11 smsl-n100 volumio[4659]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 17:36:11 smsl-n100 volumio[4659]: Error: The value NaN is not a number May 25 17:36:11 smsl-n100 volumio[4659]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) May 25 17:36:11 smsl-n100 volumio[4659]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) May 25 17:36:11 smsl-n100 volumio[4659]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) May 25 17:36:11 smsl-n100 volumio[4659]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) May 25 17:36:11 smsl-n100 volumio[4659]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) May 25 17:36:11 smsl-n100 volumio[4659]: at Browser.emit (events.js:400:28) May 25 17:36:11 smsl-n100 volumio[4659]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) May 25 17:36:11 smsl-n100 volumio[4659]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) May 25 17:36:11 smsl-n100 volumio[4659]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) May 25 17:36:11 smsl-n100 volumio[4659]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) May 25 17:36:11 smsl-n100 volumio[4659]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 May 25 17:36:11 smsl-n100 volumio[4659]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) May 25 17:36:11 smsl-n100 volumio[4659]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) May 25 17:36:11 smsl-n100 volumio[4659]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 17:36:13 smsl-n100 sudo[23242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 17:35 May 25 17:36:13 smsl-n100 sudo[23242]: 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"