-- Logs begin at Sat 2025-03-08 08:30:08 PST, end at Sat 2025-03-08 09:12:14 PST. --
Mar 08 09:11:19 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 08 09:11:19 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 08 09:11:19 smsl-n100 volumio[928]: info: Discovery: Getting this device information
Mar 08 09:11:19 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioGetState
Mar 08 09:11:19 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:11:19 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 08 09:11:32 smsl-n100 volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 08 09:11:32 smsl-n100 volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 08 09:11:32 smsl-n100 volumio[928]: info: Retrieving Cloud Streaming UI
Mar 08 09:11:33 smsl-n100 volumio[928]: info: Getting Tidal Cloud Configuration
Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 08 09:11:33 smsl-n100 volumio[928]: info: Getting Qobuz Cloud Configuration
Mar 08 09:11:33 smsl-n100 volumio[928]: info: Asking plugin for UI Config
Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 08 09:11:33 smsl-n100 volumio[928]: info: Getting Spotify Cloud Configuration
Mar 08 09:11:33 smsl-n100 volumio[928]: info: Asking plugin for UI Config
Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 08 09:11:33 smsl-n100 volumio[928]: info: Saving Spotify Acccount
Mar 08 09:11:33 smsl-n100 volumio[928]: info: Got it
Mar 08 09:11:33 smsl-n100 volumio[928]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Mar 08 09:11:33 smsl-n100 volumio[928]: info: Got Tidal Cloud Configuration
Mar 08 09:11:33 smsl-n100 volumio[928]: info: Got it
Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioGetBrowseSources
Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioGetBrowseSources
Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioGetBrowseSources
Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Mar 08 09:11:34 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Mar 08 09:11:37 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Mar 08 09:11:41 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Mar 08 09:11:47 smsl-n100 volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 08 09:11:48 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Mar 08 09:11:48 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork
Mar 08 09:11:48 smsl-n100 sudo[3548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0
Mar 08 09:11:48 smsl-n100 sudo[3548]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:11:48 smsl-n100 sudo[3553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Mar 08 09:11:48 smsl-n100 sudo[3553]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:11:48 smsl-n100 sudo[3548]: pam_unix(sudo:session): session closed for user root
Mar 08 09:11:48 smsl-n100 sudo[3553]: pam_unix(sudo:session): session closed for user root
Mar 08 09:11:48 smsl-n100 sudo[3561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Mar 08 09:11:48 smsl-n100 sudo[3561]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:11:48 smsl-n100 sudo[3567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Mar 08 09:11:48 smsl-n100 sudo[3567]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:11:48 smsl-n100 sudo[3561]: pam_unix(sudo:session): session closed for user root
Mar 08 09:11:48 smsl-n100 sudo[3567]: pam_unix(sudo:session): session closed for user root
Mar 08 09:11:48 smsl-n100 sudo[3574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 08 09:11:48 smsl-n100 sudo[3574]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:11:48 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Mar 08 09:11:48 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Mar 08 09:11:48 smsl-n100 sudo[3574]: pam_unix(sudo:session): session closed for user root
Mar 08 09:11:48 smsl-n100 sudo[3577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 08 09:11:48 smsl-n100 sudo[3577]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:11:48 smsl-n100 sudo[3577]: pam_unix(sudo:session): session closed for user root
Mar 08 09:11:48 smsl-n100 sudo[3580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Mar 08 09:11:48 smsl-n100 sudo[3580]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:11:49 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 08 09:11:49 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 08 09:11:49 smsl-n100 volumio[928]: info: Discovery: Getting this device information
Mar 08 09:11:49 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioGetState
Mar 08 09:11:49 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:11:49 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 08 09:11:58 smsl-n100 sudo[3580]: pam_unix(sudo:session): session closed for user root
Mar 08 09:12:06 smsl-n100 volumio[928]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object]
Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings
Mar 08 09:12:06 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:06 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 1
Mar 08 09:12:06 smsl-n100 volumio[928]: info: Prefetching next song
Mar 08 09:12:06 smsl-n100 volumio[928]: info: DOING PREFETCH IN MPD
Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand add "USB/LaCie/ROFI/Airto Moreira/Killer Bees/02 - Airto Moreira - Be There.flac"
Mar 08 09:12:06 smsl-n100 volumio[928]: info: sendMpdCommand add "USB/LaCie/ROFI/Airto Moreira/Killer Bees/02 - Airto Moreira - Be There.flac" took 4 milliseconds
Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand consume 1
Mar 08 09:12:06 smsl-n100 volumio[928]: info:
Mar 08 09:12:06 smsl-n100 volumio[928]: ---------------------------- MPD announces system playlist update
Mar 08 09:12:06 smsl-n100 volumio[928]: info: Ignoring MPD Status Update
Mar 08 09:12:06 smsl-n100 volumio[928]: info:
Mar 08 09:12:06 smsl-n100 volumio[928]: ---------------------------- MPD announces state update: options
Mar 08 09:12:06 smsl-n100 volumio[928]: info: sendMpdCommand consume 1 took 15 milliseconds
Mar 08 09:12:06 smsl-n100 volumio[928]: info: ControllerMpd::getState
Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand status
Mar 08 09:12:06 smsl-n100 volumio[928]: info:
Mar 08 09:12:06 smsl-n100 volumio[928]: ---------------------------- MPD announces system playlist update
Mar 08 09:12:06 smsl-n100 volumio[928]: info: Ignoring MPD Status Update
Mar 08 09:12:06 smsl-n100 volumio[928]: info:
Mar 08 09:12:06 smsl-n100 volumio[928]: ---------------------------- MPD announces system playlist update
Mar 08 09:12:06 smsl-n100 volumio[928]: info: Ignoring MPD Status Update
Mar 08 09:12:06 smsl-n100 volumio[928]: info: ------------------------------ 44ms
Mar 08 09:12:06 smsl-n100 volumio[928]: info: sendMpdCommand status took 32 milliseconds
Mar 08 09:12:06 smsl-n100 volumio[928]: info: ------------------------------ 27ms
Mar 08 09:12:06 smsl-n100 volumio[928]: info: ------------------------------ 21ms
Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::parseState
Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 08 09:12:06 smsl-n100 volumio[928]: info:
Mar 08 09:12:06 smsl-n100 volumio[928]: ---------------------------- MPD announces state update: options
Mar 08 09:12:06 smsl-n100 volumio[928]: info: ControllerMpd::getState
Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand status
Mar 08 09:12:06 smsl-n100 volumio[928]: info:
Mar 08 09:12:06 smsl-n100 volumio[928]: ---------------------------- MPD announces state update: options
Mar 08 09:12:06 smsl-n100 volumio[928]: info: ControllerMpd::getState
Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand status
Mar 08 09:12:06 smsl-n100 volumio[928]: info: sendMpdCommand playlistinfo took 31 milliseconds
Mar 08 09:12:06 smsl-n100 volumio[928]: info: sendMpdCommand status took 26 milliseconds
Mar 08 09:12:06 smsl-n100 volumio[928]: info: sendMpdCommand status took 9 milliseconds
Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::parseTrackInfo
Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::parseState
Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::parseState
Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 08 09:12:06 smsl-n100 volumio[928]: info: ControllerMpd::pushState
Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreCommandRouter::servicePushState
Mar 08 09:12:06 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: STATE SERVICE {"status":"play","position":0,"seek":359556,"duration":364,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"212 Kbps","isStreaming":false,"title":"Banana Jam","artist":"Airto Moreira","album":"Killer Bees","uri":"USB/LaCie/ROFI/Airto Moreira/Killer Bees/01 - Airto Moreira - Banana Jam.flac","trackType":"flac"}
Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: CURRENT POSITION 0
Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreStateMachine::syncState stateService play
Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreStateMachine::syncState currentStatus play
Mar 08 09:12:06 smsl-n100 volumio[928]: info: Received an update from plugin. extracting info from payload
Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreStateMachine::pushState
Mar 08 09:12:06 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState
Mar 08 09:12:06 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device
Mar 08 09:12:06 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output
Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreStateMachine::pushState
Mar 08 09:12:06 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState
Mar 08 09:12:06 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device
Mar 08 09:12:06 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output
Mar 08 09:12:07 smsl-n100 volumio[928]: info: ------------------------------ 189ms
Mar 08 09:12:07 smsl-n100 volumio[928]: info: sendMpdCommand playlistinfo took 104 milliseconds
Mar 08 09:12:07 smsl-n100 volumio[928]: info: sendMpdCommand playlistinfo took 105 milliseconds
Mar 08 09:12:07 smsl-n100 volumio[928]: verbose: ControllerMpd::parseTrackInfo
Mar 08 09:12:07 smsl-n100 volumio[928]: verbose: ControllerMpd::parseTrackInfo
Mar 08 09:12:07 smsl-n100 volumio[928]: info: ControllerMpd::pushState
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreCommandRouter::servicePushState
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:07 smsl-n100 volumio[928]: verbose: STATE SERVICE {"status":"play","position":0,"seek":359693,"duration":364,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"203 Kbps","isStreaming":false,"title":"Banana Jam","artist":"Airto Moreira","album":"Killer Bees","uri":"USB/LaCie/ROFI/Airto Moreira/Killer Bees/01 - Airto Moreira - Banana Jam.flac","trackType":"flac"}
Mar 08 09:12:07 smsl-n100 volumio[928]: verbose: CURRENT POSITION 0
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreStateMachine::syncState stateService play
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreStateMachine::syncState currentStatus play
Mar 08 09:12:07 smsl-n100 volumio[928]: info: Received an update from plugin. extracting info from payload
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreStateMachine::pushState
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState
Mar 08 09:12:07 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device
Mar 08 09:12:07 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreStateMachine::pushState
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState
Mar 08 09:12:07 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device
Mar 08 09:12:07 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output
Mar 08 09:12:07 smsl-n100 volumio[928]: info: ControllerMpd::pushState
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreCommandRouter::servicePushState
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:07 smsl-n100 volumio[928]: verbose: STATE SERVICE {"status":"play","position":0,"seek":359693,"duration":364,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"203 Kbps","isStreaming":false,"title":"Banana Jam","artist":"Airto Moreira","album":"Killer Bees","uri":"USB/LaCie/ROFI/Airto Moreira/Killer Bees/01 - Airto Moreira - Banana Jam.flac","trackType":"flac"}
Mar 08 09:12:07 smsl-n100 volumio[928]: verbose: CURRENT POSITION 0
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreStateMachine::syncState stateService play
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreStateMachine::syncState currentStatus play
Mar 08 09:12:07 smsl-n100 volumio[928]: info: Received an update from plugin. extracting info from payload
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreStateMachine::pushState
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState
Mar 08 09:12:07 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device
Mar 08 09:12:07 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreStateMachine::pushState
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState
Mar 08 09:12:07 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device
Mar 08 09:12:07 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output
Mar 08 09:12:07 smsl-n100 volumio[928]: info: ------------------------------ 318ms
Mar 08 09:12:07 smsl-n100 volumio[928]: info: ------------------------------ 315ms
Mar 08 09:12:07 smsl-n100 sudo[3658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf
Mar 08 09:12:07 smsl-n100 sudo[3658]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:12:07 smsl-n100 sudo[3658]: pam_unix(sudo:session): session closed for user root
Mar 08 09:12:07 smsl-n100 volumio[928]: info: Permissions for /etc/hostapd/hostapd.conf
Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart
Mar 08 09:12:07 smsl-n100 volumio[928]: info: Discovery: Restarting Advertising due to device name change
Mar 08 09:12:07 smsl-n100 sudo[3663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service
Mar 08 09:12:07 smsl-n100 sudo[3663]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:12:07 smsl-n100 systemd[1]: Starting Wireless Services...
Mar 08 09:12:07 smsl-n100 volumio[928]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 08 09:12:08 smsl-n100 wireless.js[3665]: Cleaning previous...
Mar 08 09:12:08 smsl-n100 volumio[928]: info: Discovery: A device disappeared from network
Mar 08 09:12:08 smsl-n100 volumio[928]: info: Discovery: A device disappeared from network
Mar 08 09:12:08 smsl-n100 systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server...
Mar 08 09:12:08 smsl-n100 volumio[928]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 08 09:12:08 smsl-n100 dnsmasq[951]: exiting on receipt of SIGTERM
Mar 08 09:12:08 smsl-n100 systemd[1]: dnsmasq.service: Succeeded.
Mar 08 09:12:08 smsl-n100 systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server.
Mar 08 09:12:08 smsl-n100 systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator...
Mar 08 09:12:08 smsl-n100 volumio[928]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 08 09:12:08 smsl-n100 dhcpcd[969]: wlan0: carrier lost
Mar 08 09:12:08 smsl-n100 systemd[1]: hostapd.service: Succeeded.
Mar 08 09:12:08 smsl-n100 systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator.
Mar 08 09:12:08 smsl-n100 dhcpcd[969]: wlan0: deleting route to 169.254.0.0/16
Mar 08 09:12:08 smsl-n100 avahi-daemon[571]: Withdrawing address record for 169.254.131.52 on wlan0.
Mar 08 09:12:08 smsl-n100 sudo[3702]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Mar 08 09:12:08 smsl-n100 sudo[3702]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:12:08 smsl-n100 avahi-daemon[571]: Withdrawing address record for 192.168.211.1 on wlan0.
Mar 08 09:12:08 smsl-n100 avahi-daemon[571]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
Mar 08 09:12:08 smsl-n100 avahi-daemon[571]: Interface wlan0.IPv4 no longer relevant for mDNS.
Mar 08 09:12:08 smsl-n100 sudo[3702]: pam_unix(sudo:session): session closed for user root
Mar 08 09:12:09 smsl-n100 sudo[3715]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Mar 08 09:12:09 smsl-n100 sudo[3715]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:12:09 smsl-n100 sudo[3715]: pam_unix(sudo:session): session closed for user root
Mar 08 09:12:09 smsl-n100 wireless.js[3665]: Stopped aP
Mar 08 09:12:09 smsl-n100 sudo[3742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Mar 08 09:12:09 smsl-n100 sudo[3742]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:12:09 smsl-n100 sudo[3742]: pam_unix(sudo:session): session closed for user root
Mar 08 09:12:09 smsl-n100 sudo[3744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get
Mar 08 09:12:09 smsl-n100 sudo[3744]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:12:09 smsl-n100 sudo[3744]: pam_unix(sudo:session): session closed for user root
Mar 08 09:12:09 smsl-n100 sudo[3751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Mar 08 09:12:09 smsl-n100 sudo[3751]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:12:09 smsl-n100 sudo[3751]: pam_unix(sudo:session): session closed for user root
Mar 08 09:12:09 smsl-n100 sudo[3753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan
Mar 08 09:12:09 smsl-n100 sudo[3753]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:12:09 smsl-n100 dhcpcd[969]: wlan0: carrier acquired
Mar 08 09:12:09 smsl-n100 dhcpcd[969]: wlan0: IAID 2b:ee:c9:22
Mar 08 09:12:10 smsl-n100 dhcpcd[969]: wlan0: soliciting a DHCP lease
Mar 08 09:12:10 smsl-n100 dhcpcd[969]: wlan0: soliciting an IPv6 router
Mar 08 09:12:11 smsl-n100 volumio[928]: info:
Mar 08 09:12:11 smsl-n100 volumio[928]: ---------------------------- MPD announces system playlist update
Mar 08 09:12:11 smsl-n100 volumio[928]: info: Ignoring MPD Status Update
Mar 08 09:12:11 smsl-n100 volumio[928]: info:
Mar 08 09:12:11 smsl-n100 volumio[928]: ---------------------------- MPD announces state update: player
Mar 08 09:12:11 smsl-n100 volumio[928]: info: ControllerMpd::getState
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand status
Mar 08 09:12:11 smsl-n100 volumio[928]: info:
Mar 08 09:12:11 smsl-n100 volumio[928]: ---------------------------- MPD announces system playlist update
Mar 08 09:12:11 smsl-n100 volumio[928]: info: Ignoring MPD Status Update
Mar 08 09:12:11 smsl-n100 volumio[928]: info:
Mar 08 09:12:11 smsl-n100 volumio[928]: ---------------------------- MPD announces state update: player
Mar 08 09:12:11 smsl-n100 volumio[928]: info: ControllerMpd::getState
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand status
Mar 08 09:12:11 smsl-n100 volumio[928]: info:
Mar 08 09:12:11 smsl-n100 volumio[928]: ---------------------------- MPD announces system playlist update
Mar 08 09:12:11 smsl-n100 volumio[928]: info: Ignoring MPD Status Update
Mar 08 09:12:11 smsl-n100 volumio[928]: info:
Mar 08 09:12:11 smsl-n100 volumio[928]: ---------------------------- MPD announces state update: player
Mar 08 09:12:11 smsl-n100 volumio[928]: info: ControllerMpd::getState
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand status
Mar 08 09:12:11 smsl-n100 volumio[928]: info: ------------------------------ 39ms
Mar 08 09:12:11 smsl-n100 volumio[928]: info: sendMpdCommand status took 33 milliseconds
Mar 08 09:12:11 smsl-n100 volumio[928]: info: ------------------------------ 31ms
Mar 08 09:12:11 smsl-n100 volumio[928]: info: sendMpdCommand status took 26 milliseconds
Mar 08 09:12:11 smsl-n100 volumio[928]: info: ------------------------------ 24ms
Mar 08 09:12:11 smsl-n100 volumio[928]: info: sendMpdCommand status took 19 milliseconds
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::parseState
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::parseState
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::parseState
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 08 09:12:11 smsl-n100 volumio[928]: info: sendMpdCommand playlistinfo took 13 milliseconds
Mar 08 09:12:11 smsl-n100 volumio[928]: info: sendMpdCommand playlistinfo took 13 milliseconds
Mar 08 09:12:11 smsl-n100 volumio[928]: info: sendMpdCommand playlistinfo took 13 milliseconds
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::parseTrackInfo
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::parseTrackInfo
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::parseTrackInfo
Mar 08 09:12:11 smsl-n100 volumio[928]: info: ControllerMpd::pushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::servicePushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"669 Kbps","isStreaming":false,"title":"Be There","artist":"Airto Moreira","album":"Killer Bees","uri":"USB/LaCie/ROFI/Airto Moreira/Killer Bees/02 - Airto Moreira - Be There.flac","trackType":"flac"}
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: CURRENT POSITION 0
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::syncState stateService play
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::syncState currentStatus play
Mar 08 09:12:11 smsl-n100 volumio[928]: info: Received an update from plugin. extracting info from payload
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::pushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device
Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::pushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device
Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output
Mar 08 09:12:11 smsl-n100 volumio[928]: info: ControllerMpd::pushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::servicePushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"669 Kbps","isStreaming":false,"title":"Be There","artist":"Airto Moreira","album":"Killer Bees","uri":"USB/LaCie/ROFI/Airto Moreira/Killer Bees/02 - Airto Moreira - Be There.flac","trackType":"flac"}
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: CURRENT POSITION 0
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::syncState stateService play
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::syncState currentStatus play
Mar 08 09:12:11 smsl-n100 volumio[928]: info: Received an update from plugin. extracting info from payload
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::pushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device
Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::pushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device
Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output
Mar 08 09:12:11 smsl-n100 volumio[928]: info: ControllerMpd::pushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::servicePushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"669 Kbps","isStreaming":false,"title":"Be There","artist":"Airto Moreira","album":"Killer Bees","uri":"USB/LaCie/ROFI/Airto Moreira/Killer Bees/02 - Airto Moreira - Be There.flac","trackType":"flac"}
Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: CURRENT POSITION 0
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::syncState stateService play
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::syncState currentStatus play
Mar 08 09:12:11 smsl-n100 volumio[928]: info: Received an update from plugin. extracting info from payload
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::pushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device
Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::pushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device
Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output
Mar 08 09:12:11 smsl-n100 volumio[928]: info: ------------------------------ 325ms
Mar 08 09:12:11 smsl-n100 volumio[928]: info: ------------------------------ 317ms
Mar 08 09:12:11 smsl-n100 volumio[928]: info: ------------------------------ 310ms
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::startPlaybackTimer
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 1
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::pushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 1
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState
Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device
Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output
Mar 08 09:12:11 smsl-n100 ntpd[967]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=583 secs
Mar 08 09:12:11 smsl-n100 ntpd[967]: Deleting interface #5 wlan0, 169.254.131.52#123, interface stats: received=0, sent=0, dropped=0, active_time=573 secs
Mar 08 09:12:12 smsl-n100 volumio[928]: info: Discovery: Started advertising with name: SMSL N100
Mar 08 09:12:13 smsl-n100 volumio[928]: info: Discovery: adding 6cc4b515-a1e1-4201-8136-6ed68e33cfa3
Mar 08 09:12:13 smsl-n100 volumio[928]: info: Discovery: Found device SMSL N100
Mar 08 09:12:13 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioGetState
Mar 08 09:12:13 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 1
Mar 08 09:12:13 smsl-n100 volumio[928]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 08 09:12:13 smsl-n100 volumio[928]: Error: The value NaN is not a number
Mar 08 09:12:13 smsl-n100 volumio[928]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20)
Mar 08 09:12:13 smsl-n100 volumio[928]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25)
Mar 08 09:12:13 smsl-n100 volumio[928]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25)
Mar 08 09:12:13 smsl-n100 volumio[928]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10)
Mar 08 09:12:13 smsl-n100 volumio[928]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12)
Mar 08 09:12:13 smsl-n100 volumio[928]: at Browser.emit (events.js:400:28)
Mar 08 09:12:13 smsl-n100 volumio[928]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14)
Mar 08 09:12:13 smsl-n100 volumio[928]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7)
Mar 08 09:12:13 smsl-n100 volumio[928]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5)
Mar 08 09:12:13 smsl-n100 volumio[928]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21)
Mar 08 09:12:13 smsl-n100 volumio[928]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11
Mar 08 09:12:13 smsl-n100 volumio[928]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7)
Mar 08 09:12:13 smsl-n100 volumio[928]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9)
Mar 08 09:12:13 smsl-n100 volumio[928]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 08 09:12:13 smsl-n100 sudo[3753]: pam_unix(sudo:session): session closed for user root
Mar 08 09:12:14 smsl-n100 sudo[3795]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Mar 08 09:12:14 smsl-n100 sudo[3795]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:12:14 smsl-n100 sudo[3795]: pam_unix(sudo:session): session closed for user root
Mar 08 09:12:14 smsl-n100 sudo[3797]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Mar 08 09:12:14 smsl-n100 sudo[3797]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 08 09:12:14 smsl-n100 dhcpcd[969]: wlan0: carrier lost
Mar 08 09:12:14 smsl-n100 sudo[3797]: pam_unix(sudo:session): session closed for user root
Mar 08 09:12:14 smsl-n100 wireless.js[3665]: Hotspot is disabled, not starting it
Mar 08 09:12:14 smsl-n100 iwconfig[3818]: Error for wireless request "Set Power Management" (8B2C) :
Mar 08 09:12:14 smsl-n100 iwconfig[3818]: SET failed on device wlan0 ; Operation not permitted.
Mar 08 09:12:14 smsl-n100 systemd[1]: wireless.service: Succeeded.
Mar 08 09:12:14 smsl-n100 systemd[1]: Started Wireless Services.
Mar 08 09:12:14 smsl-n100 sudo[3663]: pam_unix(sudo:session): session closed for user root
Mar 08 09:12:14 smsl-n100 dhcpcd[969]: wlan0: carrier acquired
Mar 08 09:12:14 smsl-n100 dhcpcd[969]: wlan0: IAID 2b:ee:c9:22
Mar 08 09:12:14 smsl-n100 sudo[3836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-08 09:11
Mar 08 09:12:14 smsl-n100 sudo[3836]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 10 (buster)"
NAME="Debian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="cc0042a1eab1cb7982711a1347e242f8947b54b6"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb"
VOLUMIO_ARCH="armv7"
VOLUMIO_VARIANT="smsl"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET"
VOLUMIO_VERSION="3.789"
VOLUMIO_HARDWARE="nanopineo3"
VOLUMIO_DEVICENAME="Nanopi Neo3"
VOLUMIO_VENDOR_MODEL="SMSL S100"
VOLUMIO_VENDOR="SMSL"
VOLUMIO_MODEL="S100"
VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"