-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Tue 2025-04-29 09:25:44 CST. -- Apr 29 09:24:14 volumio volumio[2989]: info: Apr 29 09:24:14 volumio volumio[2989]: ---------------------------- MPD announces state update: player Apr 29 09:24:14 volumio volumio[2989]: info: ControllerMpd::getState Apr 29 09:24:14 volumio volumio[2989]: verbose: ControllerMpd::sendMpdCommand status Apr 29 09:24:14 volumio volumio[2989]: info: sendMpdCommand status took 1 milliseconds Apr 29 09:24:14 volumio volumio[2989]: verbose: ControllerMpd::parseState Apr 29 09:24:14 volumio volumio[2989]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 09:24:14 volumio volumio[2989]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 29 09:24:14 volumio volumio[2989]: verbose: ControllerMpd::parseTrackInfo Apr 29 09:24:14 volumio volumio[2989]: info: ControllerMpd::pushState Apr 29 09:24:14 volumio volumio[2989]: info: CoreCommandRouter::servicePushState Apr 29 09:24:14 volumio volumio[2989]: verbose: In UPNP mode Apr 29 09:24:14 volumio volumio[2989]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22606,"duration":244,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"罗生门（Follow）","artist":"梨冻紧/Wiz_H张子豪","album":"罗生门（Follow）","uri":"http://m801.music.126.net/20250429094851/eb303823fbc27b026a10511530dab794/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/14096444297/c5a0/2580/a9e4/999a4f077c17e140ff120c643b5deb74.mp3?vuutv=O6LDjRuQJ08EqEChzOXSVzlSwVzibQjp27GvGIWq+LLUWJ1K6MyYXUDBoP0eIWXd5KXqUIgXmQ5g8SMPVJoWWJDGnhuPjss3lUmjzuGPRMY=","trackType":""} Apr 29 09:24:14 volumio volumio[2989]: verbose: CURRENT POSITION 0 Apr 29 09:24:14 volumio volumio[2989]: info: CoreStateMachine::syncState stateService pause Apr 29 09:24:14 volumio volumio[2989]: info: CoreStateMachine::syncState currentStatus play Apr 29 09:24:14 volumio volumio[2989]: info: CoreStateMachine::pushState Apr 29 09:24:14 volumio volumio[2989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 09:24:14 volumio volumio[2989]: info: CoreCommandRouter::volumioPushState Apr 29 09:24:14 volumio volumio[2989]: info: CoreStateMachine::stPlaybackTimer Apr 29 09:24:14 volumio volumio[2989]: info: ------------------------------ 19ms Apr 29 09:24:14 volumio volumio[2989]: info: CoreCommandRouter::volumioGetQueue Apr 29 09:24:14 volumio volumio[2989]: info: CoreStateMachine::getQueue Apr 29 09:24:14 volumio volumio[2989]: info: CorePlayQueue::getQueue Apr 29 09:24:23 volumio volumio[2989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 29 09:24:23 volumio volumio[2989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 09:24:23 volumio volumio[2989]: info: Discovery: Getting this device information Apr 29 09:24:23 volumio volumio[2989]: info: CoreCommandRouter::volumioGetState Apr 29 09:24:23 volumio volumio[2989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 09:24:23 volumio volumio[2989]: verbose: New Socket.io Connection to 192.168.1.21:3000 from 192.168.1.13 UA: Dart/3.7 (dart:io) Total Clients: 5 Apr 29 09:24:23 volumio volumio[2989]: info: CoreCommandRouter::volumioGetState Apr 29 09:24:23 volumio volumio[2989]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 29 09:24:23 volumio volumio[2989]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 29 09:24:28 volumio volumio[2989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 29 09:24:28 volumio volumio[2989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 09:24:28 volumio volumio[2989]: info: Discovery: Getting this device information Apr 29 09:24:28 volumio volumio[2989]: info: CoreCommandRouter::volumioGetState Apr 29 09:24:28 volumio volumio[2989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 09:24:44 volumio volumio[2989]: info: Clearing queue after UPNP request Apr 29 09:24:44 volumio volumio[2989]: info: CoreStateMachine::ClearQueue Apr 29 09:24:44 volumio volumio[2989]: info: CoreStateMachine::stop Apr 29 09:24:44 volumio volumio[2989]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 09:24:44 volumio volumio[2989]: info: CoreStateMachine::stPlaybackTimer Apr 29 09:24:44 volumio volumio[2989]: info: CoreStateMachine::updateTrackBlock Apr 29 09:24:44 volumio volumio[2989]: info: CorePlayQueue::getTrackBlock Apr 29 09:24:44 volumio volumio[2989]: info: CoreStateMachine::pushState Apr 29 09:24:44 volumio volumio[2989]: info: CorePlayQueue::getTrack 0 Apr 29 09:24:44 volumio volumio[2989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 09:24:44 volumio volumio[2989]: info: CoreCommandRouter::volumioPushState Apr 29 09:24:44 volumio volumio[2989]: info: CoreStateMachine::serviceStop Apr 29 09:24:44 volumio volumio[2989]: info: CorePlayQueue::getTrack 0 Apr 29 09:24:44 volumio volumio[2989]: info: ControllerMpd::stop Apr 29 09:24:44 volumio volumio[2989]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 09:24:44 volumio volumio[2989]: info: CorePlayQueue::clearPlayQueue Apr 29 09:24:44 volumio volumio[2989]: info: CorePlayQueue::saveQueue Apr 29 09:24:44 volumio volumio[2989]: info: CoreCommandRouter::volumioPushState Apr 29 09:24:44 volumio volumio[2989]: info: CoreCommandRouter::volumioPushQueue Apr 29 09:24:44 volumio volumio[2989]: info: Apr 29 09:24:44 volumio volumio[2989]: ---------------------------- MPD announces state update: player Apr 29 09:24:44 volumio volumio[2989]: info: sendMpdCommand stop took 33 milliseconds Apr 29 09:24:44 volumio volumio[2989]: info: ControllerMpd::getState Apr 29 09:24:44 volumio volumio[2989]: verbose: ControllerMpd::sendMpdCommand status Apr 29 09:24:44 volumio volumio[2989]: info: Apr 29 09:24:44 volumio volumio[2989]: ---------------------------- MPD announces system playlist update Apr 29 09:24:44 volumio volumio[2989]: info: Ignoring MPD Status Update Apr 29 09:24:44 volumio volumio[2989]: info: sendMpdCommand status took 3 milliseconds Apr 29 09:24:44 volumio volumio[2989]: verbose: ControllerMpd::parseState Apr 29 09:24:44 volumio volumio[2989]: info: ControllerMpd::pushState Apr 29 09:24:44 volumio volumio[2989]: info: CoreCommandRouter::servicePushState Apr 29 09:24:44 volumio volumio[2989]: info: CoreStateMachine::pushState Apr 29 09:24:44 volumio volumio[2989]: info: CorePlayQueue::getTrack 0 Apr 29 09:24:44 volumio volumio[2989]: info: CoreCommandRouter::volumioPushState Apr 29 09:24:44 volumio volumio[2989]: info: CorePlayQueue::getTrack 0 Apr 29 09:24:44 volumio volumio[2989]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 29 09:24:44 volumio volumio[2989]: verbose: CURRENT POSITION 0 Apr 29 09:24:44 volumio volumio[2989]: info: CoreStateMachine::syncState stateService stop Apr 29 09:24:44 volumio volumio[2989]: info: CoreStateMachine::syncState currentStatus stop Apr 29 09:24:44 volumio volumio[2989]: info: CoreStateMachine::pushState Apr 29 09:24:44 volumio volumio[2989]: info: CorePlayQueue::getTrack 0 Apr 29 09:24:44 volumio volumio[2989]: info: CoreCommandRouter::volumioPushState Apr 29 09:24:44 volumio volumio[2989]: info: No code Apr 29 09:24:44 volumio volumio[2989]: info: CoreStateMachine::pushState Apr 29 09:24:44 volumio volumio[2989]: info: CorePlayQueue::getTrack 0 Apr 29 09:24:44 volumio volumio[2989]: info: CoreCommandRouter::volumioPushState Apr 29 09:24:44 volumio volumio[2989]: info: ------------------------------ 54ms Apr 29 09:24:44 volumio volumio[2989]: error: updateQueue error: null Apr 29 09:24:44 volumio volumio[2989]: info: ------------------------------ 54ms Apr 29 09:24:44 volumio volumio[2989]: info: Starting UPNP Playback Apr 29 09:24:44 volumio volumio[2989]: info: Preparing playback through UPNP Apr 29 09:24:44 volumio volumio[2989]: info: CoreCommandRouter::volumioGetState Apr 29 09:24:44 volumio volumio[2989]: info: CorePlayQueue::getTrack 0 Apr 29 09:24:44 volumio volumio[2989]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 09:24:44 volumio volumio[2989]: info: Apr 29 09:24:44 volumio volumio[2989]: ---------------------------- MPD announces system playlist update Apr 29 09:24:44 volumio volumio[2989]: info: Ignoring MPD Status Update Apr 29 09:24:44 volumio volumio[2989]: info: ------------------------------ 4ms Apr 29 09:24:44 volumio volumio[2989]: info: Apr 29 09:24:44 volumio volumio[2989]: ---------------------------- MPD announces system playlist update Apr 29 09:24:44 volumio volumio[2989]: info: Ignoring MPD Status Update Apr 29 09:24:44 volumio volumio[2989]: info: ------------------------------ 1ms Apr 29 09:24:44 volumio volumio[2989]: info: Apr 29 09:24:44 volumio volumio[2989]: ---------------------------- MPD announces system playlist update Apr 29 09:24:44 volumio volumio[2989]: info: Ignoring MPD Status Update Apr 29 09:24:44 volumio volumio[2989]: info: ------------------------------ 1ms Apr 29 09:24:44 volumio volumio[2989]: info: Apr 29 09:24:44 volumio volumio[2989]: ---------------------------- MPD announces system playlist update Apr 29 09:24:44 volumio volumio[2989]: info: Ignoring MPD Status Update Apr 29 09:24:45 volumio volumio[2989]: info: ------------------------------ 1ms Apr 29 09:24:45 volumio volumio[2989]: info: Apr 29 09:24:45 volumio volumio[2989]: ---------------------------- MPD announces system playlist update Apr 29 09:24:45 volumio volumio[2989]: info: Ignoring MPD Status Update Apr 29 09:24:45 volumio volumio[2989]: info: ------------------------------ 2ms Apr 29 09:24:45 volumio volumio[2989]: info: Apr 29 09:24:45 volumio volumio[2989]: ---------------------------- MPD announces system playlist update Apr 29 09:24:45 volumio volumio[2989]: info: Ignoring MPD Status Update Apr 29 09:24:45 volumio volumio[2989]: info: ------------------------------ 1ms Apr 29 09:24:45 volumio volumio[2989]: info: Apr 29 09:24:45 volumio volumio[2989]: ---------------------------- MPD announces state update: player Apr 29 09:24:45 volumio volumio[2989]: info: ControllerMpd::getState Apr 29 09:24:45 volumio volumio[2989]: verbose: ControllerMpd::sendMpdCommand status Apr 29 09:24:45 volumio volumio[2989]: info: Apr 29 09:24:45 volumio volumio[2989]: ---------------------------- MPD announces state update: player Apr 29 09:24:45 volumio volumio[2989]: info: sendMpdCommand status took 5 milliseconds Apr 29 09:24:45 volumio volumio[2989]: info: ControllerMpd::getState Apr 29 09:24:45 volumio volumio[2989]: verbose: ControllerMpd::sendMpdCommand status Apr 29 09:24:45 volumio volumio[2989]: verbose: ControllerMpd::parseState Apr 29 09:24:45 volumio volumio[2989]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 09:24:45 volumio volumio[2989]: info: sendMpdCommand status took 3 milliseconds Apr 29 09:24:45 volumio volumio[2989]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 29 09:24:45 volumio volumio[2989]: verbose: ControllerMpd::parseState Apr 29 09:24:45 volumio volumio[2989]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 09:24:45 volumio volumio[2989]: verbose: ControllerMpd::parseTrackInfo Apr 29 09:24:45 volumio volumio[2989]: info: ControllerMpd::pushState Apr 29 09:24:45 volumio volumio[2989]: info: CoreCommandRouter::servicePushState Apr 29 09:24:45 volumio volumio[2989]: verbose: In UPNP mode Apr 29 09:24:45 volumio volumio[2989]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"小孩 (Live)","artist":"张碧晨/武艺","album":"音你而来第二季 第1期","uri":"http://m801.music.126.net/20250429094943/599919fb6f9e52222ccce243dcedd90a/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59114584991/793b/6786/c3ae/8510d38037ad6c9905967412b60e93dc.mp3?vuutv=BcFVcGv86i1fC37JPHAat4QAwgWsRWciSZgIjPFpa5yzgNqiiW1OnMbZb4cP0W1WM9PiESKeMlyBelmL8z1jrK9rwZrBr/r0u54MyLDCjKQ=","trackType":""} Apr 29 09:24:45 volumio volumio[2989]: verbose: CURRENT POSITION 0 Apr 29 09:24:45 volumio volumio[2989]: info: CoreStateMachine::syncState stateService play Apr 29 09:24:45 volumio volumio[2989]: info: CoreStateMachine::syncState currentStatus stop Apr 29 09:24:45 volumio volumio[2989]: info: ------------------------------ 18ms Apr 29 09:24:45 volumio volumio[2989]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 29 09:24:45 volumio volumio[2989]: verbose: ControllerMpd::parseTrackInfo Apr 29 09:24:45 volumio volumio[2989]: info: ControllerMpd::pushState Apr 29 09:24:45 volumio volumio[2989]: info: CoreCommandRouter::servicePushState Apr 29 09:24:45 volumio volumio[2989]: verbose: In UPNP mode Apr 29 09:24:45 volumio volumio[2989]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"小孩 (Live)","artist":"张碧晨/武艺","album":"音你而来第二季 第1期","uri":"http://m801.music.126.net/20250429094943/599919fb6f9e52222ccce243dcedd90a/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59114584991/793b/6786/c3ae/8510d38037ad6c9905967412b60e93dc.mp3?vuutv=BcFVcGv86i1fC37JPHAat4QAwgWsRWciSZgIjPFpa5yzgNqiiW1OnMbZb4cP0W1WM9PiESKeMlyBelmL8z1jrK9rwZrBr/r0u54MyLDCjKQ=","trackType":""} Apr 29 09:24:45 volumio volumio[2989]: verbose: CURRENT POSITION 0 Apr 29 09:24:45 volumio volumio[2989]: info: CoreStateMachine::syncState stateService play Apr 29 09:24:45 volumio volumio[2989]: info: CoreStateMachine::syncState currentStatus play Apr 29 09:24:45 volumio volumio[2989]: info: Received an update from plugin. extracting info from payload Apr 29 09:24:45 volumio volumio[2989]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 09:24:45 volumio volumio[2989]: info: CoreStateMachine::pushState Apr 29 09:24:45 volumio volumio[2989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 09:24:45 volumio volumio[2989]: info: CoreCommandRouter::volumioPushState Apr 29 09:24:45 volumio volumio[2989]: info: CoreStateMachine::pushState Apr 29 09:24:45 volumio volumio[2989]: info: CoreCommandRouter::volumioPushState Apr 29 09:24:45 volumio volumio[2989]: info: ------------------------------ 42ms Apr 29 09:24:45 volumio volumio[2989]: info: CoreCommandRouter::volumioGetQueue Apr 29 09:24:45 volumio volumio[2989]: info: CoreStateMachine::getQueue Apr 29 09:24:45 volumio volumio[2989]: info: CorePlayQueue::getQueue Apr 29 09:24:45 volumio volumio[2989]: info: CoreCommandRouter::volumioGetQueue Apr 29 09:24:45 volumio volumio[2989]: info: CoreStateMachine::getQueue Apr 29 09:24:45 volumio volumio[2989]: info: CorePlayQueue::getQueue Apr 29 09:25:05 volumio volumio[2308]: 95 Apr 29 09:25:05 volumio volumio[2308]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 29 09:25:05 volumio volumio[2308]: Dload Upload Total Spent Left Speed Apr 29 09:25:05 volumio volumio[2989]: info: Apr 29 09:25:05 volumio volumio[2989]: ---------------------------- Client requests Volume 95 Apr 29 09:25:05 volumio volumio[2989]: info: VolumeController::SetAlsaVolume95 Apr 29 09:25:05 volumio volumio[2989]: info: CoreStateMachine::pushState Apr 29 09:25:05 volumio volumio[2989]: info: CoreCommandRouter::volumioPushState Apr 29 09:25:05 volumio volumio[2308]: [158B blob data] Apr 29 09:25:05 volumio volumio[2308]: {"time":1745889905761,"response":"volume Success"}95 Apr 29 09:25:05 volumio volumio[2989]: info: CoreCommandRouter::volumioGetQueue Apr 29 09:25:05 volumio volumio[2989]: info: CoreStateMachine::getQueue Apr 29 09:25:05 volumio volumio[2989]: info: CorePlayQueue::getQueue Apr 29 09:25:07 volumio volumio[2308]: 95 Apr 29 09:25:07 volumio volumio[2308]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 29 09:25:07 volumio volumio[2308]: Dload Upload Total Spent Left Speed Apr 29 09:25:07 volumio volumio[2989]: info: Apr 29 09:25:07 volumio volumio[2989]: ---------------------------- Client requests Volume 95 Apr 29 09:25:07 volumio volumio[2989]: info: VolumeController::SetAlsaVolume95 Apr 29 09:25:07 volumio volumio[2989]: info: CoreStateMachine::pushState Apr 29 09:25:07 volumio volumio[2989]: info: CoreCommandRouter::volumioPushState Apr 29 09:25:07 volumio volumio[2308]: [158B blob data] Apr 29 09:25:07 volumio volumio[2308]: {"time":1745889907062,"response":"volume Success"}95 Apr 29 09:25:07 volumio volumio[2989]: info: CoreCommandRouter::volumioGetQueue Apr 29 09:25:07 volumio volumio[2989]: info: CoreStateMachine::getQueue Apr 29 09:25:07 volumio volumio[2989]: info: CorePlayQueue::getQueue Apr 29 09:25:27 volumio nmbd[1612]: [2025/04/29 09:25:27.244381, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 29 09:25:27 volumio nmbd[1612]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.21 for name WORKGROUP<1d>. Apr 29 09:25:27 volumio nmbd[1612]: This response was from IP 192.168.1.76, reporting an IP address of 192.168.1.76. Apr 29 09:25:43 volumio volumio[2989]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 09:25:43 volumio volumio[2989]: Error: connect ETIMEDOUT 199.59.149.234:443 Apr 29 09:25:43 volumio volumio[2989]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 29 09:25:43 volumio volumio[2989]: errno: -110, Apr 29 09:25:43 volumio volumio[2989]: code: 'ETIMEDOUT', Apr 29 09:25:43 volumio volumio[2989]: syscall: 'connect', Apr 29 09:25:43 volumio volumio[2989]: address: '199.59.149.234', Apr 29 09:25:43 volumio volumio[2989]: port: 443 Apr 29 09:25:43 volumio volumio[2989]: } Apr 29 09:25:43 volumio volumio[2989]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 09:25:44 volumio sudo[3713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-29 09:24 Apr 29 09:25:44 volumio sudo[3713]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 21 Oct 2023 12:03:06 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="rockpi-4b" VOLUMIO_DEVICENAME="RockPi 4B" VOLUMIO_HASH="da788f2726fdecb5b64e1a26b3349aeb"