-- Logs begin at Tue 2024-12-03 01:53:28 CST, end at Tue 2024-12-03 01:58:05 CST. -- Dec 03 01:57:05 volumio volumio[988]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 13 Dec 03 01:57:05 volumio volumio[988]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 14 Dec 03 01:57:05 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Dec 03 01:57:05 volumio volumio[988]: info: CorePlayQueue::getTrack 226 Dec 03 01:57:07 volumio volumio[988]: info: MyVolumio login type: Token Dec 03 01:57:09 volumio thd[594]: Executing trigger action: /usr/local/bin/volumio toggle Dec 03 01:57:09 volumio thd[594]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 03 01:57:09 volumio thd[594]: Dload Upload Total Spent Left Speed Dec 03 01:57:09 volumio volumio[988]: info: Dec 03 01:57:09 volumio volumio[988]: ---------------------------- Client requests Volumio toggle Dec 03 01:57:09 volumio volumio[988]: info: CoreCommandRouter::volumioToggle Dec 03 01:57:09 volumio volumio[988]: info: CorePlayQueue::getTrack 226 Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::pause Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::stPlaybackTimer Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::servicePause Dec 03 01:57:09 volumio volumio[988]: info: CorePlayQueue::getTrack 226 Dec 03 01:57:09 volumio volumio[988]: info: CoreCommandRouter::servicePause Dec 03 01:57:09 volumio volumio[988]: info: ControllerMpd::pause Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand pause Dec 03 01:57:09 volumio volumio[988]: info: Dec 03 01:57:09 volumio volumio[988]: ---------------------------- MPD announces state update: player Dec 03 01:57:09 volumio volumio[988]: info: sendMpdCommand pause took 1 milliseconds Dec 03 01:57:09 volumio volumio[988]: info: ControllerMpd::getState Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Dec 03 01:57:09 volumio thd[594]: [158B blob data] Dec 03 01:57:09 volumio volumio[988]: info: Dec 03 01:57:09 volumio volumio[988]: ---------------------------- MPD announces state update: player Dec 03 01:57:09 volumio volumio[988]: info: ControllerMpd::getState Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Dec 03 01:57:09 volumio volumio[988]: info: Dec 03 01:57:09 volumio volumio[988]: ---------------------------- MPD announces state update: player Dec 03 01:57:09 volumio volumio[988]: info: ControllerMpd::getState Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Dec 03 01:57:09 volumio volumio[988]: info: Dec 03 01:57:09 volumio volumio[988]: ---------------------------- MPD announces state update: player Dec 03 01:57:09 volumio volumio[988]: info: ControllerMpd::getState Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Dec 03 01:57:09 volumio volumio[988]: info: sendMpdCommand status took 4 milliseconds Dec 03 01:57:09 volumio volumio[988]: info: sendMpdCommand status took 2 milliseconds Dec 03 01:57:09 volumio volumio[988]: info: sendMpdCommand status took 1 milliseconds Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::parseState Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::parseState Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::parseState Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 03 01:57:09 volumio volumio[988]: info: sendMpdCommand status took 2 milliseconds Dec 03 01:57:09 volumio volumio[988]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 03 01:57:09 volumio volumio[988]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 03 01:57:09 volumio volumio[988]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::parseState Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Dec 03 01:57:09 volumio volumio[988]: info: ControllerMpd::pushState Dec 03 01:57:09 volumio volumio[988]: info: CoreCommandRouter::servicePushState Dec 03 01:57:09 volumio volumio[988]: info: CorePlayQueue::getTrack 226 Dec 03 01:57:09 volumio volumio[988]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":30407,"duration":407,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 悲情城市.wav","artist":null,"album":null,"uri":"USB/NEWSMY/发烧完整珍藏套装/发烧完整珍藏套装 31-15/01 悲情城市.wav","trackType":"wav"} Dec 03 01:57:09 volumio volumio[988]: verbose: CURRENT POSITION 226 Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::syncState stateService pause Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus pause Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::pushState Dec 03 01:57:09 volumio volumio[988]: info: CorePlayQueue::getTrack 226 Dec 03 01:57:09 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 01:57:09 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::stPlaybackTimer Dec 03 01:57:09 volumio volumio[988]: info: ControllerMpd::pushState Dec 03 01:57:09 volumio volumio[988]: info: CoreCommandRouter::servicePushState Dec 03 01:57:09 volumio volumio[988]: info: CorePlayQueue::getTrack 226 Dec 03 01:57:09 volumio volumio[988]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":30407,"duration":407,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 悲情城市.wav","artist":null,"album":null,"uri":"USB/NEWSMY/发烧完整珍藏套装/发烧完整珍藏套装 31-15/01 悲情城市.wav","trackType":"wav"} Dec 03 01:57:09 volumio volumio[988]: verbose: CURRENT POSITION 226 Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::syncState stateService pause Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus pause Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::pushState Dec 03 01:57:09 volumio volumio[988]: info: CorePlayQueue::getTrack 226 Dec 03 01:57:09 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::stPlaybackTimer Dec 03 01:57:09 volumio volumio[988]: info: ControllerMpd::pushState Dec 03 01:57:09 volumio volumio[988]: info: CoreCommandRouter::servicePushState Dec 03 01:57:09 volumio volumio[988]: info: CorePlayQueue::getTrack 226 Dec 03 01:57:09 volumio volumio[988]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":30407,"duration":407,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 悲情城市.wav","artist":null,"album":null,"uri":"USB/NEWSMY/发烧完整珍藏套装/发烧完整珍藏套装 31-15/01 悲情城市.wav","trackType":"wav"} Dec 03 01:57:09 volumio volumio[988]: verbose: CURRENT POSITION 226 Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::syncState stateService pause Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus pause Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::pushState Dec 03 01:57:09 volumio volumio[988]: info: CorePlayQueue::getTrack 226 Dec 03 01:57:09 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::stPlaybackTimer Dec 03 01:57:09 volumio volumio[988]: info: ------------------------------ 42ms Dec 03 01:57:09 volumio volumio[988]: info: ------------------------------ 40ms Dec 03 01:57:09 volumio volumio[988]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 03 01:57:09 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Dec 03 01:57:09 volumio volumio[988]: info: ControllerMpd::pushState Dec 03 01:57:09 volumio volumio[988]: info: CoreCommandRouter::servicePushState Dec 03 01:57:09 volumio volumio[988]: info: CorePlayQueue::getTrack 226 Dec 03 01:57:09 volumio volumio[988]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":30407,"duration":407,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 悲情城市.wav","artist":null,"album":null,"uri":"USB/NEWSMY/发烧完整珍藏套装/发烧完整珍藏套装 31-15/01 悲情城市.wav","trackType":"wav"} Dec 03 01:57:09 volumio volumio[988]: verbose: CURRENT POSITION 226 Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::syncState stateService pause Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus pause Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::pushState Dec 03 01:57:09 volumio volumio[988]: info: CorePlayQueue::getTrack 226 Dec 03 01:57:09 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Dec 03 01:57:09 volumio volumio[988]: info: CoreStateMachine::stPlaybackTimer Dec 03 01:57:09 volumio volumio[988]: info: ------------------------------ 53ms Dec 03 01:57:09 volumio sudo[1808]: pam_unix(sudo:session): session closed for user root Dec 03 01:57:20 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Dec 03 01:57:20 volumio volumio[988]: info: CorePlayQueue::getTrack 226 Dec 03 01:57:31 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Dec 03 01:57:31 volumio volumio[988]: info: CorePlayQueue::getTrack 226 Dec 03 01:57:37 volumio volumio[988]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 03 01:57:37 volumio volumio[988]: info: Retrying Login Due to Network Error Dec 03 01:57:42 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Dec 03 01:57:42 volumio volumio[988]: info: CorePlayQueue::getTrack 226 Dec 03 01:57:47 volumio volumio[988]: info: MyVolumio login type: Token Dec 03 01:57:53 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Dec 03 01:57:53 volumio volumio[988]: info: CorePlayQueue::getTrack 226 Dec 03 01:58:04 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Dec 03 01:58:04 volumio volumio[988]: info: CorePlayQueue::getTrack 226 Dec 03 01:58:05 volumio volumio[988]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 01:58:05 volumio volumio[988]: Error: connect ETIMEDOUT 157.240.7.5:80 Dec 03 01:58:05 volumio volumio[988]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 03 01:58:05 volumio volumio[988]: errno: -110, Dec 03 01:58:05 volumio volumio[988]: code: 'ETIMEDOUT', Dec 03 01:58:05 volumio volumio[988]: syscall: 'connect', Dec 03 01:58:05 volumio volumio[988]: address: '157.240.7.5', Dec 03 01:58:05 volumio volumio[988]: port: 80 Dec 03 01:58:05 volumio volumio[988]: } Dec 03 01:58:05 volumio volumio[988]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 01:58:05 volumio sudo[2745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-03 01:57 Dec 03 01:58:05 volumio sudo[2745]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"