-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Thu 2025-05-01 09:19:04 CST. -- May 01 09:18:05 smsl-n100 volumio[808]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 09:18:05 smsl-n100 volumio[808]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 09:18:05 smsl-n100 volumio[808]: info: Discovery: Getting this device information May 01 09:18:05 smsl-n100 volumio[808]: info: CoreCommandRouter::volumioGetState May 01 09:18:05 smsl-n100 volumio[808]: info: CorePlayQueue::getTrack 4 May 01 09:18:05 smsl-n100 volumio[808]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 09:18:35 smsl-n100 volumio[808]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 09:18:35 smsl-n100 volumio[808]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 09:18:35 smsl-n100 volumio[808]: info: Discovery: Getting this device information May 01 09:18:35 smsl-n100 volumio[808]: info: CoreCommandRouter::volumioGetState May 01 09:18:35 smsl-n100 volumio[808]: info: CorePlayQueue::getTrack 4 May 01 09:18:35 smsl-n100 volumio[808]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 09:19:02 smsl-n100 volumio[808]: info: Preload queue cleared May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::volumioReplaceandPlayItems May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::ClearQueue May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::stop May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::stPlaybackTimer May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::updateTrackBlock May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::getTrackBlock May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::pushState May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::getTrack 4 May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::volumioPushState May 01 09:19:02 smsl-n100 volumio[808]: info: MRS: Pushing multiroomSync output update for this device May 01 09:19:02 smsl-n100 volumio[808]: info: MRS: Pushing multiroomSync output May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::serviceStop May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::getTrack 4 May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::serviceStop May 01 09:19:02 smsl-n100 volumio[808]: info: ControllerMpd::stop May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::sendMpdCommand stop May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::clearPlayQueue May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::saveQueue May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::volumioPushQueue May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::addQueueItems May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::addQueueItems May 01 09:19:02 smsl-n100 volumio[808]: info: Preload queue cleared May 01 09:19:02 smsl-n100 volumio[808]: info: Adding Item to queue: music-library/USB/MUSIC_BKUP/mora/202505/021-Bamboo Grove.flac May 01 09:19:02 smsl-n100 volumio[808]: info: Using cached record of: music-library/USB/MUSIC_BKUP/mora/202505/021-Bamboo Grove.flac May 01 09:19:02 smsl-n100 volumio[808]: info: Adding Item to queue: music-library/USB/MUSIC_BKUP/mora/202505/021-Impressions.flac May 01 09:19:02 smsl-n100 volumio[808]: info: Using cached record of: music-library/USB/MUSIC_BKUP/mora/202505/021-Impressions.flac May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::volumioPushQueue May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::saveQueue May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::updateTrackBlock May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::getTrackBlock May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::volumioPlay May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::play index 1 May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::addQueueItems May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::addQueueItems May 01 09:19:02 smsl-n100 volumio[808]: info: Preload queue cleared May 01 09:19:02 smsl-n100 volumio[808]: info: Adding Item to queue: music-library/USB/MUSIC_BKUP/mora/202505/031-Little Blue - Mahogany Sessions.flac May 01 09:19:02 smsl-n100 volumio[808]: info: Using cached record of: music-library/USB/MUSIC_BKUP/mora/202505/031-Little Blue - Mahogany Sessions.flac May 01 09:19:02 smsl-n100 volumio[808]: info: Adding Item to queue: music-library/USB/MUSIC_BKUP/mora/202505/011-Route 246.flac May 01 09:19:02 smsl-n100 volumio[808]: info: Using cached record of: music-library/USB/MUSIC_BKUP/mora/202505/011-Route 246.flac May 01 09:19:02 smsl-n100 volumio[808]: info: Adding Item to queue: music-library/USB/MUSIC_BKUP/mora/202505/001-満ちてゆく.flac May 01 09:19:02 smsl-n100 volumio[808]: info: Using cached record of: music-library/USB/MUSIC_BKUP/mora/202505/001-満ちてゆく.flac May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::stop May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::volumioPushQueue May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::saveQueue May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::play index undefined May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::updateTrackBlock May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::getTrackBlock May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::getTrack 1 May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::startPlaybackTimer May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::getTrack 1 May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSIC_BKUP/mora/202505/021-Impressions.flac May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::sendMpdCommand stop May 01 09:19:02 smsl-n100 volumio[808]: info: May 01 09:19:02 smsl-n100 volumio[808]: ---------------------------- MPD announces state update: player May 01 09:19:02 smsl-n100 volumio[808]: info: sendMpdCommand stop took 115 milliseconds May 01 09:19:02 smsl-n100 volumio[808]: info: ControllerMpd::getState May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::sendMpdCommand status May 01 09:19:02 smsl-n100 volumio[808]: info: sendMpdCommand stop took 25 milliseconds May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::sendMpdCommand clear May 01 09:19:02 smsl-n100 volumio[808]: info: May 01 09:19:02 smsl-n100 volumio[808]: ---------------------------- MPD announces system playlist update May 01 09:19:02 smsl-n100 volumio[808]: info: Ignoring MPD Status Update May 01 09:19:02 smsl-n100 volumio[808]: info: sendMpdCommand status took 10 milliseconds May 01 09:19:02 smsl-n100 volumio[808]: info: sendMpdCommand clear took 8 milliseconds May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::parseState May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIC_BKUP/mora/202505/021-Impressions.flac" May 01 09:19:02 smsl-n100 volumio[808]: error: updateQueue error: null May 01 09:19:02 smsl-n100 volumio[808]: info: May 01 09:19:02 smsl-n100 volumio[808]: ---------------------------- MPD announces system playlist update May 01 09:19:02 smsl-n100 volumio[808]: info: Ignoring MPD Status Update May 01 09:19:02 smsl-n100 volumio[808]: info: ------------------------------ 17ms May 01 09:19:02 smsl-n100 volumio[808]: info: sendMpdCommand playlistinfo took 11 milliseconds May 01 09:19:02 smsl-n100 volumio[808]: info: sendMpdCommand add "USB/MUSIC_BKUP/mora/202505/021-Impressions.flac" took 11 milliseconds May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::parseTrackInfo May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::sendMpdCommand play May 01 09:19:02 smsl-n100 volumio[808]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 01 09:19:02 smsl-n100 volumio[808]: info: ------------------------------ 42ms May 01 09:19:02 smsl-n100 volumio[808]: info: ------------------------------ 20ms May 01 09:19:02 smsl-n100 volumio[808]: info: sendMpdCommand play took 8 milliseconds May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::pushState May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::getTrack 1 May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::volumioPushState May 01 09:19:02 smsl-n100 volumio[808]: info: MRS: Pushing multiroomSync output update for this device May 01 09:19:02 smsl-n100 volumio[808]: info: MRS: Pushing multiroomSync output May 01 09:19:02 smsl-n100 volumio[808]: error: Serial API: Failed to decode command: 100, message: undefined May 01 09:19:02 smsl-n100 volumio[808]: error: Serial API: Failed to decode command: MAXVOL, message: May 01 09:19:02 smsl-n100 volumio[808]: error: Serial API: Failed to decode command: 100, message: undefined May 01 09:19:02 smsl-n100 volumio[808]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 01 09:19:02 smsl-n100 volumio[808]: info: May 01 09:19:02 smsl-n100 volumio[808]: ---------------------------- MPD announces state update: player May 01 09:19:02 smsl-n100 volumio[808]: info: ControllerMpd::getState May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::sendMpdCommand status May 01 09:19:02 smsl-n100 volumio[808]: info: May 01 09:19:02 smsl-n100 volumio[808]: ---------------------------- MPD announces state update: player May 01 09:19:02 smsl-n100 volumio[808]: info: ControllerMpd::getState May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::sendMpdCommand status May 01 09:19:02 smsl-n100 volumio[808]: info: sendMpdCommand status took 50 milliseconds May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::parseState May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 09:19:02 smsl-n100 volumio[808]: info: sendMpdCommand status took 16 milliseconds May 01 09:19:02 smsl-n100 volumio[808]: info: sendMpdCommand playlistinfo took 10 milliseconds May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::parseState May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::parseTrackInfo May 01 09:19:02 smsl-n100 volumio[808]: info: ControllerMpd::pushState May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::servicePushState May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::getTrack 1 May 01 09:19:02 smsl-n100 volumio[808]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":106,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Impressions","artist":"上原ひろみ","album":"BLUE GIANT (オリジナル・サウンドトラック)","uri":"USB/MUSIC_BKUP/mora/202505/021-Impressions.flac","trackType":"flac"} May 01 09:19:02 smsl-n100 volumio[808]: verbose: CURRENT POSITION 1 May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::syncState stateService play May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::syncState currentStatus stop May 01 09:19:02 smsl-n100 volumio[808]: info: ------------------------------ 92ms May 01 09:19:02 smsl-n100 volumio[808]: info: sendMpdCommand playlistinfo took 161 milliseconds May 01 09:19:02 smsl-n100 volumio[808]: verbose: ControllerMpd::parseTrackInfo May 01 09:19:02 smsl-n100 volumio[808]: info: ControllerMpd::pushState May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::servicePushState May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::getTrack 1 May 01 09:19:02 smsl-n100 volumio[808]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":106,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Impressions","artist":"上原ひろみ","album":"BLUE GIANT (オリジナル・サウンドトラック)","uri":"USB/MUSIC_BKUP/mora/202505/021-Impressions.flac","trackType":"flac"} May 01 09:19:02 smsl-n100 volumio[808]: verbose: CURRENT POSITION 1 May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::syncState stateService play May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::syncState currentStatus play May 01 09:19:02 smsl-n100 volumio[808]: info: Received an update from plugin. extracting info from payload May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::pushState May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::getTrack 1 May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::volumioPushState May 01 09:19:02 smsl-n100 volumio[808]: info: MRS: Pushing multiroomSync output update for this device May 01 09:19:02 smsl-n100 volumio[808]: info: MRS: Pushing multiroomSync output May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::pushState May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::getTrack 1 May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::volumioPushState May 01 09:19:02 smsl-n100 volumio[808]: info: MRS: Pushing multiroomSync output update for this device May 01 09:19:02 smsl-n100 volumio[808]: info: MRS: Pushing multiroomSync output May 01 09:19:02 smsl-n100 volumio[808]: info: ------------------------------ 254ms May 01 09:19:02 smsl-n100 volumio[808]: info: VolumeController::SetAlsaVolume0 May 01 09:19:02 smsl-n100 volumio[808]: info: CoreStateMachine::pushState May 01 09:19:02 smsl-n100 volumio[808]: info: CorePlayQueue::getTrack 1 May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 09:19:02 smsl-n100 volumio[808]: info: CoreCommandRouter::volumioPushState May 01 09:19:02 smsl-n100 volumio[808]: info: MRS: Pushing multiroomSync output update for this device May 01 09:19:02 smsl-n100 volumio[808]: info: MRS: Pushing multiroomSync output May 01 09:19:02 smsl-n100 volumio[808]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 09:19:02 smsl-n100 volumio[808]: Error: connect ETIMEDOUT 199.96.58.85:443 May 01 09:19:02 smsl-n100 volumio[808]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 01 09:19:02 smsl-n100 volumio[808]: errno: -110, May 01 09:19:02 smsl-n100 volumio[808]: code: 'ETIMEDOUT', May 01 09:19:02 smsl-n100 volumio[808]: syscall: 'connect', May 01 09:19:02 smsl-n100 volumio[808]: address: '199.96.58.85', May 01 09:19:02 smsl-n100 volumio[808]: port: 443 May 01 09:19:02 smsl-n100 volumio[808]: } May 01 09:19:02 smsl-n100 volumio[808]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 09:19:04 smsl-n100 sudo[7299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 09:18 May 01 09:19:04 smsl-n100 sudo[7299]: 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"