-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-05-17 19:35:12 CEST. -- May 17 19:34:21 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioGetState May 17 19:34:21 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:21 smsl-n100 sudo[1728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 17 19:34:21 smsl-n100 sudo[1728]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 19:34:21 smsl-n100 sudo[1728]: pam_unix(sudo:session): session closed for user root May 17 19:34:21 smsl-n100 sudo[1730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 17 19:34:21 smsl-n100 sudo[1730]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 19:34:21 smsl-n100 sudo[1730]: pam_unix(sudo:session): session closed for user root May 17 19:34:22 smsl-n100 volumio[1017]: verbose: New Socket.io Connection to 192.168.0.166 from 192.168.0.165 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 May 17 19:34:22 smsl-n100 sudo[1734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 17 19:34:22 smsl-n100 sudo[1734]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 19:34:22 smsl-n100 sudo[1734]: pam_unix(sudo:session): session closed for user root May 17 19:34:22 smsl-n100 sudo[1736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 17 19:34:22 smsl-n100 sudo[1736]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 19:34:22 smsl-n100 sudo[1736]: pam_unix(sudo:session): session closed for user root May 17 19:34:22 smsl-n100 volumio[1017]: verbose: New Socket.io Connection to 192.168.0.166 from 192.168.0.165 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 May 17 19:34:22 smsl-n100 volumio[1017]: info: Received Get System Info May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 19:34:22 smsl-n100 volumio[1017]: info: Discovery: Getting this device information May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioGetState May 17 19:34:22 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioGetState May 17 19:34:22 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioGetVisibleSources May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioGetState May 17 19:34:22 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 17 19:34:22 smsl-n100 volumio[1017]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 17 19:34:22 smsl-n100 volumio[1017]: info: Received Get System Info May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 19:34:22 smsl-n100 volumio[1017]: info: Discovery: Getting this device information May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioGetState May 17 19:34:22 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioGetState May 17 19:34:22 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:22 smsl-n100 volumio[1017]: info: Listing playlists May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 19:34:22 smsl-n100 volumio[1017]: info: Discovery: Getting this device information May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioGetState May 17 19:34:22 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 19:34:22 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 17 19:34:24 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 17 19:34:24 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 17 19:34:24 smsl-n100 volumio[1017]: info: Received Get System Info May 17 19:34:24 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 19:34:24 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 19:34:24 smsl-n100 volumio[1017]: info: Discovery: Getting this device information May 17 19:34:24 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioGetState May 17 19:34:24 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:24 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 19:34:25 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 17 19:34:25 smsl-n100 volumio[1017]: info: Received Get System Info May 17 19:34:25 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 19:34:25 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 19:34:25 smsl-n100 volumio[1017]: info: Discovery: Getting this device information May 17 19:34:25 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioGetState May 17 19:34:25 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:25 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 19:34:26 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioPlay May 17 19:34:26 smsl-n100 volumio[1017]: info: CoreStateMachine::play index undefined May 17 19:34:26 smsl-n100 volumio[1017]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 19:34:26 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:26 smsl-n100 volumio[1017]: info: CoreStateMachine::startPlaybackTimer May 17 19:34:26 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:27 smsl-n100 volumio[1017]: info: [1747503267000] ControllerTidal::clearAddPlayTrack May 17 19:34:27 smsl-n100 volumio[1017]: info: Getting stream with soundQuality LOSSLESS May 17 19:34:27 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand stop May 17 19:34:27 smsl-n100 volumio[1017]: info: sendMpdCommand stop took 5 milliseconds May 17 19:34:27 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand clear May 17 19:34:27 smsl-n100 volumio[1017]: info: sendMpdCommand clear took 3 milliseconds May 17 19:34:27 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/27514137&soundQuality=LOSSLESS" May 17 19:34:27 smsl-n100 volumio[1017]: info: May 17 19:34:27 smsl-n100 volumio[1017]: ---------------------------- MPD announces system playlist update May 17 19:34:27 smsl-n100 volumio[1017]: info: Ignoring MPD Status Update May 17 19:34:27 smsl-n100 volumio[1017]: info: May 17 19:34:27 smsl-n100 volumio[1017]: ---------------------------- MPD announces system playlist update May 17 19:34:27 smsl-n100 volumio[1017]: info: Ignoring MPD Status Update May 17 19:34:27 smsl-n100 volumio[1017]: info: May 17 19:34:27 smsl-n100 volumio[1017]: ---------------------------- MPD announces system playlist update May 17 19:34:27 smsl-n100 volumio[1017]: info: Ignoring MPD Status Update May 17 19:34:27 smsl-n100 volumio[1017]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/27514137&soundQuality=LOSSLESS" took 21 milliseconds May 17 19:34:27 smsl-n100 volumio[1017]: info: ------------------------------ 19ms May 17 19:34:27 smsl-n100 volumio[1017]: info: CoreStateMachine::setConsumeUpdateService mpd May 17 19:34:27 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand play May 17 19:34:27 smsl-n100 volumio[1017]: info: May 17 19:34:27 smsl-n100 volumio[1017]: ---------------------------- MPD announces system playlist update May 17 19:34:27 smsl-n100 volumio[1017]: info: Ignoring MPD Status Update May 17 19:34:27 smsl-n100 volumio[1017]: info: May 17 19:34:27 smsl-n100 volumio[1017]: ---------------------------- MPD announces system playlist update May 17 19:34:27 smsl-n100 volumio[1017]: info: Ignoring MPD Status Update May 17 19:34:27 smsl-n100 volumio[1017]: error: Upnp client error: Error: This socket has been ended by the other party May 17 19:34:27 smsl-n100 volumio[1017]: info: ------------------------------ 40ms May 17 19:34:27 smsl-n100 volumio[1017]: info: ------------------------------ 38ms May 17 19:34:27 smsl-n100 volumio[1017]: info: sendMpdCommand play took 27 milliseconds May 17 19:34:27 smsl-n100 volumio[1017]: info: ------------------------------ 25ms May 17 19:34:27 smsl-n100 volumio[1017]: info: ------------------------------ 20ms May 17 19:34:27 smsl-n100 volumio[1017]: info: May 17 19:34:27 smsl-n100 volumio[1017]: ---------------------------- MPD announces system playlist update May 17 19:34:27 smsl-n100 volumio[1017]: info: Ignoring MPD Status Update May 17 19:34:27 smsl-n100 volumio[1017]: info: ------------------------------ 8ms May 17 19:34:27 smsl-n100 volumio[1017]: STREAMING PROXY: Handling url /?data=tidal://song/27514137&soundQuality=LOSSLESS May 17 19:34:27 smsl-n100 volumio[1017]: info: Executing endpoint getStreamUrltidal May 17 19:34:27 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI May 17 19:34:27 smsl-n100 volumio[1017]: info: getStreamUrl took 527 milliseconds May 17 19:34:27 smsl-n100 volumio[1017]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKAgDEiRmYzc1ZDIxOTMyM2EwYjU1MjMzYzU2MTIyMGY5Mzk3MS5tcDQ/0.flac?Expires=1747506867&Signature=Ka8VqpKBftjNjleT~kJfZi0Ida9~f3u8iRm~MCdyYxbXBi3yZlAHFEsNqKS7JNlM7HqzggwUDmvFfdaRtQDfNWuRhdbaukvXjHlG7T0ayGP~mND1AAWFEvRsOtC-2~zcHq~8CiOLFpy42X6u7rLktWUb7oFCOk2TYadvVANIy-hHp0Nhet-q8Spc1EqO1cWaHz~IJGey2c2PqwkZ7GntgxhUwy2z7B5k8HJhDaNbG-ob67k06F3LhZF5adZvbUPDklAhzDq7bHHprRD~ATXMAnXwB7sTb989LH8z8PFMOcPHqTstpH5L-42FLnURMJ8wcmk1skv7CzRJYk3xsn9Fjg__&Key-Pair-Id=K14LZCZ9QUI4JL May 17 19:34:28 smsl-n100 volumio[1017]: STREAMING PROXY: Response: 200, length: 25644731 May 17 19:34:28 smsl-n100 volumio[1017]: info: May 17 19:34:28 smsl-n100 volumio[1017]: ---------------------------- MPD announces state update: player May 17 19:34:28 smsl-n100 volumio[1017]: info: ControllerMpd::getState May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand status May 17 19:34:28 smsl-n100 volumio[1017]: info: May 17 19:34:28 smsl-n100 volumio[1017]: ---------------------------- MPD announces state update: player May 17 19:34:28 smsl-n100 volumio[1017]: info: ControllerMpd::getState May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand status May 17 19:34:28 smsl-n100 volumio[1017]: info: May 17 19:34:28 smsl-n100 volumio[1017]: ---------------------------- MPD announces state update: player May 17 19:34:28 smsl-n100 volumio[1017]: info: ControllerMpd::getState May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand status May 17 19:34:28 smsl-n100 volumio[1017]: info: May 17 19:34:28 smsl-n100 volumio[1017]: ---------------------------- MPD announces state update: player May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand clearerror May 17 19:34:28 smsl-n100 volumio[1017]: info: sendMpdCommand status took 52 milliseconds May 17 19:34:28 smsl-n100 volumio[1017]: info: ControllerMpd::getState May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand status May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::parseState May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand clearerror May 17 19:34:28 smsl-n100 volumio[1017]: info: sendMpdCommand status took 79 milliseconds May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand clearerror May 17 19:34:28 smsl-n100 volumio[1017]: info: sendMpdCommand status took 75 milliseconds May 17 19:34:28 smsl-n100 volumio[1017]: info: sendMpdCommand clearerror took 47 milliseconds May 17 19:34:28 smsl-n100 volumio[1017]: info: sendMpdCommand status took 45 milliseconds May 17 19:34:28 smsl-n100 volumio[1017]: info: sendMpdCommand playlistinfo took 40 milliseconds May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::parseState May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::parseState May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::parseState May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::parseTrackInfo May 17 19:34:28 smsl-n100 volumio[1017]: info: ControllerMpd::pushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreCommandRouter::servicePushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:28 smsl-n100 volumio[1017]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27514137&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/27514137&soundQuality=LOSSLESS","trackType":"tidal"} May 17 19:34:28 smsl-n100 volumio[1017]: verbose: CURRENT POSITION 0 May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::syncState stateService pause May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::syncState currentStatus stop May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::pushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioPushState May 17 19:34:28 smsl-n100 volumio[1017]: info: MRS: Pushing multiroomSync output update for this device May 17 19:34:28 smsl-n100 volumio[1017]: info: MRS: Pushing multiroomSync output May 17 19:34:28 smsl-n100 volumio[1017]: info: ------------------------------ 213ms May 17 19:34:28 smsl-n100 volumio[1017]: info: May 17 19:34:28 smsl-n100 volumio[1017]: ---------------------------- MPD announces state update: player May 17 19:34:28 smsl-n100 volumio[1017]: info: ControllerMpd::getState May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand status May 17 19:34:28 smsl-n100 volumio[1017]: info: May 17 19:34:28 smsl-n100 volumio[1017]: ---------------------------- MPD announces state update: player May 17 19:34:28 smsl-n100 volumio[1017]: info: ControllerMpd::getState May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand status May 17 19:34:28 smsl-n100 volumio[1017]: info: sendMpdCommand clearerror took 172 milliseconds May 17 19:34:28 smsl-n100 volumio[1017]: info: sendMpdCommand clearerror took 168 milliseconds May 17 19:34:28 smsl-n100 volumio[1017]: info: sendMpdCommand playlistinfo took 144 milliseconds May 17 19:34:28 smsl-n100 volumio[1017]: info: sendMpdCommand playlistinfo took 143 milliseconds May 17 19:34:28 smsl-n100 volumio[1017]: info: sendMpdCommand playlistinfo took 141 milliseconds May 17 19:34:28 smsl-n100 volumio[1017]: info: sendMpdCommand status took 55 milliseconds May 17 19:34:28 smsl-n100 volumio[1017]: info: sendMpdCommand status took 49 milliseconds May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::parseTrackInfo May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::parseTrackInfo May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::parseTrackInfo May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::parseState May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::parseState May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 19:34:28 smsl-n100 volumio[1017]: info: ControllerMpd::pushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreCommandRouter::servicePushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:28 smsl-n100 volumio[1017]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27514137&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/27514137&soundQuality=LOSSLESS","trackType":"tidal"} May 17 19:34:28 smsl-n100 volumio[1017]: verbose: CURRENT POSITION 0 May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::syncState stateService pause May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::syncState currentStatus stop May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::pushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioPushState May 17 19:34:28 smsl-n100 volumio[1017]: info: MRS: Pushing multiroomSync output update for this device May 17 19:34:28 smsl-n100 volumio[1017]: info: MRS: Pushing multiroomSync output May 17 19:34:28 smsl-n100 volumio[1017]: info: ControllerMpd::pushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreCommandRouter::servicePushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:28 smsl-n100 volumio[1017]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27514137&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/27514137&soundQuality=LOSSLESS","trackType":"tidal"} May 17 19:34:28 smsl-n100 volumio[1017]: verbose: CURRENT POSITION 0 May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::syncState stateService pause May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::syncState currentStatus stop May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::pushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioPushState May 17 19:34:28 smsl-n100 volumio[1017]: info: MRS: Pushing multiroomSync output update for this device May 17 19:34:28 smsl-n100 volumio[1017]: info: MRS: Pushing multiroomSync output May 17 19:34:28 smsl-n100 volumio[1017]: info: ControllerMpd::pushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreCommandRouter::servicePushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:28 smsl-n100 volumio[1017]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27514137&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/27514137&soundQuality=LOSSLESS","trackType":"tidal"} May 17 19:34:28 smsl-n100 volumio[1017]: verbose: CURRENT POSITION 0 May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::syncState stateService pause May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::syncState currentStatus stop May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::pushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioPushState May 17 19:34:28 smsl-n100 volumio[1017]: info: MRS: Pushing multiroomSync output update for this device May 17 19:34:28 smsl-n100 volumio[1017]: info: MRS: Pushing multiroomSync output May 17 19:34:28 smsl-n100 volumio[1017]: info: ------------------------------ 444ms May 17 19:34:28 smsl-n100 volumio[1017]: info: ------------------------------ 437ms May 17 19:34:28 smsl-n100 volumio[1017]: info: ------------------------------ 426ms May 17 19:34:28 smsl-n100 volumio[1017]: info: sendMpdCommand playlistinfo took 179 milliseconds May 17 19:34:28 smsl-n100 volumio[1017]: info: sendMpdCommand playlistinfo took 167 milliseconds May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::parseTrackInfo May 17 19:34:28 smsl-n100 volumio[1017]: verbose: ControllerMpd::parseTrackInfo May 17 19:34:28 smsl-n100 volumio[1017]: info: ControllerMpd::pushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreCommandRouter::servicePushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:28 smsl-n100 volumio[1017]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27514137&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/27514137&soundQuality=LOSSLESS","trackType":"tidal"} May 17 19:34:28 smsl-n100 volumio[1017]: verbose: CURRENT POSITION 0 May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::syncState stateService pause May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::syncState currentStatus stop May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::pushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioPushState May 17 19:34:28 smsl-n100 volumio[1017]: info: MRS: Pushing multiroomSync output update for this device May 17 19:34:28 smsl-n100 volumio[1017]: info: MRS: Pushing multiroomSync output May 17 19:34:28 smsl-n100 volumio[1017]: info: ControllerMpd::pushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreCommandRouter::servicePushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CorePlayQueue::getTrack 0 May 17 19:34:28 smsl-n100 volumio[1017]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27514137&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/27514137&soundQuality=LOSSLESS","trackType":"tidal"} May 17 19:34:28 smsl-n100 volumio[1017]: verbose: CURRENT POSITION 0 May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::syncState stateService pause May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::syncState currentStatus stop May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::pushState May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioPushState May 17 19:34:28 smsl-n100 volumio[1017]: info: MRS: Pushing multiroomSync output update for this device May 17 19:34:28 smsl-n100 volumio[1017]: info: MRS: Pushing multiroomSync output May 17 19:34:28 smsl-n100 volumio[1017]: info: ------------------------------ 351ms May 17 19:34:28 smsl-n100 volumio[1017]: info: ------------------------------ 345ms May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioGetQueue May 17 19:34:28 smsl-n100 volumio[1017]: info: CoreStateMachine::getQueue May 17 19:34:28 smsl-n100 volumio[1017]: info: CorePlayQueue::getQueue May 17 19:34:28 smsl-n100 volumio[1017]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 17 19:34:28 smsl-n100 volumio[1017]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 17 19:34:28 smsl-n100 volumio[1017]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 17 19:34:28 smsl-n100 volumio[1017]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 17 19:34:32 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 17 19:34:32 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 17 19:34:36 smsl-n100 volumio[1017]: STREAMING PROXY: Client dropped request, destroying May 17 19:34:40 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 17 19:34:40 smsl-n100 volumio[1017]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 17 19:34:40 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 17 19:34:40 smsl-n100 volumio[1017]: info: Received Get System Version May 17 19:34:40 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 17 19:34:40 smsl-n100 volumio[1017]: info: Received Get System Info May 17 19:34:40 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 19:34:40 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 19:34:40 smsl-n100 volumio[1017]: info: Discovery: Getting this device information May 17 19:34:40 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioGetState May 17 19:34:40 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 19:34:42 smsl-n100 volumio[1017]: info: CoreCommandRouter::getUIConfigOnPlugin May 17 19:34:42 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 17 19:34:42 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 17 19:34:42 smsl-n100 sudo[1789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 17 19:34:42 smsl-n100 sudo[1789]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 19:34:42 smsl-n100 sudo[1789]: pam_unix(sudo:session): session closed for user root May 17 19:34:42 smsl-n100 sudo[1794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 17 19:34:42 smsl-n100 sudo[1794]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 19:34:43 smsl-n100 sudo[1794]: pam_unix(sudo:session): session closed for user root May 17 19:34:43 smsl-n100 sudo[1802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 17 19:34:43 smsl-n100 sudo[1802]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 19:34:43 smsl-n100 sudo[1802]: pam_unix(sudo:session): session closed for user root May 17 19:34:43 smsl-n100 sudo[1808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 17 19:34:43 smsl-n100 sudo[1808]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 19:34:43 smsl-n100 sudo[1808]: pam_unix(sudo:session): session closed for user root May 17 19:34:43 smsl-n100 sudo[1814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 17 19:34:43 smsl-n100 sudo[1814]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 19:34:43 smsl-n100 sudo[1814]: pam_unix(sudo:session): session closed for user root May 17 19:34:43 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 17 19:34:43 smsl-n100 sudo[1817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 17 19:34:43 smsl-n100 sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 19:34:43 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 17 19:34:43 smsl-n100 sudo[1817]: pam_unix(sudo:session): session closed for user root May 17 19:34:43 smsl-n100 sudo[1821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 17 19:34:43 smsl-n100 sudo[1821]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 19:34:43 smsl-n100 sudo[1821]: pam_unix(sudo:session): session closed for user root May 17 19:34:52 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 19:34:52 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 19:34:52 smsl-n100 volumio[1017]: info: Discovery: Getting this device information May 17 19:34:52 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioGetState May 17 19:34:52 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 19:35:05 smsl-n100 volumio[1017]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] May 17 19:35:05 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet May 17 19:35:05 smsl-n100 sudo[1911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces May 17 19:35:05 smsl-n100 sudo[1911]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 19:35:05 smsl-n100 sudo[1911]: pam_unix(sudo:session): session closed for user root May 17 19:35:05 smsl-n100 sudo[1913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf May 17 19:35:05 smsl-n100 sudo[1913]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 19:35:05 smsl-n100 sudo[1913]: pam_unix(sudo:session): session closed for user root May 17 19:35:05 smsl-n100 volumio[1017]: info: Permissions for /etc/network/interfaces set May 17 19:35:05 smsl-n100 volumio[1017]: info: Restarting Wired Network May 17 19:35:05 smsl-n100 volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 17 19:35:05 smsl-n100 volumio[1017]: info: Discovery: Restarting Advertising due to device name change May 17 19:35:05 smsl-n100 sudo[1916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 May 17 19:35:05 smsl-n100 sudo[1916]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 19:35:05 smsl-n100 avahi-daemon[545]: Withdrawing address record for 127.0.0.1 on eth0. May 17 19:35:05 smsl-n100 dhcpcd[717]: eth0: pid 1917 deleted IP address 127.0.0.1/24 May 17 19:35:05 smsl-n100 dhcpcd[717]: eth0: deleting route to 127.0.0.0/24 May 17 19:35:05 smsl-n100 avahi-daemon[545]: Leaving mDNS multicast group on interface eth0.IPv4 with address 127.0.0.1. May 17 19:35:05 smsl-n100 avahi-daemon[545]: Interface eth0.IPv4 no longer relevant for mDNS. May 17 19:35:05 smsl-n100 sudo[1916]: pam_unix(sudo:session): session closed for user root May 17 19:35:05 smsl-n100 sudo[1919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down May 17 19:35:05 smsl-n100 sudo[1919]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 19:35:05 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down May 17 19:35:05 smsl-n100 sudo[1919]: pam_unix(sudo:session): session closed for user root May 17 19:35:05 smsl-n100 dhcpcd[717]: eth0: probing address 127.0.0.1/24 May 17 19:35:05 smsl-n100 sudo[1935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up May 17 19:35:05 smsl-n100 dhcpcd[717]: arp_probe1: Network is down May 17 19:35:05 smsl-n100 dhcpcd[717]: arp_read: eth0: Network is down May 17 19:35:05 smsl-n100 sudo[1935]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 19:35:05 smsl-n100 dhcpcd[717]: eth0: carrier lost May 17 19:35:05 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PHY [stmmac-0:01] driver [Generic PHY] May 17 19:35:05 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: No Safety Features support found May 17 19:35:05 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PTP not supported by HW May 17 19:35:05 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: configuring for phy/rgmii link mode May 17 19:35:05 smsl-n100 sudo[1935]: pam_unix(sudo:session): session closed for user root May 17 19:35:06 smsl-n100 volumio[1017]: info: Discovery: A device disappeared from network May 17 19:35:06 smsl-n100 ifplugd(eth0)[762]: Link beat lost. May 17 19:35:10 smsl-n100 volumio[1017]: info: Discovery: Started advertising with name: SMSL N100 May 17 19:35:10 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx May 17 19:35:10 smsl-n100 dhcpcd[717]: eth0: carrier acquired May 17 19:35:10 smsl-n100 dhcpcd[717]: eth0: confile file changed, expiring leases May 17 19:35:10 smsl-n100 dhcpcd[717]: eth0: IAID 79:a6:a5:22 May 17 19:35:10 smsl-n100 dhcpcd[717]: eth0: soliciting an IPv6 router May 17 19:35:11 smsl-n100 volumio[1017]: info: Discovery: adding fb74965d-62a3-4ccc-a848-f15079eef8e5 May 17 19:35:11 smsl-n100 volumio[1017]: info: Discovery: Found device SMSL N100 May 17 19:35:11 smsl-n100 volumio[1017]: info: CoreCommandRouter::volumioGetState May 17 19:35:11 smsl-n100 volumio[1017]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 19:35:11 smsl-n100 volumio[1017]: Error: The value NaN is not a number May 17 19:35:11 smsl-n100 volumio[1017]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) May 17 19:35:11 smsl-n100 volumio[1017]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) May 17 19:35:11 smsl-n100 volumio[1017]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) May 17 19:35:11 smsl-n100 volumio[1017]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) May 17 19:35:11 smsl-n100 volumio[1017]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) May 17 19:35:11 smsl-n100 volumio[1017]: at Browser.emit (events.js:400:28) May 17 19:35:11 smsl-n100 volumio[1017]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) May 17 19:35:11 smsl-n100 volumio[1017]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) May 17 19:35:11 smsl-n100 volumio[1017]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) May 17 19:35:11 smsl-n100 volumio[1017]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) May 17 19:35:11 smsl-n100 volumio[1017]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 May 17 19:35:11 smsl-n100 volumio[1017]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) May 17 19:35:11 smsl-n100 volumio[1017]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) May 17 19:35:11 smsl-n100 volumio[1017]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 19:35:11 smsl-n100 dhcpcd[717]: eth0: rebinding lease of 192.168.1.74 May 17 19:35:11 smsl-n100 dhcpcd[717]: eth0: probing address 192.168.1.74/24 May 17 19:35:11 smsl-n100 ifplugd(eth0)[762]: Link beat detected. May 17 19:35:12 smsl-n100 sudo[1986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-17 19:34 May 17 19:35:12 smsl-n100 sudo[1986]: 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"