-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Sun 2024-08-18 00:02:56 CST. -- Aug 18 00:02:50 volumio volumio[780]: info: Clearing queue after UPNP request Aug 18 00:02:51 volumio volumio[780]: info: CoreStateMachine::ClearQueue Aug 18 00:02:51 volumio volumio[780]: info: CoreStateMachine::stop Aug 18 00:02:51 volumio volumio[780]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 00:02:51 volumio volumio[780]: info: CoreStateMachine::stPlaybackTimer Aug 18 00:02:51 volumio volumio[780]: info: CoreStateMachine::updateTrackBlock Aug 18 00:02:51 volumio volumio[780]: info: CorePlayQueue::getTrackBlock Aug 18 00:02:51 volumio volumio[780]: info: CoreStateMachine::pushState Aug 18 00:02:51 volumio volumio[780]: info: CorePlayQueue::getTrack 0 Aug 18 00:02:51 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 00:02:51 volumio volumio[780]: info: CoreCommandRouter::volumioPushState Aug 18 00:02:51 volumio volumio[780]: info: CoreStateMachine::serviceStop Aug 18 00:02:51 volumio volumio[780]: info: CorePlayQueue::getTrack 0 Aug 18 00:02:51 volumio volumio[780]: info: ControllerMpd::stop Aug 18 00:02:51 volumio volumio[780]: verbose: ControllerMpd::sendMpdCommand stop Aug 18 00:02:51 volumio volumio[780]: info: CorePlayQueue::clearPlayQueue Aug 18 00:02:51 volumio volumio[780]: info: CorePlayQueue::saveQueue Aug 18 00:02:51 volumio volumio[780]: info: CoreCommandRouter::volumioPushState Aug 18 00:02:51 volumio volumio[780]: info: CoreCommandRouter::volumioPushQueue Aug 18 00:02:51 volumio volumio[780]: error: Upnp client error: Error: This socket has been ended by the other party Aug 18 00:02:51 volumio volumio[780]: info: Aug 18 00:02:51 volumio volumio[780]: ---------------------------- MPD announces state update: player Aug 18 00:02:51 volumio volumio[780]: info: sendMpdCommand stop took 17 milliseconds Aug 18 00:02:51 volumio volumio[780]: info: ControllerMpd::getState Aug 18 00:02:51 volumio volumio[780]: verbose: ControllerMpd::sendMpdCommand status Aug 18 00:02:51 volumio volumio[780]: info: sendMpdCommand status took 1 milliseconds Aug 18 00:02:51 volumio volumio[780]: verbose: ControllerMpd::parseState Aug 18 00:02:51 volumio volumio[780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 00:02:51 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 18 00:02:51 volumio volumio[780]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 18 00:02:51 volumio volumio[780]: verbose: ControllerMpd::parseTrackInfo Aug 18 00:02:51 volumio volumio[780]: info: ControllerMpd::pushState Aug 18 00:02:51 volumio volumio[780]: info: CoreCommandRouter::servicePushState Aug 18 00:02:51 volumio volumio[780]: info: CoreStateMachine::pushState Aug 18 00:02:51 volumio volumio[780]: info: CorePlayQueue::getTrack 0 Aug 18 00:02:51 volumio volumio[780]: info: CoreCommandRouter::volumioPushState Aug 18 00:02:51 volumio volumio[780]: info: CorePlayQueue::getTrack 0 Aug 18 00:02:51 volumio volumio[780]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"半壶纱","artist":"刘珂矣","album":"半壶纱","uri":"http://aqqmusic.tc.qq.com/F000004CtHoz0Wy4Ko.flac?guid=34AE89E2F46045448E881C33FD414503&vkey=D4DB0C2618F9B2F3181BE5BE3B377812199439310C27644EB2F63568F19F38334A08B8E0FE0E60EB28B8A675B9706749B6E57C96FDD5284C&uin=936430163&redirect=1&fromtag=101114","trackType":""} Aug 18 00:02:51 volumio volumio[780]: verbose: CURRENT POSITION 0 Aug 18 00:02:51 volumio volumio[780]: info: CoreStateMachine::syncState stateService stop Aug 18 00:02:51 volumio volumio[780]: info: CoreStateMachine::syncState currentStatus stop Aug 18 00:02:51 volumio volumio[780]: info: CoreStateMachine::pushState Aug 18 00:02:51 volumio volumio[780]: info: CorePlayQueue::getTrack 0 Aug 18 00:02:51 volumio volumio[780]: info: CoreCommandRouter::volumioPushState Aug 18 00:02:51 volumio volumio[780]: info: No code Aug 18 00:02:51 volumio volumio[780]: info: CoreStateMachine::pushState Aug 18 00:02:51 volumio volumio[780]: info: CorePlayQueue::getTrack 0 Aug 18 00:02:51 volumio volumio[780]: info: CoreCommandRouter::volumioPushState Aug 18 00:02:51 volumio volumio[780]: info: ------------------------------ 15ms Aug 18 00:02:52 volumio volumio[780]: info: Starting UPNP Playback Aug 18 00:02:52 volumio volumio[780]: info: Preparing playback through UPNP Aug 18 00:02:52 volumio volumio[780]: info: CoreCommandRouter::volumioGetState Aug 18 00:02:52 volumio volumio[780]: info: CorePlayQueue::getTrack 0 Aug 18 00:02:52 volumio volumio[780]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 18 00:02:53 volumio volumio[780]: info: Clearing queue after UPNP request Aug 18 00:02:53 volumio volumio[780]: info: CoreStateMachine::ClearQueue Aug 18 00:02:53 volumio volumio[780]: info: CoreStateMachine::stop Aug 18 00:02:53 volumio volumio[780]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 00:02:53 volumio volumio[780]: info: CorePlayQueue::clearPlayQueue Aug 18 00:02:53 volumio volumio[780]: info: CorePlayQueue::saveQueue Aug 18 00:02:53 volumio volumio[780]: info: CoreCommandRouter::volumioPushState Aug 18 00:02:53 volumio volumio[780]: info: CoreCommandRouter::volumioPushQueue Aug 18 00:02:53 volumio volumio[780]: info: Aug 18 00:02:53 volumio volumio[780]: ---------------------------- MPD announces system playlist update Aug 18 00:02:53 volumio volumio[780]: info: Ignoring MPD Status Update Aug 18 00:02:53 volumio volumio[780]: error: updateQueue error: null Aug 18 00:02:53 volumio volumio[780]: info: ------------------------------ 2ms Aug 18 00:02:53 volumio volumio[780]: info: Starting UPNP Playback Aug 18 00:02:53 volumio volumio[780]: info: Preparing playback through UPNP Aug 18 00:02:53 volumio volumio[780]: info: CoreCommandRouter::volumioGetState Aug 18 00:02:53 volumio volumio[780]: info: CorePlayQueue::getTrack 0 Aug 18 00:02:53 volumio volumio[780]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 18 00:02:54 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 00:02:54 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 00:02:54 volumio volumio[780]: info: Discovery: Getting this device information Aug 18 00:02:54 volumio volumio[780]: info: CoreCommandRouter::volumioGetState Aug 18 00:02:54 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 00:02:54 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 00:02:54 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 00:02:54 volumio volumio[780]: info: Discovery: Getting this device information Aug 18 00:02:54 volumio volumio[780]: info: CoreCommandRouter::volumioGetState Aug 18 00:02:54 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 00:02:54 volumio volumio[780]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%98%E7%8F%82%E7%9F%A3/%E5%8D%8A%E5%A3%B6%E7%BA%B1/59334834-7133-4ab2-b2c6-2ce19295dcb0.jpg' Aug 18 00:02:54 volumio volumio[780]: info: Clearing queue after UPNP request Aug 18 00:02:54 volumio volumio[780]: info: CoreStateMachine::ClearQueue Aug 18 00:02:54 volumio volumio[780]: info: CoreStateMachine::stop Aug 18 00:02:54 volumio volumio[780]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 00:02:54 volumio volumio[780]: info: CorePlayQueue::clearPlayQueue Aug 18 00:02:54 volumio volumio[780]: info: CorePlayQueue::saveQueue Aug 18 00:02:54 volumio volumio[780]: info: CoreCommandRouter::volumioPushState Aug 18 00:02:54 volumio volumio[780]: info: CoreCommandRouter::volumioPushQueue Aug 18 00:02:54 volumio volumio[780]: info: Aug 18 00:02:54 volumio volumio[780]: ---------------------------- MPD announces system playlist update Aug 18 00:02:54 volumio volumio[780]: info: Ignoring MPD Status Update Aug 18 00:02:54 volumio volumio[780]: error: updateQueue error: null Aug 18 00:02:54 volumio volumio[780]: info: ------------------------------ 2ms Aug 18 00:02:54 volumio volumio[780]: info: Starting UPNP Playback Aug 18 00:02:54 volumio volumio[780]: info: Preparing playback through UPNP Aug 18 00:02:54 volumio volumio[780]: info: CoreCommandRouter::volumioGetState Aug 18 00:02:54 volumio volumio[780]: info: CorePlayQueue::getTrack 0 Aug 18 00:02:54 volumio volumio[780]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 18 00:02:55 volumio volumio[780]: info: Aug 18 00:02:55 volumio volumio[780]: ---------------------------- MPD announces system playlist update Aug 18 00:02:55 volumio volumio[780]: info: Ignoring MPD Status Update Aug 18 00:02:55 volumio volumio[780]: info: ------------------------------ 2ms Aug 18 00:02:55 volumio volumio[780]: info: Aug 18 00:02:55 volumio volumio[780]: ---------------------------- MPD announces system playlist update Aug 18 00:02:55 volumio volumio[780]: info: Ignoring MPD Status Update Aug 18 00:02:55 volumio volumio[780]: info: ------------------------------ 2ms Aug 18 00:02:55 volumio volumio[780]: info: Aug 18 00:02:55 volumio volumio[780]: ---------------------------- MPD announces system playlist update Aug 18 00:02:55 volumio volumio[780]: info: Ignoring MPD Status Update Aug 18 00:02:55 volumio volumio[780]: info: ------------------------------ 8ms Aug 18 00:02:55 volumio volumio[780]: info: Aug 18 00:02:55 volumio volumio[780]: ---------------------------- MPD announces system playlist update Aug 18 00:02:55 volumio volumio[780]: info: Ignoring MPD Status Update Aug 18 00:02:55 volumio volumio[780]: info: ------------------------------ 2ms Aug 18 00:02:55 volumio volumio[780]: info: Aug 18 00:02:55 volumio volumio[780]: ---------------------------- MPD announces system playlist update Aug 18 00:02:55 volumio volumio[780]: info: Ignoring MPD Status Update Aug 18 00:02:55 volumio volumio[780]: info: ------------------------------ 3ms Aug 18 00:02:55 volumio volumio[780]: info: Aug 18 00:02:55 volumio volumio[780]: ---------------------------- MPD announces system playlist update Aug 18 00:02:55 volumio volumio[780]: info: Ignoring MPD Status Update Aug 18 00:02:55 volumio volumio[780]: info: ------------------------------ 2ms Aug 18 00:02:55 volumio volumio[780]: verbose: New Socket.io Connection to 192.168.50.206 from 192.168.50.82 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Aug 18 00:02:55 volumio volumio[780]: verbose: New Socket.io Connection to 192.168.50.206:3000 from 192.168.50.82 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::volumioGetVisibleSources Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::volumioGetState Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 18 00:02:55 volumio volumio[780]: info: Received Get System Info Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 00:02:55 volumio volumio[780]: info: Discovery: Getting this device information Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::volumioGetState Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::volumioGetState Aug 18 00:02:55 volumio volumio[780]: info: Listing playlists Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 18 00:02:55 volumio volumio[780]: info: Aug 18 00:02:55 volumio volumio[780]: ---------------------------- MPD announces state update: player Aug 18 00:02:55 volumio volumio[780]: info: ControllerMpd::getState Aug 18 00:02:55 volumio volumio[780]: verbose: ControllerMpd::sendMpdCommand status Aug 18 00:02:55 volumio volumio[780]: info: Aug 18 00:02:55 volumio volumio[780]: ---------------------------- MPD announces system playlist update Aug 18 00:02:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 18 00:02:55 volumio volumio[780]: info: Ignoring MPD Status Update Aug 18 00:02:55 volumio volumio[780]: info: Aug 18 00:02:55 volumio volumio[780]: ---------------------------- MPD announces state update: player Aug 18 00:02:55 volumio volumio[780]: info: sendMpdCommand status took 41 milliseconds Aug 18 00:02:55 volumio volumio[780]: info: ControllerMpd::getState Aug 18 00:02:55 volumio volumio[780]: verbose: ControllerMpd::sendMpdCommand status Aug 18 00:02:55 volumio volumio[780]: verbose: ControllerMpd::parseState Aug 18 00:02:55 volumio volumio[780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 00:02:55 volumio volumio[780]: info: ------------------------------ 5ms Aug 18 00:02:55 volumio volumio[780]: info: sendMpdCommand status took 4 milliseconds Aug 18 00:02:55 volumio volumio[780]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 18 00:02:55 volumio volumio[780]: verbose: ControllerMpd::parseState Aug 18 00:02:55 volumio volumio[780]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 00:02:55 volumio volumio[780]: verbose: ControllerMpd::parseTrackInfo Aug 18 00:02:55 volumio volumio[780]: info: ControllerMpd::pushState Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::servicePushState Aug 18 00:02:55 volumio volumio[780]: verbose: In UPNP mode Aug 18 00:02:55 volumio volumio[780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"半壶纱","artist":"刘珂矣","album":"半壶纱","uri":"http://aqqmusic.tc.qq.com/F000004CtHoz0Wy4Ko.flac?guid=34AE89E2F46045448E881C33FD414503&vkey=D4DB0C2618F9B2F3181BE5BE3B377812199439310C27644EB2F63568F19F38334A08B8E0FE0E60EB28B8A675B9706749B6E57C96FDD5284C&uin=936430163&redirect=1&fromtag=101114","trackType":""} Aug 18 00:02:55 volumio volumio[780]: verbose: CURRENT POSITION 0 Aug 18 00:02:55 volumio volumio[780]: info: CoreStateMachine::syncState stateService play Aug 18 00:02:55 volumio volumio[780]: info: CoreStateMachine::syncState currentStatus stop Aug 18 00:02:55 volumio volumio[780]: info: ------------------------------ 50ms Aug 18 00:02:55 volumio volumio[780]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 18 00:02:55 volumio volumio[780]: verbose: ControllerMpd::parseTrackInfo Aug 18 00:02:55 volumio volumio[780]: info: ControllerMpd::pushState Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::servicePushState Aug 18 00:02:55 volumio volumio[780]: verbose: In UPNP mode Aug 18 00:02:55 volumio volumio[780]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"838 Kbps","isStreaming":false,"title":"半壶纱","artist":"刘珂矣","album":"半壶纱","uri":"http://aqqmusic.tc.qq.com/F000004CtHoz0Wy4Ko.flac?guid=34AE89E2F46045448E881C33FD414503&vkey=D4DB0C2618F9B2F3181BE5BE3B377812199439310C27644EB2F63568F19F38334A08B8E0FE0E60EB28B8A675B9706749B6E57C96FDD5284C&uin=936430163&redirect=1&fromtag=101114","trackType":""} Aug 18 00:02:55 volumio volumio[780]: verbose: CURRENT POSITION 0 Aug 18 00:02:55 volumio volumio[780]: info: CoreStateMachine::syncState stateService play Aug 18 00:02:55 volumio volumio[780]: info: CoreStateMachine::syncState currentStatus play Aug 18 00:02:55 volumio volumio[780]: info: Received an update from plugin. extracting info from payload Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 18 00:02:55 volumio volumio[780]: info: CoreStateMachine::pushState Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::volumioPushState Aug 18 00:02:55 volumio volumio[780]: info: CoreStateMachine::pushState Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::volumioPushState Aug 18 00:02:55 volumio volumio[780]: info: ------------------------------ 28ms Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::volumioGetQueue Aug 18 00:02:55 volumio volumio[780]: info: CoreStateMachine::getQueue Aug 18 00:02:55 volumio volumio[780]: info: CorePlayQueue::getQueue Aug 18 00:02:55 volumio volumio[780]: info: CoreCommandRouter::volumioGetQueue Aug 18 00:02:55 volumio volumio[780]: info: CoreStateMachine::getQueue Aug 18 00:02:55 volumio volumio[780]: info: CorePlayQueue::getQueue Aug 18 00:02:56 volumio volumio[780]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 00:02:56 volumio volumio[780]: Error: connect ETIMEDOUT 199.193.116.105:443 Aug 18 00:02:56 volumio volumio[780]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 18 00:02:56 volumio volumio[780]: errno: -110, Aug 18 00:02:56 volumio volumio[780]: code: 'ETIMEDOUT', Aug 18 00:02:56 volumio volumio[780]: syscall: 'connect', Aug 18 00:02:56 volumio volumio[780]: address: '199.193.116.105', Aug 18 00:02:56 volumio volumio[780]: port: 443 Aug 18 00:02:56 volumio volumio[780]: } Aug 18 00:02:56 volumio volumio[780]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 00:02:56 volumio sudo[5575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 00:01 Aug 18 00:02:56 volumio sudo[5575]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"