-- Logs begin at Thu 2025-03-13 06:37:03 CST, end at Thu 2025-03-13 08:27:14 CST. -- Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreCommandRouter::servicePushState Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreCommandRouter::servicePushState Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreCommandRouter::servicePushState Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:01 smsl-n100 shairport-sync[14266]: {"time":1741825499785,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Mar 13 08:26:01 smsl-n100 shairport-sync[14266]: Dload Upload Total Spent Left Speed Mar 13 08:26:01 smsl-n100 volumio[14004]: info: Mar 13 08:26:01 smsl-n100 volumio[14004]: ---------------------------- Client requests Stop Airplay Playback Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Mar 13 08:26:01 smsl-n100 volumio[14004]: info: Airplay playback stop Mar 13 08:26:01 smsl-n100 volumio[14004]: info: Airplay Playback Stopped, clearing state Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreStateMachine::resetVolumioState Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreStateMachine::getcurrentVolume Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioRetrievevolume Mar 13 08:26:01 smsl-n100 shairport-sync[14266]: [158B blob data] Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 08:26:01 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:02 smsl-n100 volumio[14004]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 13 08:26:02 smsl-n100 volumio[14004]: info: Retrying Login Due to Network Error Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioGetState Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioGetState Mar 13 08:26:07 smsl-n100 volumio[14004]: info: Currently active: airplay_emulation Mar 13 08:26:07 smsl-n100 volumio[14004]: info: Stopping currently active service Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioStop Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreStateMachine::stop Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreStateMachine::serviceStop Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreCommandRouter::serviceStop Mar 13 08:26:07 smsl-n100 volumio[14004]: info: Airplay Stop Mar 13 08:26:07 smsl-n100 volumio[14004]: info: Stopping Airplay Playback and sending pause command to client via USR2 Mar 13 08:26:07 smsl-n100 sudo[22767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 14266 Mar 13 08:26:07 smsl-n100 sudo[22767]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 08:26:07 smsl-n100 sudo[22767]: pam_unix(sudo:session): session closed for user root Mar 13 08:26:07 smsl-n100 volumio[14004]: info: Shairport-Sync paused with USR2 Mar 13 08:26:07 smsl-n100 volumio[14004]: info: TidalConnect Active Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioGetState Mar 13 08:26:07 smsl-n100 volumio[14004]: info: Currently active: airplay_emulation Mar 13 08:26:07 smsl-n100 volumio[14004]: info: Setting Volatile state to tidalconnect Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreCommandRouter::servicePushState Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CorePlayQueue::getTrack 0 Mar 13 08:26:07 smsl-n100 volumio[14004]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Mar 13 08:26:07 smsl-n100 volumio[14004]: verbose: CURRENT POSITION 0 Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreStateMachine::syncState stateService stop Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreStateMachine::syncState currentStatus stop Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:07 smsl-n100 volumio[14004]: info: No code Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreCommandRouter::servicePushState Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CorePlayQueue::getTrack 0 Mar 13 08:26:07 smsl-n100 volumio[14004]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Mar 13 08:26:07 smsl-n100 volumio[14004]: verbose: CURRENT POSITION 0 Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreStateMachine::syncState stateService stop Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreStateMachine::syncState currentStatus stop Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:07 smsl-n100 volumio[14004]: info: No code Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:07 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:08 smsl-n100 volumio[14004]: info: Restarting Shairport-Sync after stop Mar 13 08:26:08 smsl-n100 vtcs[14281]: [2025-03-13 08:26:08.379] [tisoc] [warning] [PlaybackControllerImpl.cpp:510] Illegal state:1 Mar 13 08:26:08 smsl-n100 volumio[14004]: info: Pushing metadata Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreCommandRouter::servicePushState Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CorePlayQueue::getTrack 0 Mar 13 08:26:08 smsl-n100 volumio[14004]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"On Being","artist":"Max Cooper, Felix Gerbelot","album":"On Being","albumart":"https://resources.tidal.com/images/fe0bd5c7/d60c/4ea2/841b/ad1b422af99a/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":281,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Mar 13 08:26:08 smsl-n100 volumio[14004]: verbose: CURRENT POSITION 0 Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreStateMachine::syncState stateService stop Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreStateMachine::syncState currentStatus stop Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:08 smsl-n100 sudo[22775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 13 08:26:08 smsl-n100 sudo[22775]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 08:26:08 smsl-n100 volumio[14004]: info: No code Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreCommandRouter::servicePushState Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CorePlayQueue::getTrack 0 Mar 13 08:26:08 smsl-n100 volumio[14004]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"On Being","artist":"Max Cooper, Felix Gerbelot","album":"On Being","albumart":"https://resources.tidal.com/images/fe0bd5c7/d60c/4ea2/841b/ad1b422af99a/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":281,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Mar 13 08:26:08 smsl-n100 volumio[14004]: verbose: CURRENT POSITION 0 Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreStateMachine::syncState stateService stop Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreStateMachine::syncState currentStatus stop Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:08 smsl-n100 volumio[14004]: info: No code Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:08 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 13 08:26:08 smsl-n100 shairport-sync[14266]: {"time":1741825561245,"response":"stopAirplayPlayback Success"} Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreCommandRouter::servicePushState Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 08:26:08 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:08 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Mar 13 08:26:08 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 13 08:26:08 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 13 08:26:08 smsl-n100 sudo[22775]: pam_unix(sudo:session): session closed for user root Mar 13 08:26:08 smsl-n100 volumio[14004]: info: Shairport-Sync retarted Mar 13 08:26:09 smsl-n100 volumio[14004]: info: Pushing metadata Mar 13 08:26:09 smsl-n100 volumio[14004]: info: CoreCommandRouter::servicePushState Mar 13 08:26:09 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:09 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:09 smsl-n100 volumio[14004]: info: CoreCommandRouter::servicePushState Mar 13 08:26:09 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:09 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:10 smsl-n100 volumio[14004]: info: Executing endpoint metavolumio Mar 13 08:26:10 smsl-n100 volumio[14004]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 08:26:10 smsl-n100 volumio[14004]: info: Executing endpoint metavolumio Mar 13 08:26:10 smsl-n100 volumio[14004]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 08:26:10 smsl-n100 volumio[14004]: info: Executing endpoint metavolumio Mar 13 08:26:10 smsl-n100 volumio[14004]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 08:26:10 smsl-n100 volumio[14004]: info: CoreCommandRouter::servicePushState Mar 13 08:26:10 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:10 smsl-n100 volumio[14004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 08:26:10 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:10 smsl-n100 volumio[14004]: info: CoreCommandRouter::servicePushState Mar 13 08:26:10 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:10 smsl-n100 volumio[14004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 08:26:10 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:10 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:10 smsl-n100 volumio[14004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 08:26:10 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:10 smsl-n100 volumio[14004]: error: Serial API: Failed to decode command: 100, message: undefined Mar 13 08:26:10 smsl-n100 volumio[14004]: error: Serial API: Failed to decode command: MAXVOL, message: Mar 13 08:26:10 smsl-n100 volumio[14004]: error: Serial API: Failed to decode command: 100, message: undefined Mar 13 08:26:10 smsl-n100 volumio[14004]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Mar 13 08:26:11 smsl-n100 volumio[14004]: info: VolumeController::SetAlsaVolume0 Mar 13 08:26:11 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:11 smsl-n100 volumio[14004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 08:26:11 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:11 smsl-n100 volumio[14004]: info: CoreCommandRouter::servicePushState Mar 13 08:26:11 smsl-n100 volumio[14004]: info: CoreStateMachine::pushState Mar 13 08:26:11 smsl-n100 volumio[14004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 08:26:11 smsl-n100 volumio[14004]: info: CoreCommandRouter::volumioPushState Mar 13 08:26:12 smsl-n100 volumio[14004]: info: MyVolumio login type: Token Mar 13 08:26:18 smsl-n100 volumio[14004]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 13 08:26:18 smsl-n100 volumio[14004]: info: Retrying Login Due to Network Error Mar 13 08:26:19 smsl-n100 volumio[14004]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 13 08:26:19 smsl-n100 volumio[14004]: info: Retrying Login Due to Network Error Mar 13 08:26:28 smsl-n100 volumio[14004]: info: MyVolumio login type: Token Mar 13 08:26:29 smsl-n100 volumio[14004]: info: MyVolumio login type: Token Mar 13 08:26:42 smsl-n100 volumio[14004]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 13 08:26:42 smsl-n100 volumio[14004]: info: Retrying Login Due to Network Error Mar 13 08:26:52 smsl-n100 volumio[14004]: info: MyVolumio login type: Token Mar 13 08:26:58 smsl-n100 volumio[14004]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 13 08:26:58 smsl-n100 volumio[14004]: info: Retrying Login Due to Network Error Mar 13 08:26:59 smsl-n100 volumio[14004]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 13 08:26:59 smsl-n100 volumio[14004]: info: Retrying Login Due to Network Error Mar 13 08:27:08 smsl-n100 volumio[14004]: info: MyVolumio login type: Token Mar 13 08:27:09 smsl-n100 volumio[14004]: info: MyVolumio login type: Token Mar 13 08:27:13 smsl-n100 volumio[14004]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 08:27:13 smsl-n100 volumio[14004]: Error: connect ETIMEDOUT 108.160.172.200:443 Mar 13 08:27:13 smsl-n100 volumio[14004]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 13 08:27:13 smsl-n100 volumio[14004]: errno: -110, Mar 13 08:27:13 smsl-n100 volumio[14004]: code: 'ETIMEDOUT', Mar 13 08:27:13 smsl-n100 volumio[14004]: syscall: 'connect', Mar 13 08:27:13 smsl-n100 volumio[14004]: address: '108.160.172.200', Mar 13 08:27:13 smsl-n100 volumio[14004]: port: 443 Mar 13 08:27:13 smsl-n100 volumio[14004]: } Mar 13 08:27:13 smsl-n100 volumio[14004]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 08:27:14 smsl-n100 sudo[22950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-13 08:26 Mar 13 08:27:14 smsl-n100 sudo[22950]: 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"