-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Thu 2025-04-24 08:35:18 CST. --
Apr 24 08:34:00 smsl-n100 wireless.js[2018]: trying...
Apr 24 08:34:00 smsl-n100 sudo[2265]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:34:00 smsl-n100 sudo[2265]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:00 smsl-n100 sudo[2265]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:01 smsl-n100 wireless.js[2018]: trying...
Apr 24 08:34:01 smsl-n100 sudo[2268]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:34:01 smsl-n100 sudo[2268]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:01 smsl-n100 sudo[2268]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:02 smsl-n100 wireless.js[2018]: trying...
Apr 24 08:34:02 smsl-n100 sudo[2271]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:34:02 smsl-n100 sudo[2271]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:02 smsl-n100 sudo[2271]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:03 smsl-n100 wireless.js[2018]: trying...
Apr 24 08:34:03 smsl-n100 sudo[2277]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:34:03 smsl-n100 sudo[2277]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:03 smsl-n100 sudo[2277]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:04 smsl-n100 wireless.js[2018]: trying...
Apr 24 08:34:04 smsl-n100 sudo[2280]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:34:04 smsl-n100 sudo[2280]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:04 smsl-n100 sudo[2280]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:05 smsl-n100 wireless.js[2018]: trying...
Apr 24 08:34:05 smsl-n100 sudo[2321]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:34:05 smsl-n100 sudo[2321]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:05 smsl-n100 sudo[2321]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:06 smsl-n100 wireless.js[2018]: Overtime, starting plan B
Apr 24 08:34:06 smsl-n100 wireless.js[2018]: STARTING HOTSPOT
Apr 24 08:34:06 smsl-n100 wpa_supplicant[2134]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
Apr 24 08:34:06 smsl-n100 wpa_supplicant[2134]: wlan0: CTRL-EVENT-TERMINATING
Apr 24 08:34:06 smsl-n100 kernel: usb 2-1.4: USB disconnect, device number 6
Apr 24 08:34:06 smsl-n100 kernel: ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
Apr 24 08:34:06 smsl-n100 kernel: ch341 2-1.4:1.0: device disconnected
Apr 24 08:34:07 smsl-n100 kernel: usb 2-1.4: new high-speed USB device number 7 using ehci-platform
Apr 24 08:34:07 smsl-n100 kernel: usb 2-1.4: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.23
Apr 24 08:34:07 smsl-n100 kernel: usb 2-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=2
Apr 24 08:34:07 smsl-n100 kernel: usb 2-1.4: Product: SMSL USB AUDIO
Apr 24 08:34:07 smsl-n100 kernel: usb 2-1.4: Manufacturer: SMSL
Apr 24 08:34:07 smsl-n100 kernel: usb 2-1.4: 1:3 : unsupported format bits 0x100000000
Apr 24 08:34:07 smsl-n100 kernel: hid-generic 0003:152A:85DD.0002: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-ff5c0000.usb-1.4/input3
Apr 24 08:34:07 smsl-n100 systemd-udevd[2342]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99.
Apr 24 08:34:07 smsl-n100 volumio[995]: info:
Apr 24 08:34:07 smsl-n100 volumio[995]: ---------------------------- USB Audio Device Attached
Apr 24 08:34:07 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach
Apr 24 08:34:09 smsl-n100 sudo[2351]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Apr 24 08:34:09 smsl-n100 sudo[2351]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:09 smsl-n100 sudo[2351]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:09 smsl-n100 sudo[2353]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Apr 24 08:34:09 smsl-n100 sudo[2353]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:09 smsl-n100 sudo[2353]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:09 smsl-n100 avahi-daemon[575]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
Apr 24 08:34:09 smsl-n100 dhcpcd[1030]: wlan0: carrier acquired
Apr 24 08:34:09 smsl-n100 avahi-daemon[575]: New relevant interface wlan0.IPv4 for mDNS.
Apr 24 08:34:09 smsl-n100 avahi-daemon[575]: Registering new address record for 192.168.211.1 on wlan0.IPv4.
Apr 24 08:34:09 smsl-n100 dhcpcd[1030]: wlan0: IAID 2b:ee:ca:06
Apr 24 08:34:09 smsl-n100 systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator...
Apr 24 08:34:09 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:09 smsl-n100 hostapd[2360]: Configuration file: /etc/hostapd/hostapd.conf
Apr 24 08:34:10 smsl-n100 dhcpcd[1030]: wlan0: soliciting a DHCP lease
Apr 24 08:34:10 smsl-n100 dhcpcd[1030]: wlan0: soliciting an IPv6 router
Apr 24 08:34:10 smsl-n100 volumio[995]: info: Discovery: this is already registered,  92a28c52-9a60-4106-82e6-98702e3976f3
Apr 24 08:34:10 smsl-n100 volumio[995]: info: Discovery: Found device SMSL N100
Apr 24 08:34:10 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioGetState
Apr 24 08:34:10 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:10 smsl-n100 hostapd[2360]: Using interface wlan0 with hwaddr 28:f5:2b:ee:ca:06 and ssid "Volumio-A94C0"
Apr 24 08:34:10 smsl-n100 hostapd[2360]: wlan0: interface state UNINITIALIZED->ENABLED
Apr 24 08:34:10 smsl-n100 hostapd[2360]: wlan0: AP-ENABLED
Apr 24 08:34:10 smsl-n100 systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator.
Apr 24 08:34:10 smsl-n100 systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server...
Apr 24 08:34:10 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:10 smsl-n100 dnsmasq[2363]: dnsmasq: syntax check OK.
Apr 24 08:34:10 smsl-n100 iwconfig[2366]: Error for wireless request "Set Power Management" (8B2C) :
Apr 24 08:34:10 smsl-n100 iwconfig[2366]:     SET failed on device wlan0 ; Operation not permitted.
Apr 24 08:34:10 smsl-n100 systemd[1]: wireless.service: Succeeded.
Apr 24 08:34:10 smsl-n100 systemd[1]: Started Wireless Services.
Apr 24 08:34:10 smsl-n100 sudo[2016]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:10 smsl-n100 dnsmasq[2371]: started, version 2.80 cachesize 150
Apr 24 08:34:10 smsl-n100 dnsmasq[2371]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile
Apr 24 08:34:10 smsl-n100 dnsmasq-dhcp[2371]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d
Apr 24 08:34:10 smsl-n100 dnsmasq[2371]: reading /etc/resolv.conf
Apr 24 08:34:10 smsl-n100 dnsmasq[2371]: using nameserver 8.8.8.8#53
Apr 24 08:34:10 smsl-n100 dnsmasq[2371]: using nameserver 192.168.1.1#53
Apr 24 08:34:10 smsl-n100 dnsmasq[2371]: using nameserver 208.67.222.222#53
Apr 24 08:34:10 smsl-n100 dnsmasq[2371]: using nameserver 208.67.220.220#53
Apr 24 08:34:10 smsl-n100 dnsmasq[2371]: read /etc/hosts - 5 addresses
Apr 24 08:34:11 smsl-n100 systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server.
Apr 24 08:34:11 smsl-n100 ntpd[1029]: Listen normally on 6 wlan0 192.168.211.1:123
Apr 24 08:34:11 smsl-n100 ntpd[1029]: new interface(s) found: waking up resolver
Apr 24 08:34:15 smsl-n100 dhcpcd[1030]: wlan0: probing for an IPv4LL address
Apr 24 08:34:15 smsl-n100 volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 24 08:34:15 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Apr 24 08:34:15 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork
Apr 24 08:34:15 smsl-n100 sudo[2383]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0
Apr 24 08:34:15 smsl-n100 sudo[2383]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:15 smsl-n100 sudo[2383]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:15 smsl-n100 sudo[2388]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Apr 24 08:34:15 smsl-n100 sudo[2388]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:15 smsl-n100 sudo[2388]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:15 smsl-n100 sudo[2396]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Apr 24 08:34:15 smsl-n100 sudo[2396]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:15 smsl-n100 sudo[2396]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:15 smsl-n100 sudo[2402]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Apr 24 08:34:15 smsl-n100 sudo[2402]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:15 smsl-n100 sudo[2402]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:15 smsl-n100 sudo[2409]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 24 08:34:15 smsl-n100 sudo[2409]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:15 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Apr 24 08:34:15 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Apr 24 08:34:15 smsl-n100 sudo[2409]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:15 smsl-n100 sudo[2411]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 24 08:34:15 smsl-n100 sudo[2411]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:15 smsl-n100 sudo[2411]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:15 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection
Apr 24 08:34:15 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo
Apr 24 08:34:15 smsl-n100 sudo[2415]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Apr 24 08:34:15 smsl-n100 sudo[2415]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:16 smsl-n100 sudo[2418]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 24 08:34:16 smsl-n100 sudo[2418]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:16 smsl-n100 sudo[2418]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:18 smsl-n100 volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 24 08:34:18 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:18 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Apr 24 08:34:18 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 24 08:34:18 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 24 08:34:18 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:18 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:18 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:18 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:18 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:18 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:18 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:18 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:18 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Apr 24 08:34:18 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Apr 24 08:34:18 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection
Apr 24 08:34:18 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:18 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:20 smsl-n100 volumio-remote-updater[566]: No test mode
Apr 24 08:34:20 smsl-n100 volumio-remote-updater[566]: No alpha test mode
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Apr 24 08:34:20 smsl-n100 dhcpcd[1030]: wlan0: using IPv4LL address 169.254.240.31
Apr 24 08:34:20 smsl-n100 dhcpcd[1030]: wlan0: adding route to 169.254.0.0/16
Apr 24 08:34:20 smsl-n100 avahi-daemon[575]: Registering new address record for 169.254.240.31 on wlan0.IPv4.
Apr 24 08:34:20 smsl-n100 sudo[2415]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:20 smsl-n100 volumio-remote-updater[566]: No test mode
Apr 24 08:34:20 smsl-n100 volumio-remote-updater[566]: No alpha test mode
Apr 24 08:34:20 smsl-n100 volumio[995]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES


