-- Logs begin at Fri 2024-11-22 23:58:02 UTC, end at Sat 2024-11-23 01:02:48 UTC. -- Nov 23 01:01:08 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Nov 23 01:01:08 volumio volumio[891]: info: CorePlayQueue::getTrack 205 Nov 23 01:01:08 volumio volumio[891]: info: Listing playlists Nov 23 01:01:18 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Nov 23 01:01:18 volumio volumio[891]: info: CorePlayQueue::getTrack 205 Nov 23 01:01:28 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Nov 23 01:01:28 volumio volumio[891]: info: CorePlayQueue::getTrack 205 Nov 23 01:01:28 volumio volumio[891]: info: Listing playlists Nov 23 01:01:28 volumio volumio[891]: info: Listing playlists Nov 23 01:01:38 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Nov 23 01:01:38 volumio volumio[891]: info: CorePlayQueue::getTrack 205 Nov 23 01:01:48 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Nov 23 01:01:48 volumio volumio[891]: info: CorePlayQueue::getTrack 205 Nov 23 01:01:48 volumio volumio[891]: info: Listing playlists Nov 23 01:01:48 volumio volumio[891]: info: Listing playlists Nov 23 01:01:58 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Nov 23 01:01:58 volumio volumio[891]: info: CorePlayQueue::getTrack 205 Nov 23 01:02:06 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 01:02:06 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 01:02:06 volumio volumio[891]: info: Discovery: Getting this device information Nov 23 01:02:06 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Nov 23 01:02:06 volumio volumio[891]: info: CorePlayQueue::getTrack 205 Nov 23 01:02:06 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 01:02:06 volumio volumio[891]: verbose: New Socket.io Connection to 192.168.3.122:3000 from 192.168.3.14 UA: Dalvik/2.1.0 (Linux; U; Android 14; SM-F9260 Build/UP1A.231005.007) Total Clients: 5 Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::volumioGetQueue Nov 23 01:02:07 volumio volumio[891]: info: CoreStateMachine::getQueue Nov 23 01:02:07 volumio volumio[891]: info: CorePlayQueue::getQueue Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Nov 23 01:02:07 volumio volumio[891]: info: CorePlayQueue::getTrack 205 Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Nov 23 01:02:07 volumio volumio[891]: info: CorePlayQueue::getTrack 205 Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Nov 23 01:02:07 volumio volumio[891]: info: CorePlayQueue::getTrack 205 Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Nov 23 01:02:07 volumio volumio[891]: info: CorePlayQueue::getTrack 205 Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 23 01:02:07 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 23 01:02:07 volumio volumio[891]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BA%BE%E6%BE%84%E5%BA%86/%E8%AE%A9%E6%88%91%E4%B8%80%E6%AC%A1%E7%88%B1%E4%B8%AA%E5%A4%9F/8c825441-1316-4b54-981a-0308f682165d.jpg' Nov 23 01:02:07 volumio volumio[891]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BA%BE%E6%BE%84%E5%BA%86/%E8%AE%A9%E6%88%91%E4%B8%80%E6%AC%A1%E7%88%B1%E4%B8%AA%E5%A4%9F/8c825441-1316-4b54-981a-0308f682165d.jpg' Nov 23 01:02:07 volumio volumio[891]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BA%BE%E6%BE%84%E5%BA%86/%E8%AE%A9%E6%88%91%E4%B8%80%E6%AC%A1%E7%88%B1%E4%B8%AA%E5%A4%9F/8c825441-1316-4b54-981a-0308f682165d.jpg' Nov 23 01:02:07 volumio volumio[891]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BA%BE%E6%BE%84%E5%BA%86/%E8%AE%A9%E6%88%91%E4%B8%80%E6%AC%A1%E7%88%B1%E4%B8%AA%E5%A4%9F/8c825441-1316-4b54-981a-0308f682165d.jpg' Nov 23 01:02:08 volumio volumio[891]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BA%BE%E6%BE%84%E5%BA%86/%E8%AE%A9%E6%88%91%E4%B8%80%E6%AC%A1%E7%88%B1%E4%B8%AA%E5%A4%9F/8c825441-1316-4b54-981a-0308f682165d.jpg' Nov 23 01:02:08 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Nov 23 01:02:08 volumio volumio[891]: info: CorePlayQueue::getTrack 205 Nov 23 01:02:08 volumio volumio[891]: info: Listing playlists Nov 23 01:02:08 volumio volumio[891]: info: Listing playlists Nov 23 01:02:09 volumio volumio[891]: info: CoreCommandRouter::volumioNext Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::next Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::stop Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::stPlaybackTimer Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::updateTrackBlock Nov 23 01:02:09 volumio volumio[891]: info: CorePlayQueue::getTrackBlock Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::pushState Nov 23 01:02:09 volumio volumio[891]: info: CorePlayQueue::getTrack 205 Nov 23 01:02:09 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 01:02:09 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::serviceStop Nov 23 01:02:09 volumio volumio[891]: info: CorePlayQueue::getTrack 205 Nov 23 01:02:09 volumio volumio[891]: info: CoreCommandRouter::serviceStop Nov 23 01:02:09 volumio volumio[891]: info: ControllerMpd::stop Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 01:02:09 volumio volumio[891]: info: sendMpdCommand stop took 82 milliseconds Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::play index undefined Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 01:02:09 volumio volumio[891]: info: CorePlayQueue::getTrack 164 Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::startPlaybackTimer Nov 23 01:02:09 volumio volumio[891]: info: CorePlayQueue::getTrack 164 Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/无损/谢淑恩 - 山路十八弯 (Live).flac Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::updateTrackBlock Nov 23 01:02:09 volumio volumio[891]: info: CorePlayQueue::getTrackBlock Nov 23 01:02:09 volumio volumio[891]: info: Nov 23 01:02:09 volumio volumio[891]: ---------------------------- MPD announces state update: player Nov 23 01:02:09 volumio volumio[891]: info: ControllerMpd::getState Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand status Nov 23 01:02:09 volumio volumio[891]: info: sendMpdCommand stop took 2 milliseconds Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 01:02:09 volumio volumio[891]: info: Nov 23 01:02:09 volumio volumio[891]: ---------------------------- MPD announces system playlist update Nov 23 01:02:09 volumio volumio[891]: info: Ignoring MPD Status Update Nov 23 01:02:09 volumio volumio[891]: info: sendMpdCommand status took 3 milliseconds Nov 23 01:02:09 volumio volumio[891]: info: sendMpdCommand clear took 2 milliseconds Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::parseState Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/无损/谢淑恩 - 山路十八弯 (Live).flac" Nov 23 01:02:09 volumio volumio[891]: error: updateQueue error: null Nov 23 01:02:09 volumio volumio[891]: info: Nov 23 01:02:09 volumio volumio[891]: ---------------------------- MPD announces system playlist update Nov 23 01:02:09 volumio volumio[891]: info: Ignoring MPD Status Update Nov 23 01:02:09 volumio volumio[891]: info: ------------------------------ 7ms Nov 23 01:02:09 volumio volumio[891]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 23 01:02:09 volumio volumio[891]: info: sendMpdCommand add "NAS/NAS/无损/谢淑恩 - 山路十八弯 (Live).flac" took 2 milliseconds Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::parseTrackInfo Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand play Nov 23 01:02:09 volumio volumio[891]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 23 01:02:09 volumio volumio[891]: info: ------------------------------ 13ms Nov 23 01:02:09 volumio volumio[891]: info: ------------------------------ 4ms Nov 23 01:02:09 volumio volumio[891]: info: sendMpdCommand play took 3 milliseconds Nov 23 01:02:09 volumio volumio[891]: info: Nov 23 01:02:09 volumio volumio[891]: ---------------------------- MPD announces state update: player Nov 23 01:02:09 volumio volumio[891]: info: ControllerMpd::getState Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand status Nov 23 01:02:09 volumio volumio[891]: info: Nov 23 01:02:09 volumio volumio[891]: ---------------------------- MPD announces state update: player Nov 23 01:02:09 volumio volumio[891]: info: sendMpdCommand status took 30 milliseconds Nov 23 01:02:09 volumio volumio[891]: info: ControllerMpd::getState Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand status Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::parseState Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 01:02:09 volumio volumio[891]: info: sendMpdCommand status took 7 milliseconds Nov 23 01:02:09 volumio volumio[891]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::parseState Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::parseTrackInfo Nov 23 01:02:09 volumio volumio[891]: info: ControllerMpd::pushState Nov 23 01:02:09 volumio volumio[891]: info: CoreCommandRouter::servicePushState Nov 23 01:02:09 volumio volumio[891]: info: CorePlayQueue::getTrack 164 Nov 23 01:02:09 volumio volumio[891]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"96 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"山路十八弯","artist":"谢淑恩","album":"歌声的翅膀 第1期","uri":"NAS/NAS/无损/谢淑恩 - 山路十八弯 (Live).flac","trackType":"flac"} Nov 23 01:02:09 volumio volumio[891]: verbose: CURRENT POSITION 164 Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::syncState stateService play Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::syncState currentStatus stop Nov 23 01:02:09 volumio volumio[891]: info: ------------------------------ 44ms Nov 23 01:02:09 volumio volumio[891]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 23 01:02:09 volumio volumio[891]: verbose: ControllerMpd::parseTrackInfo Nov 23 01:02:09 volumio volumio[891]: info: ControllerMpd::pushState Nov 23 01:02:09 volumio volumio[891]: info: CoreCommandRouter::servicePushState Nov 23 01:02:09 volumio volumio[891]: info: CorePlayQueue::getTrack 164 Nov 23 01:02:09 volumio volumio[891]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"96 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"755 Kbps","isStreaming":false,"title":"山路十八弯","artist":"谢淑恩","album":"歌声的翅膀 第1期","uri":"NAS/NAS/无损/谢淑恩 - 山路十八弯 (Live).flac","trackType":"flac"} Nov 23 01:02:09 volumio volumio[891]: verbose: CURRENT POSITION 164 Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::syncState stateService play Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::syncState currentStatus play Nov 23 01:02:09 volumio volumio[891]: info: Received an update from plugin. extracting info from payload Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::pushState Nov 23 01:02:09 volumio volumio[891]: info: CorePlayQueue::getTrack 164 Nov 23 01:02:09 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 01:02:09 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Nov 23 01:02:09 volumio volumio[891]: info: CoreStateMachine::pushState Nov 23 01:02:09 volumio volumio[891]: info: CorePlayQueue::getTrack 164 Nov 23 01:02:09 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Nov 23 01:02:09 volumio volumio[891]: info: ------------------------------ 51ms Nov 23 01:02:11 volumio nmbd[574]: [2024/11/23 01:02:11.307162, 0] ../source3/nmbd/nmbd_packets.c:1398(validate_nmb_packet) Nov 23 01:02:11 volumio nmbd[574]: validate_nmb_packet: Bad QUERY Packet. validate_nmb_packet: Ignoring request packet with opcode 0. Nov 23 01:02:11 volumio nmbd[574]: [2024/11/23 01:02:11.375635, 0] ../source3/nmbd/nmbd_packets.c:1398(validate_nmb_packet) Nov 23 01:02:11 volumio nmbd[574]: validate_nmb_packet: Bad QUERY Packet. validate_nmb_packet: Ignoring request packet with opcode 0. Nov 23 01:02:11 volumio nmbd[574]: [2024/11/23 01:02:11.723056, 0] ../source3/nmbd/nmbd_packets.c:1398(validate_nmb_packet) Nov 23 01:02:11 volumio nmbd[574]: validate_nmb_packet: Bad QUERY Packet. validate_nmb_packet: Ignoring request packet with opcode 0. Nov 23 01:02:11 volumio nmbd[574]: [2024/11/23 01:02:11.723385, 0] ../source3/nmbd/nmbd_packets.c:1398(validate_nmb_packet) Nov 23 01:02:11 volumio nmbd[574]: validate_nmb_packet: Bad QUERY Packet. validate_nmb_packet: Ignoring request packet with opcode 0. Nov 23 01:02:18 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Nov 23 01:02:18 volumio volumio[891]: info: CorePlayQueue::getTrack 164 Nov 23 01:02:28 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Nov 23 01:02:28 volumio volumio[891]: info: CorePlayQueue::getTrack 164 Nov 23 01:02:28 volumio volumio[891]: info: Listing playlists Nov 23 01:02:28 volumio volumio[891]: info: Listing playlists Nov 23 01:02:38 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Nov 23 01:02:38 volumio volumio[891]: info: CorePlayQueue::getTrack 164 Nov 23 01:02:48 volumio volumio[891]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 01:02:48 volumio volumio[891]: Error: connect ETIMEDOUT 128.242.245.43:443 Nov 23 01:02:48 volumio volumio[891]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Nov 23 01:02:48 volumio volumio[891]: errno: -110, Nov 23 01:02:48 volumio volumio[891]: code: 'ETIMEDOUT', Nov 23 01:02:48 volumio volumio[891]: syscall: 'connect', Nov 23 01:02:48 volumio volumio[891]: address: '128.242.245.43', Nov 23 01:02:48 volumio volumio[891]: port: 443 Nov 23 01:02:48 volumio volumio[891]: } Nov 23 01:02:48 volumio volumio[891]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 01:02:48 volumio sudo[13164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 01:01 Nov 23 01:02:48 volumio sudo[13164]: 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="1d63a568b7d5e4a317f16be7d0961cbb2d82e282" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="4f8978e0148ab5e1610548b80abf1b4b5e03ea0a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 07 Feb 2023 05:04:19 PM CET" VOLUMIO_VERSION="3.429" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4c8dcdad83349056095d42fbad08221f"