-- Logs begin at Mon 2024-08-19 23:31:49 CST, end at Tue 2024-08-20 00:08:08 CST. -- Aug 20 00:07:06 volumio volumio[890]: info: Clearing queue after UPNP request Aug 20 00:07:06 volumio volumio[890]: info: CoreStateMachine::ClearQueue Aug 20 00:07:06 volumio volumio[890]: info: CoreStateMachine::stop Aug 20 00:07:06 volumio volumio[890]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 00:07:06 volumio volumio[890]: info: CorePlayQueue::clearPlayQueue Aug 20 00:07:06 volumio volumio[890]: info: CorePlayQueue::saveQueue Aug 20 00:07:06 volumio volumio[890]: info: CoreCommandRouter::volumioPushState Aug 20 00:07:06 volumio volumio[890]: info: CoreCommandRouter::volumioPushQueue Aug 20 00:07:07 volumio volumio[890]: info: Aug 20 00:07:07 volumio volumio[890]: ---------------------------- MPD announces system playlist update Aug 20 00:07:07 volumio volumio[890]: info: Ignoring MPD Status Update Aug 20 00:07:07 volumio volumio[890]: error: updateQueue error: null Aug 20 00:07:07 volumio volumio[890]: info: ------------------------------ 1ms Aug 20 00:07:07 volumio volumio[890]: info: Starting UPNP Playback Aug 20 00:07:07 volumio volumio[890]: info: Preparing playback through UPNP Aug 20 00:07:07 volumio volumio[890]: info: CoreCommandRouter::volumioGetState Aug 20 00:07:07 volumio volumio[890]: info: CorePlayQueue::getTrack 0 Aug 20 00:07:07 volumio volumio[890]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 20 00:07:07 volumio volumio[890]: info: Aug 20 00:07:07 volumio volumio[890]: ---------------------------- MPD announces system playlist update Aug 20 00:07:07 volumio volumio[890]: info: Ignoring MPD Status Update Aug 20 00:07:07 volumio volumio[890]: info: ------------------------------ 1ms Aug 20 00:07:07 volumio volumio[890]: info: Aug 20 00:07:07 volumio volumio[890]: ---------------------------- MPD announces system playlist update Aug 20 00:07:07 volumio volumio[890]: info: Ignoring MPD Status Update Aug 20 00:07:07 volumio volumio[890]: info: ------------------------------ 1ms Aug 20 00:07:07 volumio volumio[890]: info: Aug 20 00:07:07 volumio volumio[890]: ---------------------------- MPD announces system playlist update Aug 20 00:07:07 volumio volumio[890]: info: Ignoring MPD Status Update Aug 20 00:07:07 volumio volumio[890]: info: ------------------------------ 1ms Aug 20 00:07:07 volumio volumio[890]: info: Aug 20 00:07:07 volumio volumio[890]: ---------------------------- MPD announces system playlist update Aug 20 00:07:07 volumio volumio[890]: info: Ignoring MPD Status Update Aug 20 00:07:07 volumio volumio[890]: info: ------------------------------ 1ms Aug 20 00:07:07 volumio volumio[890]: info: Aug 20 00:07:07 volumio volumio[890]: ---------------------------- MPD announces system playlist update Aug 20 00:07:07 volumio volumio[890]: info: Ignoring MPD Status Update Aug 20 00:07:07 volumio volumio[890]: info: ------------------------------ 1ms Aug 20 00:07:07 volumio volumio[890]: info: Aug 20 00:07:07 volumio volumio[890]: ---------------------------- MPD announces system playlist update Aug 20 00:07:07 volumio volumio[890]: info: Ignoring MPD Status Update Aug 20 00:07:07 volumio volumio[890]: info: ------------------------------ 1ms Aug 20 00:07:08 volumio volumio[890]: info: Aug 20 00:07:08 volumio volumio[890]: ---------------------------- MPD announces state update: player Aug 20 00:07:08 volumio volumio[890]: info: ControllerMpd::getState Aug 20 00:07:08 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand status Aug 20 00:07:08 volumio volumio[890]: info: Aug 20 00:07:08 volumio volumio[890]: ---------------------------- MPD announces state update: player Aug 20 00:07:08 volumio volumio[890]: info: sendMpdCommand status took 3 milliseconds Aug 20 00:07:08 volumio volumio[890]: info: ControllerMpd::getState Aug 20 00:07:08 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand status Aug 20 00:07:08 volumio volumio[890]: verbose: ControllerMpd::parseState Aug 20 00:07:08 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 00:07:08 volumio volumio[890]: info: sendMpdCommand status took 1 milliseconds Aug 20 00:07:08 volumio volumio[890]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 20 00:07:08 volumio volumio[890]: verbose: ControllerMpd::parseState Aug 20 00:07:08 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 00:07:08 volumio volumio[890]: verbose: ControllerMpd::parseTrackInfo Aug 20 00:07:08 volumio volumio[890]: info: ControllerMpd::pushState Aug 20 00:07:08 volumio volumio[890]: info: CoreCommandRouter::servicePushState Aug 20 00:07:08 volumio volumio[890]: verbose: In UPNP mode Aug 20 00:07:08 volumio volumio[890]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":673,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"盖世英雄","artist":"王力宏/欧阳靖","album":"盖世英雄","uri":"http://m701.music.126.net/20240820003206/3290b3d8fc7160a33929bb141fcc4c26/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/28557357014/534d/3432/970d/b5dbc39039819cf9782f349f702b8680.flac","trackType":"flac"} Aug 20 00:07:08 volumio volumio[890]: verbose: CURRENT POSITION 0 Aug 20 00:07:08 volumio volumio[890]: info: CoreStateMachine::syncState stateService play Aug 20 00:07:08 volumio volumio[890]: info: CoreStateMachine::syncState currentStatus stop Aug 20 00:07:08 volumio volumio[890]: info: ------------------------------ 20ms Aug 20 00:07:08 volumio volumio[890]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 20 00:07:08 volumio volumio[890]: verbose: ControllerMpd::parseTrackInfo Aug 20 00:07:08 volumio volumio[890]: info: ControllerMpd::pushState Aug 20 00:07:08 volumio volumio[890]: info: CoreCommandRouter::servicePushState Aug 20 00:07:08 volumio volumio[890]: verbose: In UPNP mode Aug 20 00:07:08 volumio volumio[890]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":673,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"盖世英雄","artist":"王力宏/欧阳靖","album":"盖世英雄","uri":"http://m701.music.126.net/20240820003206/3290b3d8fc7160a33929bb141fcc4c26/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/28557357014/534d/3432/970d/b5dbc39039819cf9782f349f702b8680.flac","trackType":"flac"} Aug 20 00:07:08 volumio volumio[890]: verbose: CURRENT POSITION 0 Aug 20 00:07:08 volumio volumio[890]: info: CoreStateMachine::syncState stateService play Aug 20 00:07:08 volumio volumio[890]: info: CoreStateMachine::syncState currentStatus play Aug 20 00:07:08 volumio volumio[890]: info: Received an update from plugin. extracting info from payload Aug 20 00:07:08 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 20 00:07:08 volumio volumio[890]: info: CoreStateMachine::pushState Aug 20 00:07:08 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 00:07:08 volumio volumio[890]: info: CoreCommandRouter::volumioPushState Aug 20 00:07:08 volumio volumio[890]: info: CoreStateMachine::pushState Aug 20 00:07:08 volumio volumio[890]: info: CoreCommandRouter::volumioPushState Aug 20 00:07:08 volumio volumio[890]: info: ------------------------------ 32ms Aug 20 00:07:08 volumio volumio[890]: info: CoreCommandRouter::volumioGetQueue Aug 20 00:07:08 volumio volumio[890]: info: CoreStateMachine::getQueue Aug 20 00:07:08 volumio volumio[890]: info: CorePlayQueue::getQueue Aug 20 00:07:08 volumio volumio[890]: info: CoreCommandRouter::volumioGetQueue Aug 20 00:07:08 volumio volumio[890]: info: CoreStateMachine::getQueue Aug 20 00:07:08 volumio volumio[890]: info: CorePlayQueue::getQueue Aug 20 00:07:09 volumio volumio[890]: info: Aug 20 00:07:09 volumio volumio[890]: ---------------------------- MPD announces state update: player Aug 20 00:07:09 volumio volumio[890]: info: ControllerMpd::getState Aug 20 00:07:09 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand status Aug 20 00:07:09 volumio volumio[890]: info: sendMpdCommand status took 1 milliseconds Aug 20 00:07:09 volumio volumio[890]: verbose: ControllerMpd::parseState Aug 20 00:07:09 volumio volumio[890]: info: ControllerMpd::pushState Aug 20 00:07:09 volumio volumio[890]: info: CoreCommandRouter::servicePushState Aug 20 00:07:09 volumio volumio[890]: info: CoreStateMachine::pushState Aug 20 00:07:09 volumio volumio[890]: info: CoreCommandRouter::volumioPushState Aug 20 00:07:10 volumio volumio[890]: info: CorePlayQueue::getTrack 0 Aug 20 00:07:10 volumio volumio[890]: 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} Aug 20 00:07:10 volumio volumio[890]: verbose: CURRENT POSITION 0 Aug 20 00:07:10 volumio volumio[890]: info: CoreStateMachine::syncState stateService stop Aug 20 00:07:10 volumio volumio[890]: info: CoreStateMachine::syncState currentStatus play Aug 20 00:07:10 volumio volumio[890]: info: CoreCommandRouter::volumioPushState Aug 20 00:07:10 volumio volumio[890]: info: CoreStateMachine::stPlaybackTimer Aug 20 00:07:10 volumio volumio[890]: info: ------------------------------ 16ms Aug 20 00:07:10 volumio volumio[890]: info: CoreCommandRouter::volumioGetQueue Aug 20 00:07:10 volumio volumio[890]: info: CoreStateMachine::getQueue Aug 20 00:07:10 volumio volumio[890]: info: CorePlayQueue::getQueue Aug 20 00:07:57 volumio volumio[890]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 20 00:07:57 volumio volumio[890]: /bin/cat: /sys/class/net/wlan0/flags: No such file or directory Aug 20 00:07:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 20 00:07:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Aug 20 00:07:57 volumio sudo[5884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Aug 20 00:07:57 volumio sudo[5884]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 00:07:57 volumio sudo[5884]: pam_unix(sudo:session): session closed for user root Aug 20 00:07:57 volumio sudo[5895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 20 00:07:57 volumio sudo[5895]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 00:07:57 volumio sudo[5895]: pam_unix(sudo:session): session closed for user root Aug 20 00:07:57 volumio sudo[5910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 20 00:07:57 volumio sudo[5910]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 00:07:57 volumio sudo[5927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 20 00:07:57 volumio sudo[5927]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 00:07:57 volumio sudo[5910]: pam_unix(sudo:session): session closed for user root Aug 20 00:07:57 volumio sudo[5927]: pam_unix(sudo:session): session closed for user root Aug 20 00:07:57 volumio sudo[5952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 20 00:07:57 volumio sudo[5952]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 00:07:57 volumio sudo[5952]: pam_unix(sudo:session): session closed for user root Aug 20 00:07:57 volumio sudo[5955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 00:07:57 volumio sudo[5955]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 00:07:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 20 00:07:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 20 00:07:57 volumio sudo[5955]: pam_unix(sudo:session): session closed for user root Aug 20 00:07:57 volumio sudo[5961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 20 00:07:57 volumio sudo[5961]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 00:07:57 volumio sudo[5961]: pam_unix(sudo:session): session closed for user root Aug 20 00:07:57 volumio volumio[890]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Aug 20 00:07:57 volumio volumio[890]: wlan0 Interface doesn't support scanning. Aug 20 00:07:57 volumio volumio[890]: info: Cannot use regular scanning, forcing with ap-force Aug 20 00:07:57 volumio sudo[5966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Aug 20 00:07:57 volumio sudo[5966]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 00:07:57 volumio sudo[5966]: pam_unix(sudo:session): session closed for user root Aug 20 00:07:57 volumio volumio[890]: command failed: No such device (-19) Aug 20 00:07:57 volumio volumio[890]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Aug 20 00:07:57 volumio volumio[890]: command failed: No such device (-19) Aug 20 00:08:06 volumio volumio[890]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 20 00:08:08 volumio volumio[890]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 00:08:08 volumio volumio[890]: Error: connect ETIMEDOUT 199.59.148.229:443 Aug 20 00:08:08 volumio volumio[890]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Aug 20 00:08:08 volumio volumio[890]: errno: -110, Aug 20 00:08:08 volumio volumio[890]: code: 'ETIMEDOUT', Aug 20 00:08:08 volumio volumio[890]: syscall: 'connect', Aug 20 00:08:08 volumio volumio[890]: address: '199.59.148.229', Aug 20 00:08:08 volumio volumio[890]: port: 443 Aug 20 00:08:08 volumio volumio[890]: } Aug 20 00:08:08 volumio volumio[890]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 00:08:08 volumio sudo[5983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-20 00:07 Aug 20 00:08:08 volumio sudo[5983]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"