Improved translations
 
Fix Multiroom Buffer Sizes
 
Increase results for Kana-Kanji Conversion
 
Fix for streaming interruptions


NEW ADDITIONS


Add network status on the serial API
 ","title":"Update v3.789","updateavailable":true}
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object]
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions
Apr 24 08:34:20 smsl-n100 volumio[995]: info: Preparing to save Alsa Options, stopping services first
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioGetState
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPause
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CoreStateMachine::pause
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CoreStateMachine::stPlaybackTimer
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CoreStateMachine::servicePause
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CoreCommandRouter::servicePause
Apr 24 08:34:20 smsl-n100 volumio[995]: info: ControllerMpd::pause
Apr 24 08:34:20 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand pause
Apr 24 08:34:20 smsl-n100 volumio[995]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"SMSL USB AUDIO"}}
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 24 08:34:20 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:20 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:20 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 24 08:34:21 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:21 smsl-n100 volumio[995]: info: Device 5 does not have any Mixer Control Available
Apr 24 08:34:21 smsl-n100 volumio[995]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes
Apr 24 08:34:21 smsl-n100 vtcs[1870]: [2025-04-24 08:34:21.080] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE
Apr 24 08:34:21 smsl-n100 vtcs[1870]: [2025-04-24 08:34:21.081] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected
Apr 24 08:34:21 smsl-n100 sudo[2463]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Apr 24 08:34:21 smsl-n100 sudo[2463]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:21 smsl-n100 volumio-remote-updater[566]: No test mode
Apr 24 08:34:21 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service...
Apr 24 08:34:21 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM
Apr 24 08:34:21 smsl-n100 volumio-remote-updater[566]: No alpha test mode
Apr 24 08:34:21 smsl-n100 systemd[1]: vtcs.service: Succeeded.
Apr 24 08:34:21 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service.
Apr 24 08:34:21 smsl-n100 sudo[2463]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:21 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Apr 24 08:34:21 smsl-n100 volumio[995]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer:  Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
Apr 24 08:34:21 smsl-n100 volumio[995]: info: Disabling external Volume Control
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:21 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:21 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:21 smsl-n100 volumio[995]: info: Preparing to generate the ALSA configuration file
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart
Apr 24 08:34:21 smsl-n100 sudo[2470]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Apr 24 08:34:21 smsl-n100 sudo[2470]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart
Apr 24 08:34:21 smsl-n100 volumio[995]: info: Refreshing Cached IP Addresses
Apr 24 08:34:21 smsl-n100 sudo[2473]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli
Apr 24 08:34:21 smsl-n100 sudo[2473]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:21 smsl-n100 sudo[2470]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:21 smsl-n100 sudo[2473]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:21 smsl-n100 sudo[2476]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 24 08:34:21 smsl-n100 sudo[2476]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:21 smsl-n100 sudo[2476]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:21 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:21 smsl-n100 sudo[2480]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 24 08:34:21 smsl-n100 sudo[2480]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:21 smsl-n100 sudo[2480]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:21 smsl-n100 volumio[995]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Apr 24 08:34:21 smsl-n100 volumio[995]: info: Reading ALSA contributions from plugins.
Apr 24 08:34:21 smsl-n100 volumio[995]: info:
Apr 24 08:34:21 smsl-n100 volumio[995]: ---------------------------- MPD announces state update: player
Apr 24 08:34:21 smsl-n100 volumio[995]: info: sendMpdCommand pause took 827 milliseconds
Apr 24 08:34:21 smsl-n100 volumio[995]: info: ControllerMpd::getState
Apr 24 08:34:21 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand status
Apr 24 08:34:21 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:21 smsl-n100 volumio[995]: error: Upnp client error: Error: This socket has been ended by the other party
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:21 smsl-n100 volumio[995]: info: Not Reporting Auto name since its the default one
Apr 24 08:34:21 smsl-n100 volumio[995]: info: sendMpdCommand status took 106 milliseconds
Apr 24 08:34:21 smsl-n100 volumio[995]: verbose: ControllerMpd::parseState
Apr 24 08:34:21 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 24 08:34:21 smsl-n100 volumio[995]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES


Improved translations
 
Fix Multiroom Buffer Sizes
 
Increase results for Kana-Kanji Conversion
 
Fix for streaming interruptions


NEW ADDITIONS


Add network status on the serial API
 ","title":"Update v3.789","updateavailable":true}
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 24 08:34:21 smsl-n100 volumio[995]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES


Improved translations
 
Fix Multiroom Buffer Sizes
 
Increase results for Kana-Kanji Conversion
 
Fix for streaming interruptions


NEW ADDITIONS


