-- Logs begin at Tue 2025-02-25 16:21:10 +07, end at Tue 2025-02-25 20:07:34 +07. -- Feb 25 20:06:00 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:00 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:00 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 20:06:00 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:00 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:00 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:00 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:00 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:00 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:00 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:01 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:01 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:01 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 20:06:01 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:01 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:01 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:01 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:01 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:01 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:01 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:02 volumio ntpd[1032]: Soliciting pool server 103.184.124.254 Feb 25 20:06:02 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:02 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:02 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 20:06:02 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:02 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:02 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:02 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:02 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:02 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:02 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:03 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:03 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:03 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 20:06:03 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:03 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:03 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:03 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:03 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:03 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:03 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:04 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:04 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:04 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 20:06:04 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:04 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:04 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:04 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:04 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:04 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:04 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:04 volumio go-librespot[1955]: time="2025-02-25T20:06:04+07:00" level=trace msg="sent dealer ping" Feb 25 20:06:04 volumio go-librespot[1955]: time="2025-02-25T20:06:04+07:00" level=trace msg="received dealer pong" Feb 25 20:06:05 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:05 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:05 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 20:06:05 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:05 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:05 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:05 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:05 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:05 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:05 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:06 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:06 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:06 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 20:06:06 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:06 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:06 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:06 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:06 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:06 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:06 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:07 volumio ntpd[1032]: Soliciting pool server 103.70.115.65 Feb 25 20:06:07 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:07 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:07 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 20:06:07 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:07 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:07 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:07 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:07 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:07 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:07 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:08 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:08 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:08 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 20:06:08 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:08 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:08 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:08 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:08 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:08 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:08 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:09 volumio ntpd[1032]: Soliciting pool server 103.70.115.65 Feb 25 20:06:09 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:09 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:09 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 20:06:09 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:09 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:09 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:09 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:09 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:09 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:09 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:10 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:10 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:10 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 20:06:10 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:10 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:10 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:10 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:10 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:10 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:10 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:11 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:11 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:11 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 20:06:11 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:11 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:11 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:11 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:11 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:11 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:11 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:12 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:12 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:12 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 20:06:12 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:12 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:12 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:12 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:12 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:12 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:12 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:13 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:13 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:13 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 20:06:13 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:13 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:13 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:13 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:13 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:13 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:13 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:14 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 20:06:14 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 20:06:14 volumio volumio[1419]: info: Discovery: Getting this device information Feb 25 20:06:14 volumio volumio[1419]: info: CoreCommandRouter::volumioGetState Feb 25 20:06:14 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 20:06:14 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:14 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:14 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 20:06:14 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:14 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:14 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:14 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:14 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:14 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:14 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:20 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:20 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:20 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 20:06:20 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:20 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:20 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:20 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:20 volumio volumio[1419]: info: CoreCommandRouter::servicePushState Feb 25 20:06:20 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:20 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:20 volumio shairport-sync[2003]: {"time":1740488745308,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Feb 25 20:06:20 volumio shairport-sync[2003]: Dload Upload Total Spent Left Speed Feb 25 20:06:20 volumio volumio[1419]: info: Feb 25 20:06:20 volumio volumio[1419]: ---------------------------- Client requests Stop Airplay Playback Feb 25 20:06:20 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Feb 25 20:06:20 volumio volumio[1419]: info: Airplay playback stop Feb 25 20:06:20 volumio volumio[1419]: info: Airplay Playback Stopped, clearing state Feb 25 20:06:20 volumio volumio[1419]: info: CoreStateMachine::resetVolumioState Feb 25 20:06:20 volumio volumio[1419]: info: CoreStateMachine::getcurrentVolume Feb 25 20:06:20 volumio volumio[1419]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 20:06:20 volumio shairport-sync[2003]: [158B blob data] Feb 25 20:06:20 volumio volumio[1419]: info: CoreCommandRouter::volumioGetState Feb 25 20:06:20 volumio volumio[1419]: info: CoreStateMachine::pushState Feb 25 20:06:20 volumio volumio[1419]: info: CoreCommandRouter::volumioPushState Feb 25 20:06:21 volumio kernel: nouveau 0000:01:00.0: Enabling HDA controller Feb 25 20:06:21 volumio kernel: snd_hda_intel 0000:01:00.1: IRQ timing workaround is activated for card #1. Suggest a bigger bdl_pos_adj. Feb 25 20:06:34 volumio go-librespot[1955]: time="2025-02-25T20:06:34+07:00" level=trace msg="sent dealer ping" Feb 25 20:06:34 volumio go-librespot[1955]: time="2025-02-25T20:06:34+07:00" level=trace msg="received dealer pong" Feb 25 20:06:35 volumio volumio[1419]: error: error Feb 25 20:06:36 volumio volumio[1419]: error: error Feb 25 20:06:44 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 20:06:44 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 20:06:44 volumio volumio[1419]: info: Discovery: Getting this device information Feb 25 20:06:44 volumio volumio[1419]: info: CoreCommandRouter::volumioGetState Feb 25 20:06:44 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 20:07:02 volumio ntpd[1032]: Soliciting pool server 2a0c:b847:ffff:79::e53c Feb 25 20:07:04 volumio go-librespot[1955]: time="2025-02-25T20:07:04+07:00" level=trace msg="sent dealer ping" Feb 25 20:07:04 volumio go-librespot[1955]: time="2025-02-25T20:07:04+07:00" level=trace msg="received dealer pong" Feb 25 20:07:06 volumio ntpd[1032]: Soliciting pool server 103.199.19.135 Feb 25 20:07:13 volumio ntpd[1032]: Soliciting pool server 103.184.124.254 Feb 25 20:07:13 volumio ntpd[1032]: Soliciting pool server 27.71.27.209 Feb 25 20:07:14 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 20:07:14 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 20:07:14 volumio volumio[1419]: info: Discovery: Getting this device information Feb 25 20:07:14 volumio volumio[1419]: info: CoreCommandRouter::volumioGetState Feb 25 20:07:14 volumio volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 20:07:25 volumio volumio[1419]: error: error Feb 25 20:07:25 volumio volumio[1419]: error: error Feb 25 20:07:34 volumio volumio[1419]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 20:07:34 volumio volumio[1419]: Error: connect ETIMEDOUT 151.101.130.79:443 Feb 25 20:07:34 volumio volumio[1419]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 25 20:07:34 volumio volumio[1419]: errno: -110, Feb 25 20:07:34 volumio volumio[1419]: code: 'ETIMEDOUT', Feb 25 20:07:34 volumio volumio[1419]: syscall: 'connect', Feb 25 20:07:34 volumio volumio[1419]: address: '151.101.130.79', Feb 25 20:07:34 volumio volumio[1419]: port: 443 Feb 25 20:07:34 volumio volumio[1419]: } Feb 25 20:07:34 volumio volumio[1419]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 20:07:34 volumio sudo[9287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-25 20:06 Feb 25 20:07:34 volumio sudo[9287]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"