Add network status on the serial API
 ","title":"Update v3.789","updateavailable":true}
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 24 08:34:21 smsl-n100 volumio[995]: info: sendMpdCommand playlistinfo took 103 milliseconds
Apr 24 08:34:21 smsl-n100 volumio[995]: verbose: ControllerMpd::parseTrackInfo
Apr 24 08:34:21 smsl-n100 volumio[995]: info: ControllerMpd::pushState
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::servicePushState
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:21 smsl-n100 volumio[995]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37088,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/KINGSTON/1_正弦波.wav","trackType":"wav"}
Apr 24 08:34:21 smsl-n100 volumio[995]: verbose: CURRENT POSITION 1
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   stateService pause
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   currentStatus pause
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:21 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:21 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:21 smsl-n100 volumio[995]: info: CoreStateMachine::stPlaybackTimer
Apr 24 08:34:21 smsl-n100 volumio[995]: info: ------------------------------ 275ms
Apr 24 08:34:22 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 24 08:34:22 smsl-n100 ntpd[1029]: Listen normally on 7 wlan0 169.254.240.31:123
Apr 24 08:34:22 smsl-n100 ntpd[1029]: new interface(s) found: waking up resolver
Apr 24 08:34:22 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:22 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:22 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:22 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: 100, message: undefined
Apr 24 08:34:22 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: MAXVOL, message:
Apr 24 08:34:22 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: 100, message: undefined
Apr 24 08:34:22 smsl-n100 volumio[995]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Apr 24 08:34:22 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable
Apr 24 08:34:22 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:22 smsl-n100 volumio[995]: info: VolumeController::SetAlsaVolume0
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:22 smsl-n100 sudo[2493]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service
Apr 24 08:34:22 smsl-n100 sudo[2493]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:22 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:22 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:22 smsl-n100 sudo[2493]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:22 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:22 smsl-n100 sudo[2495]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service
Apr 24 08:34:22 smsl-n100 sudo[2495]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:22 smsl-n100 systemd[1]: Stopping RAAT DAEMON...
Apr 24 08:34:22 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM
Apr 24 08:34:22 smsl-n100 systemd[1]: raat-daemon.service: Succeeded.
Apr 24 08:34:22 smsl-n100 systemd[1]: Stopped RAAT DAEMON.
Apr 24 08:34:22 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:22 smsl-n100 volumio[995]: info: Asound.conf file written
Apr 24 08:34:22 smsl-n100 systemd[1]: Started RAAT DAEMON.
Apr 24 08:34:22 smsl-n100 sudo[2495]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:22 smsl-n100 sudo[2501]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Apr 24 08:34:22 smsl-n100 sudo[2501]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:22 smsl-n100 sudo[2501]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:22 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:22 smsl-n100 volumio[995]: No state is present for card Out
Apr 24 08:34:22 smsl-n100 volumio[995]: Found hardware: "I2S_Out" "" "" "" ""
Apr 24 08:34:22 smsl-n100 volumio[995]: Hardware is initialized using a generic method
Apr 24 08:34:22 smsl-n100 volumio[995]: No state is present for card Out
Apr 24 08:34:22 smsl-n100 volumio[995]: No state is present for card SPDIF
Apr 24 08:34:22 smsl-n100 volumio[995]: Found hardware: "SPDIF" "" "" "" ""
Apr 24 08:34:22 smsl-n100 volumio[995]: Hardware is initialized using a generic method
Apr 24 08:34:22 smsl-n100 volumio[995]: No state is present for card SPDIF
Apr 24 08:34:22 smsl-n100 volumio[995]: No state is present for card AUDIO
Apr 24 08:34:22 smsl-n100 volumio[995]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:85dd" "" ""
Apr 24 08:34:22 smsl-n100 volumio[995]: Hardware is initialized using a generic method
Apr 24 08:34:22 smsl-n100 volumio[995]: No state is present for card AUDIO
Apr 24 08:34:22 smsl-n100 volumio[995]: info: Output device has changed, restarting MPD
Apr 24 08:34:22 smsl-n100 sudo[2516]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 24 08:34:22 smsl-n100 sudo[2516]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:22 smsl-n100 volumio[995]: info: Output device has changed, restarting Shairport Sync
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 24 08:34:22 smsl-n100 sudo[2516]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:22 smsl-n100 sudo[2519]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 24 08:34:22 smsl-n100 sudo[2519]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:22 smsl-n100 systemd[1]: Stopping Music Player Daemon...
Apr 24 08:34:22 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:22 smsl-n100 volumio[995]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 24 08:34:22 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:22 smsl-n100 volumio[995]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Apr 24 08:34:22 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:22 smsl-n100 systemd[1]: mpd.service: Succeeded.
Apr 24 08:34:22 smsl-n100 systemd[1]: Stopped Music Player Daemon.
Apr 24 08:34:22 smsl-n100 systemd[1]: Starting Music Player Daemon...
Apr 24 08:34:23 smsl-n100 volumio[995]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: Output device has changed, restarting MPD
Apr 24 08:34:23 smsl-n100 sudo[2528]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Apr 24 08:34:23 smsl-n100 sudo[2528]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:23 smsl-n100 sudo[2531]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 24 08:34:23 smsl-n100 sudo[2531]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:23 smsl-n100 volumio[995]: info: Output device has changed, restarting Shairport Sync
Apr 24 08:34:23 smsl-n100 sudo[2531]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 24 08:34:23 smsl-n100 sudo[2528]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:23 smsl-n100 sudo[2524]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Apr 24 08:34:23 smsl-n100 sudo[2524]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:23 smsl-n100 sudo[2524]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:23 smsl-n100 sudo[2534]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 24 08:34:23 smsl-n100 sudo[2534]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:23 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Apr 24 08:34:23 smsl-n100 systemd[1]: mpd.service: Succeeded.
Apr 24 08:34:23 smsl-n100 systemd[1]: Stopped Music Player Daemon.
Apr 24 08:34:23 smsl-n100 systemd[1]: Starting Music Player Daemon...
Apr 24 08:34:23 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:23 smsl-n100 volumio[995]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 24 08:34:23 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:23 smsl-n100 volumio[995]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 sudo[2546]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Apr 24 08:34:23 smsl-n100 sudo[2546]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:23 smsl-n100 sudo[2542]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Apr 24 08:34:23 smsl-n100 sudo[2542]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:23 smsl-n100 sudo[2548]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Apr 24 08:34:23 smsl-n100 sudo[2548]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:23 smsl-n100 sudo[2542]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:23 smsl-n100 sudo[2546]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:23 smsl-n100 sudo[2553]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Apr 24 08:34:23 smsl-n100 sudo[2553]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:23 smsl-n100 sudo[2548]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: Not Reporting Auto name since its the default one
Apr 24 08:34:23 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:34:23 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:23 smsl-n100 sudo[2553]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:23 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:34:23 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:23 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:34:23 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:23 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:34:23 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:23 smsl-n100 volumio[995]: info: MPD Permissions set
Apr 24 08:34:23 smsl-n100 volumio[995]: info: MPD Permissions set
Apr 24 08:34:23 smsl-n100 volumio[995]: info: Raat Daemon started successfully
Apr 24 08:34:23 smsl-n100 volumio[995]: info: Executing endpoint restartRAATSocket
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 24 08:34:23 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable
Apr 24 08:34:24 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:24 smsl-n100 sudo[2579]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service
Apr 24 08:34:24 smsl-n100 sudo[2579]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 sudo[2579]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:24 smsl-n100 volumio[995]: info: Not Reporting Auto name since its the default one
Apr 24 08:34:24 smsl-n100 sudo[2585]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service
Apr 24 08:34:24 smsl-n100 sudo[2585]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:24 smsl-n100 volumio[995]: info: TidalConnect service stoped!
Apr 24 08:34:24 smsl-n100 systemd[1]: Stopping RAAT DAEMON...
Apr 24 08:34:24 smsl-n100 volumio[995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Apr 24 08:34:24 smsl-n100 volumio[995]: info: Starting Shairport Sync
Apr 24 08:34:24 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM
Apr 24 08:34:24 smsl-n100 systemd[1]: raat-daemon.service: Succeeded.
Apr 24 08:34:24 smsl-n100 systemd[1]: Stopped RAAT DAEMON.
Apr 24 08:34:24 smsl-n100 systemd[1]: Started RAAT DAEMON.
Apr 24 08:34:24 smsl-n100 volumio[995]: info: Starting Shairport Sync
Apr 24 08:34:24 smsl-n100 sudo[2585]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:24 smsl-n100 sudo[2589]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 24 08:34:24 smsl-n100 sudo[2589]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:24 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:24 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:34:24 smsl-n100 sudo[2595]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 24 08:34:24 smsl-n100 sudo[2595]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:24 smsl-n100 volumio[995]: info: Preparing to generate the ALSA configuration file
Apr 24 08:34:24 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 24 08:34:24 smsl-n100 systemd[1]: shairport-sync.service: Succeeded.
Apr 24 08:34:24 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 24 08:34:24 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 24 08:34:24 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable
Apr 24 08:34:24 smsl-n100 sudo[2589]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:24 smsl-n100 sudo[2595]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:24 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:25 smsl-n100 volumio[995]: info: Raat Daemon started successfully
Apr 24 08:34:25 smsl-n100 volumio[995]: info: TidalConnect service stoped!
Apr 24 08:34:25 smsl-n100 sudo[2609]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service
Apr 24 08:34:25 smsl-n100 sudo[2609]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:25 smsl-n100 volumio[995]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Apr 24 08:34:25 smsl-n100 sudo[2609]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:25 smsl-n100 volumio[995]: info: Reading ALSA contributions from plugins.
Apr 24 08:34:25 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:34:25 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:25 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:34:25 smsl-n100 sudo[2615]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service
Apr 24 08:34:25 smsl-n100 sudo[2615]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:25 smsl-n100 volumio[995]: info: Shairport-Sync Started
Apr 24 08:34:25 smsl-n100 volumio[995]: info: Shairport-Sync Started
Apr 24 08:34:25 smsl-n100 volumio[995]: info: Executing endpoint restartRAATSocket
Apr 24 08:34:25 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection
Apr 24 08:34:25 smsl-n100 systemd[1]: Stopping RAAT DAEMON...
Apr 24 08:34:25 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM
Apr 24 08:34:25 smsl-n100 systemd[1]: raat-daemon.service: Succeeded.
Apr 24 08:34:25 smsl-n100 systemd[1]: Stopped RAAT DAEMON.
Apr 24 08:34:25 smsl-n100 systemd[1]: Started RAAT DAEMON.
Apr 24 08:34:25 smsl-n100 sudo[2615]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:25 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Apr 24 08:34:25 smsl-n100 volumio[995]: info: CoreStateMachine::getcurrentVolume
Apr 24 08:34:25 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioRetrievevolume
Apr 24 08:34:25 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:25 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:25 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:25 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:25 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:25 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:25 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:34:25 smsl-n100 volumio[995]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Apr 24 08:34:25 smsl-n100 volumio[995]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect
Apr 24 08:34:25 smsl-n100 volumio[995]: info: Raat Daemon started successfully
Apr 24 08:34:25 smsl-n100 volumio[995]: info: RAAT: Requesting Headphone Status
Apr 24 08:34:25 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus
Apr 24 08:34:25 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 24 08:34:25 smsl-n100 volumio[995]: info: CURURI: music-library
Apr 24 08:34:25 smsl-n100 volumio[995]: error: MPD error: Error: This socket has been ended by the other party
Apr 24 08:34:25 smsl-n100 volumio[995]: error: This socket has been ended by the other party {"code":"EPIPE"}
Apr 24 08:34:25 smsl-n100 volumio[995]: error: MPD error: Error: This socket has been ended by the other party
Apr 24 08:34:25 smsl-n100 sudo[2632]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Apr 24 08:34:25 smsl-n100 volumio[995]: error: This socket has been ended by the other party {"code":"EPIPE"}
Apr 24 08:34:25 smsl-n100 volumio[995]: error: MPD error: Error: This socket has been ended by the other party
Apr 24 08:34:25 smsl-n100 volumio[995]: error: This socket has been ended by the other party {"code":"EPIPE"}
Apr 24 08:34:25 smsl-n100 sudo[2632]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:25 smsl-n100 volumio[995]: info: Executing endpoint restartRAATSocket
Apr 24 08:34:25 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection
Apr 24 08:34:25 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:25 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service.
Apr 24 08:34:25 smsl-n100 volumio[995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Apr 24 08:34:25 smsl-n100 sudo[2632]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:25 smsl-n100 volumio[995]: info: Asound.conf file written
Apr 24 08:34:25 smsl-n100 sudo[2639]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Apr 24 08:34:25 smsl-n100 sudo[2639]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:25 smsl-n100 sudo[2639]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:26 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:26 smsl-n100 volumio[995]: No state is present for card Out
Apr 24 08:34:26 smsl-n100 volumio[995]: Found hardware: "I2S_Out" "" "" "" ""
Apr 24 08:34:26 smsl-n100 volumio[995]: Hardware is initialized using a generic method
Apr 24 08:34:26 smsl-n100 volumio[995]: No state is present for card Out
Apr 24 08:34:26 smsl-n100 volumio[995]: No state is present for card SPDIF
Apr 24 08:34:26 smsl-n100 volumio[995]: Found hardware: "SPDIF" "" "" "" ""
Apr 24 08:34:26 smsl-n100 volumio[995]: Hardware is initialized using a generic method
Apr 24 08:34:26 smsl-n100 volumio[995]: No state is present for card SPDIF
Apr 24 08:34:26 smsl-n100 volumio[995]: No state is present for card AUDIO
Apr 24 08:34:26 smsl-n100 volumio[995]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:85dd" "" ""
Apr 24 08:34:26 smsl-n100 volumio[995]: Hardware is initialized using a generic method
Apr 24 08:34:26 smsl-n100 volumio[995]: No state is present for card AUDIO
Apr 24 08:34:26 smsl-n100 volumio[995]: info: Output device has changed, restarting MPD
Apr 24 08:34:26 smsl-n100 volumio[995]: info: Output device has changed, restarting Shairport Sync
Apr 24 08:34:26 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:26 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 24 08:34:26 smsl-n100 sudo[2644]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 24 08:34:26 smsl-n100 sudo[2644]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:26 smsl-n100 sudo[2646]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 24 08:34:26 smsl-n100 sudo[2644]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:26 smsl-n100 sudo[2646]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:26 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Apr 24 08:34:26 smsl-n100 systemd[1]: mpd.service: Succeeded.
Apr 24 08:34:26 smsl-n100 systemd[1]: Stopped Music Player Daemon.
Apr 24 08:34:26 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:26 smsl-n100 volumio[995]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 24 08:34:26 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:26 smsl-n100 volumio[995]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Apr 24 08:34:26 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:26 smsl-n100 systemd[1]: Starting Music Player Daemon...
Apr 24 08:34:26 smsl-n100 volumio[995]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes
Apr 24 08:34:26 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:26 smsl-n100 volumio[995]: info: Preparing to generate the ALSA configuration file
Apr 24 08:34:26 smsl-n100 sudo[2656]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Apr 24 08:34:26 smsl-n100 sudo[2656]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:26 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service...
Apr 24 08:34:26 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:34:26 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:26 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:34:26 smsl-n100 sudo[2653]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Apr 24 08:34:26 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:26 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM
Apr 24 08:34:26 smsl-n100 systemd[1]: vtcs.service: Succeeded.
Apr 24 08:34:26 smsl-n100 sudo[2653]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:26 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service.
Apr 24 08:34:26 smsl-n100 sudo[2668]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Apr 24 08:34:26 smsl-n100 sudo[2668]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:26 smsl-n100 sudo[2653]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:26 smsl-n100 volumio[995]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Apr 24 08:34:26 smsl-n100 volumio[995]: info: Reading ALSA contributions from plugins.
Apr 24 08:34:26 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:26 smsl-n100 sudo[2656]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:26 smsl-n100 volumio[995]: info: MPD Permissions set
Apr 24 08:34:26 smsl-n100 volumio[995]: info: TidalConnect service stoped!
Apr 24 08:34:26 smsl-n100 sudo[2668]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:26 smsl-n100 volumio[995]: info: TidalConnect service stoped!
Apr 24 08:34:26 smsl-n100 volumio[995]: info: TidalConnect service stoped!
Apr 24 08:34:26 smsl-n100 volumio[995]: info: TidalConnect service stoped!
Apr 24 08:34:27 smsl-n100 volumio[995]: info: Executing endpoint tc_getconfig
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: Not Reporting Auto name since its the default one
Apr 24 08:34:27 smsl-n100 volumio[995]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Apr 24 08:34:27 smsl-n100 volumio[995]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect
Apr 24 08:34:27 smsl-n100 sudo[2697]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Apr 24 08:34:27 smsl-n100 sudo[2697]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service.
Apr 24 08:34:27 smsl-n100 sudo[2697]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:27 smsl-n100 volumio[995]: info: RAAT: Requesting Headphone Status
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus
Apr 24 08:34:27 smsl-n100 volumio[995]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Apr 24 08:34:27 smsl-n100 volumio[995]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable
Apr 24 08:34:27 smsl-n100 sudo[2713]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Apr 24 08:34:27 smsl-n100 sudo[2713]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:27 smsl-n100 sudo[2713]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:27 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:27 smsl-n100 volumio[995]: info: Starting Shairport Sync
Apr 24 08:34:27 smsl-n100 sudo[2719]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service
Apr 24 08:34:27 smsl-n100 sudo[2719]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:34:27 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:27 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:27 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:27 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: 100, message: undefined
Apr 24 08:34:27 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: MAXVOL, message:
Apr 24 08:34:27 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: 100, message: undefined
Apr 24 08:34:27 smsl-n100 sudo[2719]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:27 smsl-n100 volumio[995]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Apr 24 08:34:27 smsl-n100 sudo[2721]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 24 08:34:27 smsl-n100 sudo[2721]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:27 smsl-n100 sudo[2723]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service
Apr 24 08:34:27 smsl-n100 sudo[2723]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:27 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 24 08:34:28 smsl-n100 systemd[1]: shairport-sync.service: Succeeded.
Apr 24 08:34:28 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 24 08:34:28 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:28 smsl-n100 volumio[995]: info: RAAT: Requesting Headphone Status
Apr 24 08:34:28 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus
Apr 24 08:34:28 smsl-n100 volumio[995]: info: Asound.conf file unchanged, so no further update is needed
Apr 24 08:34:28 smsl-n100 volumio[995]: info: Output device has changed, restarting MPD
Apr 24 08:34:28 smsl-n100 systemd[1]: Stopping RAAT DAEMON...
Apr 24 08:34:28 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 24 08:34:28 smsl-n100 sudo[2721]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:28 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM
Apr 24 08:34:28 smsl-n100 systemd[1]: raat-daemon.service: Succeeded.
Apr 24 08:34:28 smsl-n100 systemd[1]: Stopped RAAT DAEMON.
Apr 24 08:34:28 smsl-n100 systemd[1]: Started RAAT DAEMON.
Apr 24 08:34:28 smsl-n100 volumio[995]: info: Output device has changed, restarting Shairport Sync
Apr 24 08:34:28 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:28 smsl-n100 sudo[2723]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:28 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 24 08:34:28 smsl-n100 sudo[2729]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 24 08:34:28 smsl-n100 sudo[2729]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:28 smsl-n100 sudo[2729]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:28 smsl-n100 sudo[2733]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 24 08:34:28 smsl-n100 sudo[2733]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:28 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:28 smsl-n100 volumio[995]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 24 08:34:28 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:28 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Apr 24 08:34:28 smsl-n100 systemd[1]: mpd.service: Succeeded.
Apr 24 08:34:28 smsl-n100 systemd[1]: Stopped Music Player Daemon.
Apr 24 08:34:28 smsl-n100 volumio[995]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Apr 24 08:34:28 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:28 smsl-n100 systemd[1]: Starting Music Player Daemon...
Apr 24 08:34:28 smsl-n100 volumio[995]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes
Apr 24 08:34:28 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:28 smsl-n100 sudo[2753]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Apr 24 08:34:28 smsl-n100 sudo[2753]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:28 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:34:28 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:28 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:34:28 smsl-n100 sudo[2755]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Apr 24 08:34:28 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:34:28 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service...
Apr 24 08:34:28 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:28 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM
Apr 24 08:34:28 smsl-n100 systemd[1]: vtcs.service: Succeeded.
Apr 24 08:34:28 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:34:28 smsl-n100 sudo[2755]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:28 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service.
Apr 24 08:34:28 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:28 smsl-n100 sudo[2749]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Apr 24 08:34:28 smsl-n100 sudo[2749]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:28 smsl-n100 volumio[995]: info: Executing endpoint tc_getconfig
Apr 24 08:34:28 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Apr 24 08:34:28 smsl-n100 sudo[2753]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:28 smsl-n100 sudo[2749]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:28 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:28 smsl-n100 volumio[995]: info: VolumeController::SetAlsaVolume0
Apr 24 08:34:28 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:28 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:28 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:34:28 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:28 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:28 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:28 smsl-n100 sudo[2755]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:28 smsl-n100 volumio[995]: info: MPD Permissions set
Apr 24 08:34:28 smsl-n100 volumio[995]: info: Raat Daemon started successfully
Apr 24 08:34:28 smsl-n100 volumio[995]: info: Shairport-Sync Started
Apr 24 08:34:28 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:28 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:28 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:34:28 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:28 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:28 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:28 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: 100, message: undefined
Apr 24 08:34:28 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: MAXVOL, message:
Apr 24 08:34:28 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: 100, message: undefined
Apr 24 08:34:28 smsl-n100 volumio[995]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Apr 24 08:34:28 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:28 smsl-n100 volumio[995]: info: Executing endpoint restartRAATSocket
Apr 24 08:34:28 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection
Apr 24 08:34:28 smsl-n100 volumio[995]: info: TidalConnect service started!
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: Not Reporting Auto name since its the default one
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:29 smsl-n100 volumio[995]: info: VolumeController::SetAlsaVolume0
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:29 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:29 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:29 smsl-n100 volumio[995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Apr 24 08:34:29 smsl-n100 volumio[995]: info: Starting Shairport Sync
Apr 24 08:34:29 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable
Apr 24 08:34:29 smsl-n100 sudo[2774]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 24 08:34:29 smsl-n100 sudo[2774]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:29 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 24 08:34:29 smsl-n100 systemd[1]: shairport-sync.service: Succeeded.
Apr 24 08:34:29 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 24 08:34:29 smsl-n100 volumio[995]: xcb_connection_has_error() returned true
Apr 24 08:34:29 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 24 08:34:29 smsl-n100 sudo[2774]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:29 smsl-n100 sudo[2780]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service
Apr 24 08:34:29 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:29 smsl-n100 sudo[2780]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:29 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:34:29 smsl-n100 volumio[995]: info: Shairport-Sync Started
Apr 24 08:34:29 smsl-n100 sudo[2780]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:29 smsl-n100 sudo[2782]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service
Apr 24 08:34:29 smsl-n100 sudo[2782]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:29 smsl-n100 systemd[1]: Stopping RAAT DAEMON...
Apr 24 08:34:29 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM
Apr 24 08:34:29 smsl-n100 systemd[1]: raat-daemon.service: Succeeded.
Apr 24 08:34:29 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:29 smsl-n100 systemd[1]: Stopped RAAT DAEMON.
Apr 24 08:34:29 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:34:29 smsl-n100 systemd[1]: Started RAAT DAEMON.
Apr 24 08:34:29 smsl-n100 sudo[2782]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:29 smsl-n100 volumio[995]: info: Raat Daemon started successfully
Apr 24 08:34:29 smsl-n100 volumio[995]: info: TidalConnect service stoped!
Apr 24 08:34:29 smsl-n100 volumio[995]: info: TidalConnect service stoped!
Apr 24 08:34:30 smsl-n100 volumio[995]: info: Executing endpoint restartRAATSocket
Apr 24 08:34:30 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection
Apr 24 08:34:30 smsl-n100 volumio[995]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Apr 24 08:34:30 smsl-n100 volumio[995]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect
Apr 24 08:34:30 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:30 smsl-n100 volumio[995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Apr 24 08:34:30 smsl-n100 sudo[2806]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Apr 24 08:34:30 smsl-n100 sudo[2806]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:30 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service.
Apr 24 08:34:30 smsl-n100 sudo[2806]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:30 smsl-n100 volumio[995]: info: TidalConnect service started!
Apr 24 08:34:30 smsl-n100 volumio[995]: info: TidalConnect service started!
Apr 24 08:34:30 smsl-n100 volumio[995]: info: Executing endpoint tc_getconfig
Apr 24 08:34:30 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Apr 24 08:34:30 smsl-n100 volumio[995]: info: RAAT: Requesting Headphone Status
Apr 24 08:34:30 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus
Apr 24 08:34:30 smsl-n100 vtcs[2808]: STARTING TidalConnect services, version: 1.4.0.34
Apr 24 08:34:30 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:30 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:30 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:34:30 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:30 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:30 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:30 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: 100, message: undefined
Apr 24 08:34:30 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: MAXVOL, message:
Apr 24 08:34:30 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: 100, message: undefined
Apr 24 08:34:30 smsl-n100 volumio[995]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Apr 24 08:34:30 smsl-n100 vtcs[2808]: STARTED TidalConnect services.
Apr 24 08:34:30 smsl-n100 volumio[995]: info: Executing endpoint tc_connect
Apr 24 08:34:30 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Apr 24 08:34:31 smsl-n100 volumio[995]: info: Connecting to TidalConnect
Apr 24 08:34:31 smsl-n100 volumio[995]: info: VolumeController::SetAlsaVolume0
Apr 24 08:34:31 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:31 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:31 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:34:31 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:31 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:31 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:31 smsl-n100 volumio[995]: info: CoreCommandRouter::servicePushState
Apr 24 08:34:31 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:31 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:31 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:31 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:31 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:31 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:31 smsl-n100 volumio[995]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect
Apr 24 08:34:31 smsl-n100 volumio[995]: info: CoreCommandRouter::servicePushState
Apr 24 08:34:31 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:31 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:31 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:31 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:31 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:31 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:31 smsl-n100 volumio[995]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect
Apr 24 08:34:31 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:31 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:31 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:31 smsl-n100 sudo[2828]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 24 08:34:31 smsl-n100 sudo[2828]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:31 smsl-n100 volumio[995]: info: TidalConnect service stoped!
Apr 24 08:34:31 smsl-n100 sudo[2828]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:31 smsl-n100 sudo[2830]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 24 08:34:31 smsl-n100 sudo[2830]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:31 smsl-n100 sudo[2830]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:31 smsl-n100 volumio[995]: info: TidalConnect service stoped!
Apr 24 08:34:31 smsl-n100 sudo[2834]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Apr 24 08:34:31 smsl-n100 sudo[2834]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:31 smsl-n100 sudo[2834]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:31 smsl-n100 volumio[995]: info: Upmpdcli Daemon Started
Apr 24 08:34:31 smsl-n100 volumio[995]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Apr 24 08:34:31 smsl-n100 volumio[995]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect
Apr 24 08:34:32 smsl-n100 sudo[2845]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Apr 24 08:34:32 smsl-n100 sudo[2845]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:32 smsl-n100 sudo[2845]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:32 smsl-n100 volumio[995]: info: RAAT: Requesting Headphone Status
Apr 24 08:34:32 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus
Apr 24 08:34:32 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:32 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:32 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:34:32 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:32 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:32 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:32 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: 100, message: undefined
Apr 24 08:34:32 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: MAXVOL, message:
Apr 24 08:34:32 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: 100, message: undefined
Apr 24 08:34:32 smsl-n100 volumio[995]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Apr 24 08:34:32 smsl-n100 volumio[995]: info: VolumeController::SetAlsaVolume0
Apr 24 08:34:32 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:32 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:32 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:34:32 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:32 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:32 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:32 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:33 smsl-n100 mpd[2760]: Apr 24 08:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 24 08:34:33 smsl-n100 systemd[1]: Started Music Player Daemon.
Apr 24 08:34:33 smsl-n100 sudo[2733]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:33 smsl-n100 sudo[2646]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:33 smsl-n100 sudo[2519]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:33 smsl-n100 sudo[2534]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:33 smsl-n100 volumio[995]: error: MPD error: The expression evaluated to a falsy value:
Apr 24 08:34:33 smsl-n100 volumio[995]:   assert.ok(self.idling)
Apr 24 08:34:33 smsl-n100 volumio[995]: error: The expression evaluated to a falsy value:
Apr 24 08:34:33 smsl-n100 volumio[995]:   assert.ok(self.idling)
Apr 24 08:34:33 smsl-n100 volumio[995]: error: MPD error: The expression evaluated to a falsy value:
Apr 24 08:34:33 smsl-n100 volumio[995]:   assert.ok(self.idling)
Apr 24 08:34:33 smsl-n100 volumio[995]: error: The expression evaluated to a falsy value:
Apr 24 08:34:33 smsl-n100 volumio[995]:   assert.ok(self.idling)
Apr 24 08:34:33 smsl-n100 volumio[995]: error: MPD error: The expression evaluated to a falsy value:
Apr 24 08:34:33 smsl-n100 volumio[995]:   assert.ok(self.idling)
Apr 24 08:34:33 smsl-n100 volumio[995]: error: The expression evaluated to a falsy value:
Apr 24 08:34:33 smsl-n100 volumio[995]:   assert.ok(self.idling)
Apr 24 08:34:33 smsl-n100 volumio[995]: error: updateQueue error: null
Apr 24 08:34:33 smsl-n100 volumio[995]: info: TidalConnect service started!
Apr 24 08:34:35 smsl-n100 volumio[995]: info: TidalConnect service started!
Apr 24 08:34:35 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 24 08:34:35 smsl-n100 volumio[995]: info: CURURI: music-library
Apr 24 08:34:35 smsl-n100 volumio[995]: info: Preload queue cleared
Apr 24 08:34:36 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 24 08:34:36 smsl-n100 volumio[995]: info: CURURI: music-library/USB
Apr 24 08:34:36 smsl-n100 volumio[995]: info: Preload queue cleared
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CURURI: music-library/USB/PKBACK#_001
Apr 24 08:34:37 smsl-n100 volumio[995]: info: Preload queue cleared
Apr 24 08:34:37 smsl-n100 volumio[995]: info: Preloading song: music-library/USB/PKBACK#_001/1_正弦波.wav
Apr 24 08:34:37 smsl-n100 volumio[995]: info: Exploding uri music-library/USB/PKBACK#_001/1_正弦波.wav in service mpd
Apr 24 08:34:37 smsl-n100 volumio[995]: info: ALBUMART /albumart?cacheid=645&web=%CE%B4%D6%AA%3F%3F%3F%3F%3F%3F/%CE%B4%D6%AA%3F%3F%C6%AC%3F%3F%20(2011%2F6%2F26%2016%3A15%3A04)/extralarge&path=%2Fmnt%2FUSB%2FPKBACK%23_001%2F1_%E6%AD%A3%E5%BC%A6%E6%B3%A2.wav&metadata=false
Apr 24 08:34:37 smsl-n100 volumio[995]: info: URI /mnt/USB/PKBACK#_001/1_正弦波.wav
Apr 24 08:34:37 smsl-n100 volumio[995]: info: Preload queue cleared
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreStateMachine::ClearQueue
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreStateMachine::stop
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreStateMachine::updateTrackBlock
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CorePlayQueue::getTrackBlock
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreStateMachine::stPlaybackTimer
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:37 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:37 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreStateMachine::serviceStop
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 1
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreCommandRouter::serviceStop
Apr 24 08:34:37 smsl-n100 volumio[995]: info: ControllerMpd::stop
Apr 24 08:34:37 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand stop
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CorePlayQueue::clearPlayQueue
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CorePlayQueue::saveQueue
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushQueue
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreStateMachine::addQueueItems
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CorePlayQueue::addQueueItems
Apr 24 08:34:37 smsl-n100 volumio[995]: info: Preload queue cleared
Apr 24 08:34:37 smsl-n100 volumio[995]: info: Adding Item to queue: music-library/USB/PKBACK#_001/1_正弦波.wav
Apr 24 08:34:37 smsl-n100 volumio[995]: info: Using cached record of: music-library/USB/PKBACK#_001/1_正弦波.wav
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushQueue
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CorePlayQueue::saveQueue
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreStateMachine::updateTrackBlock
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CorePlayQueue::getTrackBlock
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPlay
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreStateMachine::play index 0
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreStateMachine::stop
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreStateMachine::play index undefined
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreStateMachine::startPlaybackTimer
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 24 08:34:37 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Apr 24 08:34:37 smsl-n100 volumio[995]: verbose: ControllerMpd::clearAddPlayTracks USB/PKBACK#_001/1_正弦波.wav
Apr 24 08:34:37 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand stop
Apr 24 08:34:37 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:37 smsl-n100 volumio[995]: info: sendMpdCommand stop took 93 milliseconds
Apr 24 08:34:37 smsl-n100 volumio[995]: info: sendMpdCommand stop took 26 milliseconds
Apr 24 08:34:37 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand clear
Apr 24 08:34:37 smsl-n100 volumio[995]: info:
Apr 24 08:34:37 smsl-n100 volumio[995]: ---------------------------- MPD announces system playlist update
Apr 24 08:34:37 smsl-n100 volumio[995]: info: Ignoring MPD Status Update
Apr 24 08:34:37 smsl-n100 volumio[995]: info: sendMpdCommand clear took 6 milliseconds
Apr 24 08:34:37 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand add "USB/PKBACK#_001/1_正弦波.wav"
Apr 24 08:34:37 smsl-n100 volumio[995]: info:
Apr 24 08:34:37 smsl-n100 volumio[995]: ---------------------------- MPD announces system playlist update
Apr 24 08:34:37 smsl-n100 volumio[995]: info: Ignoring MPD Status Update
Apr 24 08:34:37 smsl-n100 volumio[995]: info:
Apr 24 08:34:37 smsl-n100 volumio[995]: ---------------------------- MPD announces system playlist update
Apr 24 08:34:37 smsl-n100 volumio[995]: info: Ignoring MPD Status Update
Apr 24 08:34:37 smsl-n100 volumio[995]: info:
Apr 24 08:34:37 smsl-n100 volumio[995]: ---------------------------- MPD announces system playlist update
Apr 24 08:34:37 smsl-n100 volumio[995]: info: Ignoring MPD Status Update
Apr 24 08:34:37 smsl-n100 volumio[995]: error: updateQueue error: null
Apr 24 08:34:37 smsl-n100 volumio[995]: info:
Apr 24 08:34:37 smsl-n100 volumio[995]: ---------------------------- MPD announces system playlist update
Apr 24 08:34:37 smsl-n100 volumio[995]: info: Ignoring MPD Status Update
Apr 24 08:34:37 smsl-n100 volumio[995]: info: ------------------------------ 25ms
Apr 24 08:34:37 smsl-n100 volumio[995]: info: sendMpdCommand add "USB/PKBACK#_001/1_正弦波.wav" took 22 milliseconds
Apr 24 08:34:37 smsl-n100 volumio[995]: info: ------------------------------ 20ms
Apr 24 08:34:37 smsl-n100 volumio[995]: info: ------------------------------ 17ms
Apr 24 08:34:37 smsl-n100 volumio[995]: info: ------------------------------ 14ms
Apr 24 08:34:37 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand play
Apr 24 08:34:38 smsl-n100 volumio[995]: info:
Apr 24 08:34:38 smsl-n100 volumio[995]: ---------------------------- MPD announces system playlist update
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Ignoring MPD Status Update
Apr 24 08:34:38 smsl-n100 volumio[995]: info:
Apr 24 08:34:38 smsl-n100 volumio[995]: ---------------------------- MPD announces system playlist update
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Ignoring MPD Status Update
Apr 24 08:34:38 smsl-n100 volumio[995]: info:
Apr 24 08:34:38 smsl-n100 volumio[995]: ---------------------------- MPD announces system playlist update
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Ignoring MPD Status Update
Apr 24 08:34:38 smsl-n100 volumio[995]: info:
Apr 24 08:34:38 smsl-n100 volumio[995]: ---------------------------- MPD announces state update: player
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ------------------------------ 33ms
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand play took 24 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ControllerMpd::getState
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand status
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ------------------------------ 25ms
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ------------------------------ 20ms
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ------------------------------ 16ms
Apr 24 08:34:38 smsl-n100 volumio[995]: info:
Apr 24 08:34:38 smsl-n100 volumio[995]: ---------------------------- MPD announces state update: player
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ControllerMpd::getState
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand status
Apr 24 08:34:38 smsl-n100 volumio[995]: info:
Apr 24 08:34:38 smsl-n100 volumio[995]: ---------------------------- MPD announces state update: player
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ControllerMpd::getState
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand status
Apr 24 08:34:38 smsl-n100 volumio[995]: info:
Apr 24 08:34:38 smsl-n100 volumio[995]: ---------------------------- MPD announces state update: player
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ControllerMpd::getState
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand status
Apr 24 08:34:38 smsl-n100 volumio[995]: info:
Apr 24 08:34:38 smsl-n100 volumio[995]: ---------------------------- MPD announces state update: player
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand status took 31 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ControllerMpd::getState
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand status
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand status took 26 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand status took 20 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand status took 14 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::parseState
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::parseState
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::parseState
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::parseState
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 24 08:34:38 smsl-n100 volumio[995]: info:
Apr 24 08:34:38 smsl-n100 volumio[995]: ---------------------------- MPD announces state update: player
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ControllerMpd::getState
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand status
Apr 24 08:34:38 smsl-n100 volumio[995]: info:
Apr 24 08:34:38 smsl-n100 volumio[995]: ---------------------------- MPD announces state update: player
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ControllerMpd::getState
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand status
Apr 24 08:34:38 smsl-n100 volumio[995]: info:
Apr 24 08:34:38 smsl-n100 volumio[995]: ---------------------------- MPD announces state update: player
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ControllerMpd::getState
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand status
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand status took 42 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand playlistinfo took 35 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand playlistinfo took 33 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand playlistinfo took 32 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand playlistinfo took 30 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand status took 22 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand status took 17 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand status took 14 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::parseState
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::parseTrackInfo
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::parseTrackInfo
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::parseTrackInfo
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::parseTrackInfo
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::parseState
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::parseState
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::parseState
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ControllerMpd::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::servicePushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"}
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: CURRENT POSITION 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   stateService play
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   currentStatus stop
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ControllerMpd::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::servicePushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"}
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: CURRENT POSITION 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   stateService play
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   currentStatus play
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Received an update from plugin. extracting info from payload
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ControllerMpd::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::servicePushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"}
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: CURRENT POSITION 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   stateService play
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   currentStatus play
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Received an update from plugin. extracting info from payload
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ControllerMpd::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::servicePushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"}
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: CURRENT POSITION 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   stateService play
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   currentStatus play
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Received an update from plugin. extracting info from payload
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ------------------------------ 210ms
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ------------------------------ 303ms
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ------------------------------ 301ms
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ------------------------------ 296ms
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand playlistinfo took 235 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand playlistinfo took 229 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand playlistinfo took 229 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: info: sendMpdCommand playlistinfo took 228 milliseconds
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::parseTrackInfo
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::parseTrackInfo
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::parseTrackInfo
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: ControllerMpd::parseTrackInfo
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ControllerMpd::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::servicePushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"}
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: CURRENT POSITION 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   stateService play
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   currentStatus play
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Received an update from plugin. extracting info from payload
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ControllerMpd::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::servicePushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"}
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: CURRENT POSITION 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   stateService play
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   currentStatus play
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Received an update from plugin. extracting info from payload
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ControllerMpd::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::servicePushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"}
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: CURRENT POSITION 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   stateService play
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   currentStatus play
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Received an update from plugin. extracting info from payload
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ControllerMpd::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::servicePushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"}
Apr 24 08:34:38 smsl-n100 volumio[995]: verbose: CURRENT POSITION 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   stateService play
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::syncState   currentStatus play
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Received an update from plugin. extracting info from payload
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ------------------------------ 583ms
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ------------------------------ 557ms
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ------------------------------ 553ms
Apr 24 08:34:38 smsl-n100 volumio[995]: info: ------------------------------ 550ms
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:38 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:34:38 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:38 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: 100, message: undefined
Apr 24 08:34:38 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: MAXVOL, message:
Apr 24 08:34:38 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: 100, message: undefined
Apr 24 08:34:38 smsl-n100 volumio[995]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Apr 24 08:34:39 smsl-n100 volumio[995]: info: VolumeController::SetAlsaVolume0
Apr 24 08:34:39 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:34:39 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:34:39 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:34:39 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:34:39 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:34:39 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:34:39 smsl-n100 volumio[995]: info: Updating  RAAT Signal Path
Apr 24 08:34:48 smsl-n100 volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 24 08:34:48 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Apr 24 08:34:48 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork
Apr 24 08:34:48 smsl-n100 sudo[2890]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0
Apr 24 08:34:48 smsl-n100 sudo[2890]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:48 smsl-n100 sudo[2890]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:48 smsl-n100 sudo[2896]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Apr 24 08:34:48 smsl-n100 sudo[2896]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:48 smsl-n100 sudo[2896]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:49 smsl-n100 sudo[2903]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Apr 24 08:34:49 smsl-n100 sudo[2903]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:49 smsl-n100 sudo[2903]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:49 smsl-n100 sudo[2909]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Apr 24 08:34:49 smsl-n100 sudo[2909]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:49 smsl-n100 sudo[2909]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:49 smsl-n100 sudo[2915]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 24 08:34:49 smsl-n100 sudo[2915]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:49 smsl-n100 sudo[2915]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:49 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Apr 24 08:34:49 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Apr 24 08:34:49 smsl-n100 sudo[2919]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 24 08:34:49 smsl-n100 sudo[2919]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:49 smsl-n100 sudo[2919]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:49 smsl-n100 sudo[2922]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Apr 24 08:34:49 smsl-n100 sudo[2922]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:53 smsl-n100 sudo[2922]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:58 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings
Apr 24 08:34:58 smsl-n100 volumio[995]: info: Saving new wireless network
Apr 24 08:34:58 smsl-n100 sudo[2943]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf
Apr 24 08:34:58 smsl-n100 sudo[2943]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:58 smsl-n100 sudo[2943]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:58 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart
Apr 24 08:34:58 smsl-n100 volumio[995]: info: Discovery: Restarting Advertising due to device name change
Apr 24 08:34:58 smsl-n100 sudo[2946]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service
Apr 24 08:34:58 smsl-n100 sudo[2946]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:58 smsl-n100 systemd[1]: Starting Wireless Services...
Apr 24 08:34:58 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:59 smsl-n100 wireless.js[2948]: Cleaning previous...
Apr 24 08:34:59 smsl-n100 volumio[995]: info: Discovery: A device disappeared from network
Apr 24 08:34:59 smsl-n100 volumio[995]: info: Discovery: A device disappeared from network
Apr 24 08:34:59 smsl-n100 systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server...
Apr 24 08:34:59 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:59 smsl-n100 dnsmasq[2371]: exiting on receipt of SIGTERM
Apr 24 08:34:59 smsl-n100 systemd[1]: dnsmasq.service: Succeeded.
Apr 24 08:34:59 smsl-n100 systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server.
Apr 24 08:34:59 smsl-n100 systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator...
Apr 24 08:34:59 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:34:59 smsl-n100 systemd[1]: hostapd.service: Succeeded.
Apr 24 08:34:59 smsl-n100 systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator.
Apr 24 08:34:59 smsl-n100 sudo[2966]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Apr 24 08:34:59 smsl-n100 sudo[2966]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:59 smsl-n100 avahi-daemon[575]: Withdrawing address record for 192.168.211.1 on wlan0.
Apr 24 08:34:59 smsl-n100 avahi-daemon[575]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
Apr 24 08:34:59 smsl-n100 avahi-daemon[575]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.240.31.
Apr 24 08:34:59 smsl-n100 avahi-daemon[575]: Withdrawing address record for 169.254.240.31 on wlan0.
Apr 24 08:34:59 smsl-n100 avahi-daemon[575]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.240.31.
Apr 24 08:34:59 smsl-n100 avahi-daemon[575]: Interface wlan0.IPv4 no longer relevant for mDNS.
Apr 24 08:34:59 smsl-n100 sudo[2966]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:59 smsl-n100 sudo[2968]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Apr 24 08:34:59 smsl-n100 sudo[2968]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:59 smsl-n100 dhcpcd[1030]: wlan0: carrier lost
Apr 24 08:34:59 smsl-n100 sudo[2968]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:59 smsl-n100 dhcpcd[1030]: wlan0: deleting route to 169.254.0.0/16
Apr 24 08:34:59 smsl-n100 wireless.js[2948]: Stopped aP
Apr 24 08:34:59 smsl-n100 sudo[3003]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Apr 24 08:34:59 smsl-n100 sudo[3003]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:59 smsl-n100 sudo[3003]: pam_unix(sudo:session): session closed for user root
Apr 24 08:34:59 smsl-n100 sudo[3014]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get
Apr 24 08:34:59 smsl-n100 sudo[3014]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:34:59 smsl-n100 sudo[3014]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:00 smsl-n100 sudo[3026]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Apr 24 08:35:00 smsl-n100 sudo[3026]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:00 smsl-n100 sudo[3026]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:00 smsl-n100 sudo[3028]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan
Apr 24 08:35:00 smsl-n100 sudo[3028]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:00 smsl-n100 dhcpcd[1030]: wlan0: carrier acquired
Apr 24 08:35:00 smsl-n100 dhcpcd[1030]: wlan0: IAID 2b:ee:ca:06
Apr 24 08:35:00 smsl-n100 dhcpcd[1030]: wlan0: soliciting a DHCP lease
Apr 24 08:35:01 smsl-n100 dhcpcd[1030]: wlan0: soliciting an IPv6 router
Apr 24 08:35:02 smsl-n100 ntpd[1029]: Deleting interface #6 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=51 secs
Apr 24 08:35:02 smsl-n100 ntpd[1029]: Deleting interface #7 wlan0, 169.254.240.31#123, interface stats: received=0, sent=0, dropped=0, active_time=40 secs
Apr 24 08:35:03 smsl-n100 volumio[995]: info: Discovery: Started advertising with name: SMSL N100
Apr 24 08:35:04 smsl-n100 volumio[995]: info: Discovery: adding 92a28c52-9a60-4106-82e6-98702e3976f3
Apr 24 08:35:04 smsl-n100 volumio[995]: info: Discovery: Found device SMSL N100
Apr 24 08:35:04 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioGetState
Apr 24 08:35:04 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:35:04 smsl-n100 sudo[3028]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:04 smsl-n100 wireless.js[2948]: Start wireless flow
Apr 24 08:35:04 smsl-n100 wireless.js[2948]: Stopped hotspot (if there)..
Apr 24 08:35:04 smsl-n100 sudo[3040]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Apr 24 08:35:04 smsl-n100 sudo[3040]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:04 smsl-n100 sudo[3040]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:04 smsl-n100 sudo[3054]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Apr 24 08:35:04 smsl-n100 sudo[3054]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:04 smsl-n100 dhcpcd[1030]: wlan0: carrier lost
Apr 24 08:35:04 smsl-n100 sudo[3054]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:04 smsl-n100 wireless.js[2948]: DHCP IP
Apr 24 08:35:04 smsl-n100 wireless.js[2948]: Start ap
Apr 24 08:35:04 smsl-n100 wpa_supplicant[3072]: Successfully initialized wpa_supplicant
Apr 24 08:35:04 smsl-n100 sudo[3076]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd
Apr 24 08:35:04 smsl-n100 sudo[3076]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:04 smsl-n100 dhcpcd[3095]: sending commands to master dhcpcd process
Apr 24 08:35:04 smsl-n100 sudo[3076]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:04 smsl-n100 dhcpcd[1030]: wlan0: carrier acquired
Apr 24 08:35:04 smsl-n100 dhcpcd[1030]: wlan0: IAID 2b:ee:ca:06
Apr 24 08:35:05 smsl-n100 wireless.js[2948]: trying...
Apr 24 08:35:05 smsl-n100 dhcpcd[1030]: control command: /sbin/dhcpcd
Apr 24 08:35:05 smsl-n100 dhcpcd[1030]: wlan0: carrier lost
Apr 24 08:35:05 smsl-n100 sudo[3115]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:35:05 smsl-n100 sudo[3115]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:05 smsl-n100 sudo[3115]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:06 smsl-n100 wireless.js[2948]: trying...
Apr 24 08:35:06 smsl-n100 sudo[3132]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:35:06 smsl-n100 sudo[3132]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:06 smsl-n100 sudo[3132]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:07 smsl-n100 wireless.js[2948]: trying...
Apr 24 08:35:07 smsl-n100 sudo[3149]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:35:07 smsl-n100 sudo[3149]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:07 smsl-n100 sudo[3149]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:08 smsl-n100 wireless.js[2948]: trying...
Apr 24 08:35:08 smsl-n100 sudo[3152]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:35:08 smsl-n100 sudo[3152]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:08 smsl-n100 sudo[3152]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:09 smsl-n100 wireless.js[2948]: trying...
Apr 24 08:35:09 smsl-n100 sudo[3156]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:35:09 smsl-n100 sudo[3156]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:09 smsl-n100 sudo[3156]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:10 smsl-n100 wpa_supplicant[3098]: wlan0: Trying to associate with f4:2a:7d:d8:0f:b9 (SSID='TP-LINK_0FB7' freq=5765 MHz)
Apr 24 08:35:10 smsl-n100 wpa_supplicant[3098]: wlan0: Associated with f4:2a:7d:d8:0f:b9
Apr 24 08:35:10 smsl-n100 wpa_supplicant[3098]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Apr 24 08:35:10 smsl-n100 wpa_supplicant[3098]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN
Apr 24 08:35:10 smsl-n100 wpa_supplicant[3098]: wlan0: WPA: Key negotiation completed with f4:2a:7d:d8:0f:b9 [PTK=CCMP GTK=CCMP]
Apr 24 08:35:10 smsl-n100 wpa_supplicant[3098]: wlan0: CTRL-EVENT-CONNECTED - Connection to f4:2a:7d:d8:0f:b9 completed [id=0 id_str=]
Apr 24 08:35:10 smsl-n100 dhcpcd[1030]: wlan0: carrier acquired
Apr 24 08:35:10 smsl-n100 dhcpcd[1030]: wlan0: IAID 2b:ee:ca:06
Apr 24 08:35:10 smsl-n100 dhcpcd[1030]: wlan0: soliciting a DHCP lease
Apr 24 08:35:10 smsl-n100 wireless.js[2948]: trying...
Apr 24 08:35:10 smsl-n100 sudo[3162]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:35:10 smsl-n100 sudo[3162]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:10 smsl-n100 sudo[3162]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:11 smsl-n100 dhcpcd[1030]: wlan0: offered 192.168.0.46 from 192.168.0.1
Apr 24 08:35:11 smsl-n100 dhcpcd[1030]: wlan0: probing address 192.168.0.46/24
Apr 24 08:35:11 smsl-n100 dhcpcd[1030]: wlan0: soliciting an IPv6 router
Apr 24 08:35:11 smsl-n100 wireless.js[2948]: trying...
Apr 24 08:35:11 smsl-n100 sudo[3166]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:35:11 smsl-n100 sudo[3166]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:11 smsl-n100 sudo[3166]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:12 smsl-n100 wireless.js[2948]: trying...
Apr 24 08:35:12 smsl-n100 sudo[3169]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:35:12 smsl-n100 sudo[3169]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:12 smsl-n100 sudo[3169]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:13 smsl-n100 wireless.js[2948]: trying...
Apr 24 08:35:13 smsl-n100 sudo[3175]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:35:13 smsl-n100 sudo[3175]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:13 smsl-n100 sudo[3175]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:14 smsl-n100 wireless.js[2948]: trying...
Apr 24 08:35:14 smsl-n100 sudo[3178]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:35:14 smsl-n100 sudo[3178]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:14 smsl-n100 sudo[3178]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:15 smsl-n100 wireless.js[2948]: trying...
Apr 24 08:35:15 smsl-n100 sudo[3181]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:35:15 smsl-n100 sudo[3181]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:15 smsl-n100 sudo[3181]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:16 smsl-n100 dhcpcd[1030]: wlan0: leased 192.168.0.46 for 172800 seconds
Apr 24 08:35:16 smsl-n100 dhcpcd[1030]: wlan0: adding route to 192.168.0.0/24
Apr 24 08:35:16 smsl-n100 dhcpcd[1030]: wlan0: adding default route via 192.168.0.1
Apr 24 08:35:16 smsl-n100 avahi-daemon[575]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.46.
Apr 24 08:35:16 smsl-n100 avahi-daemon[575]: New relevant interface wlan0.IPv4 for mDNS.
Apr 24 08:35:16 smsl-n100 avahi-daemon[575]: Registering new address record for 192.168.0.46 on wlan0.IPv4.
Apr 24 08:35:16 smsl-n100 ntpd[1029]: ntpd exiting on signal 15 (Terminated)
Apr 24 08:35:16 smsl-n100 ntpd[1029]: 193.182.111.14 local addr 192.168.0.45 -> 
Apr 24 08:35:16 smsl-n100 systemd[1]: Stopping Network Time Service...
Apr 24 08:35:16 smsl-n100 ntpd[1029]: 193.182.111.12 local addr 192.168.0.45 -> 
Apr 24 08:35:16 smsl-n100 systemd[1]: ntp.service: Succeeded.
Apr 24 08:35:16 smsl-n100 ntpd[1029]: 193.182.111.143 local addr 192.168.0.45 -> 
Apr 24 08:35:16 smsl-n100 systemd[1]: Stopped Network Time Service.
Apr 24 08:35:16 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:35:16 smsl-n100 ntpd[1029]: 193.182.111.142 local addr 192.168.0.45 -> 
Apr 24 08:35:16 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 24 08:35:16 smsl-n100 ntpd[1029]: 108.59.2.24 local addr 192.168.0.45 -> 
Apr 24 08:35:16 smsl-n100 ntpd[1029]: 185.209.85.222 local addr 192.168.0.45 -> 
Apr 24 08:35:16 smsl-n100 ntpd[1029]: 39.105.209.124 local addr 192.168.0.45 -> 
Apr 24 08:35:16 smsl-n100 ntpd[1029]: 162.159.200.1 local addr 192.168.0.45 -> 
Apr 24 08:35:16 smsl-n100 ntpd[1029]: 193.182.111.141 local addr 192.168.0.45 -> 
Apr 24 08:35:16 smsl-n100 ntpd[1029]: 116.203.151.74 local addr 192.168.0.45 -> 
Apr 24 08:35:16 smsl-n100 ntpd[1029]: 119.28.206.193 local addr 192.168.0.45 -> 
Apr 24 08:35:16 smsl-n100 ntpd[1029]: 202.118.1.81 local addr 192.168.0.45 -> 
Apr 24 08:35:16 smsl-n100 ntpd[1029]: 162.159.200.123 local addr 192.168.0.45 -> 
Apr 24 08:35:16 smsl-n100 systemd[1]: Starting Network Time Service...
Apr 24 08:35:16 smsl-n100 ntpd[1029]: 119.28.183.184 local addr 192.168.0.45 -> 
Apr 24 08:35:16 smsl-n100 ntpd[1029]: 78.46.102.180 local addr 192.168.0.45 -> 
Apr 24 08:35:16 smsl-n100 volumio[995]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 24 08:35:16 smsl-n100 ntpd[3216]: ntpd 4.2.8p12@1.3728-o (1): Starting
Apr 24 08:35:16 smsl-n100 ntpd[3216]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103
Apr 24 08:35:16 smsl-n100 systemd[1]: Started Network Time Service.
Apr 24 08:35:16 smsl-n100 ntpd[3222]: proto: precision = 1.166 usec (-20)
Apr 24 08:35:16 smsl-n100 ntpd[3222]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
Apr 24 08:35:16 smsl-n100 ntpd[3222]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
Apr 24 08:35:16 smsl-n100 ntpd[3222]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 849 days ago
Apr 24 08:35:16 smsl-n100 ntpd[3222]: Listen and drop on 0 v6wildcard [::]:123
Apr 24 08:35:16 smsl-n100 ntpd[3222]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Apr 24 08:35:16 smsl-n100 ntpd[3222]: Listen normally on 2 lo 127.0.0.1:123
Apr 24 08:35:16 smsl-n100 ntpd[3222]: Listen normally on 3 eth0 192.168.0.45:123
Apr 24 08:35:16 smsl-n100 ntpd[3222]: Listen normally on 4 wlan0 192.168.0.46:123
Apr 24 08:35:16 smsl-n100 ntpd[3222]: Listening on routing socket on fd #21 for interface updates
Apr 24 08:35:16 smsl-n100 ntpd[3222]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Apr 24 08:35:16 smsl-n100 ntpd[3222]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Apr 24 08:35:16 smsl-n100 wireless.js[2948]: trying...
Apr 24 08:35:16 smsl-n100 sudo[3225]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 24 08:35:16 smsl-n100 sudo[3225]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:16 smsl-n100 sudo[3225]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:16 smsl-n100 wireless.js[2948]: Connected to: ----TP-LINK_0FB7
Apr 24 08:35:16 smsl-n100 wireless.js[2948]: ----
Apr 24 08:35:16 smsl-n100 sudo[3228]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 24 08:35:16 smsl-n100 sudo[3228]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 24 08:35:16 smsl-n100 sudo[3228]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:16 smsl-n100 wireless.js[2948]: ... joined AP, wlan0 IPv4 is 192.168.0.46, ipV6 is undefined
Apr 24 08:35:16 smsl-n100 wireless.js[2948]: It's done! AP
Apr 24 08:35:17 smsl-n100 iwconfig[3232]: Error for wireless request "Set Power Management" (8B2C) :
Apr 24 08:35:17 smsl-n100 iwconfig[3232]:     SET failed on device wlan0 ; Operation not permitted.
Apr 24 08:35:17 smsl-n100 systemd[1]: Started Wireless Services.
Apr 24 08:35:17 smsl-n100 sudo[2946]: pam_unix(sudo:session): session closed for user root
Apr 24 08:35:17 smsl-n100 volumio[995]: info: Reporting MCU Network Status: 3
Apr 24 08:35:17 smsl-n100 volumio[995]: info: Volumio Network Manager: Network status updated: 3
Apr 24 08:35:17 smsl-n100 volumio[995]: info: CoreStateMachine::pushState
Apr 24 08:35:17 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:35:17 smsl-n100 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 24 08:35:17 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioPushState
Apr 24 08:35:17 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output update for this device
Apr 24 08:35:17 smsl-n100 volumio[995]: info: MRS: Pushing multiroomSync output
Apr 24 08:35:17 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: 100, message: undefined
Apr 24 08:35:17 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: MAXVOL, message:
Apr 24 08:35:17 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: 100, message: undefined
Apr 24 08:35:17 smsl-n100 volumio[995]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Apr 24 08:35:17 smsl-n100 volumio[995]: error: Serial API: Failed to decode command: 3, message: undefined
Apr 24 08:35:17 smsl-n100 volumio[995]: info: Discovery: this is already registered,  92a28c52-9a60-4106-82e6-98702e3976f3
Apr 24 08:35:17 smsl-n100 volumio[995]: info: Discovery: Found device SMSL N100
Apr 24 08:35:17 smsl-n100 volumio[995]: info: CoreCommandRouter::volumioGetState
Apr 24 08:35:17 smsl-n100 volumio[995]: info: CorePlayQueue::getTrack 0
Apr 24 08:35:17 smsl-n100 volumio[995]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 24 08:35:17 smsl-n100 volumio[995]: Error: The value NaN is not a number
Apr 24 08:35:17 smsl-n100 volumio[995]:     at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20)
Apr 24 08:35:17 smsl-n100 volumio[995]:     at Config.set (/volumio/node_modules/v-conf/index.js:153:25)
Apr 24 08:35:17 smsl-n100 volumio[995]:     at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25)
Apr 24 08:35:17 smsl-n100 volumio[995]:     at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10)
Apr 24 08:35:17 smsl-n100 volumio[995]:     at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12)
Apr 24 08:35:17 smsl-n100 volumio[995]:     at Browser.emit (events.js:400:28)
Apr 24 08:35:17 smsl-n100 volumio[995]:     at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14)
Apr 24 08:35:17 smsl-n100 volumio[995]:     at next (/volumio/node_modules/mdns/lib/browser.js:106:7)
Apr 24 08:35:17 smsl-n100 volumio[995]:     at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5)
Apr 24 08:35:17 smsl-n100 volumio[995]:     at next (/volumio/node_modules/mdns/lib/browser.js:109:21)
Apr 24 08:35:17 smsl-n100 volumio[995]:     at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11
Apr 24 08:35:17 smsl-n100 volumio[995]:     at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7)
Apr 24 08:35:17 smsl-n100 volumio[995]:     at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9)
Apr 24 08:35:17 smsl-n100 volumio[995]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 24 08:35:17 smsl-n100 ntpd[3222]: Soliciting pool server 193.182.111.14
Apr 24 08:35:18 smsl-n100 sudo[3256]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-24 08:34
Apr 24 08:35:18 smsl-n100 sudo[3256]: 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="8205c9d25502d90d2bb411bb156e1d5a8ef7404e"
VOLUMIO_ARCH="armv7"
VOLUMIO_VARIANT="smsl"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 20 Jan 2025 07:23:13 PM CET"
VOLUMIO_VERSION="3.788"
VOLUMIO_HARDWARE="nanopineo3"
VOLUMIO_DEVICENAME="Nanopi Neo3"
VOLUMIO_VENDOR_MODEL="SMSL S100"
VOLUMIO_VENDOR="SMSL"
VOLUMIO_MODEL="S100"
VOLUMIO_HASH="b3195a7f5841a4bc08e3e196c1a91b34"