-- Logs begin at Sun 2025-01-12 23:25:58 UTC, end at Sun 2025-01-12 23:33:23 UTC. -- Jan 12 23:32:11 volumio volumio[947]: info: CoreCommandRouter::volumioPlay Jan 12 23:32:11 volumio volumio[947]: verbose: UNSET VOLATILE Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::play index 5 Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::stop Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 23:32:11 volumio volumio[947]: verbose: UNSET VOLATILE Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::stPlaybackTimer Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::updateTrackBlock Jan 12 23:32:11 volumio volumio[947]: info: CorePlayQueue::getTrackBlock Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::pushState Jan 12 23:32:11 volumio volumio[947]: info: CorePlayQueue::getTrack 1 Jan 12 23:32:11 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jan 12 23:32:11 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::serviceStop Jan 12 23:32:11 volumio volumio[947]: info: CorePlayQueue::getTrack 1 Jan 12 23:32:11 volumio volumio[947]: info: CoreCommandRouter::serviceStop Jan 12 23:32:11 volumio volumio[947]: info: ControllerMpd::stop Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 23:32:11 volumio volumio[947]: info: sendMpdCommand stop took 5 milliseconds Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::play index undefined Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 23:32:11 volumio volumio[947]: info: CorePlayQueue::getTrack 5 Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::startPlaybackTimer Jan 12 23:32:11 volumio volumio[947]: info: CorePlayQueue::getTrack 5 Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::clearAddPlayTracks USB/FLASHDEVICE/永不失联的爱 - 单依纯.mp3 Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 23:32:11 volumio volumio[947]: info: Jan 12 23:32:11 volumio volumio[947]: ---------------------------- MPD announces state update: player Jan 12 23:32:11 volumio volumio[947]: info: ControllerMpd::getState Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:32:11 volumio volumio[947]: info: sendMpdCommand stop took 2 milliseconds Jan 12 23:32:11 volumio volumio[947]: info: sendMpdCommand status took 1 milliseconds Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::parseState Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:32:11 volumio volumio[947]: info: Jan 12 23:32:11 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jan 12 23:32:11 volumio volumio[947]: info: Ignoring MPD Status Update Jan 12 23:32:11 volumio volumio[947]: info: sendMpdCommand clear took 0 milliseconds Jan 12 23:32:11 volumio volumio[947]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand add "USB/FLASHDEVICE/永不失联的爱 - 单依纯.mp3" Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:32:11 volumio volumio[947]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 12 23:32:11 volumio volumio[947]: info: ------------------------------ 3ms Jan 12 23:32:11 volumio volumio[947]: error: updateQueue error: null Jan 12 23:32:11 volumio volumio[947]: info: Jan 12 23:32:11 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jan 12 23:32:11 volumio volumio[947]: info: Ignoring MPD Status Update Jan 12 23:32:11 volumio volumio[947]: info: ------------------------------ 2ms Jan 12 23:32:11 volumio volumio[947]: info: sendMpdCommand add "USB/FLASHDEVICE/永不失联的爱 - 单依纯.mp3" took 2 milliseconds Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand play Jan 12 23:32:11 volumio volumio[947]: info: ------------------------------ 6ms Jan 12 23:32:11 volumio volumio[947]: info: sendMpdCommand play took 6 milliseconds Jan 12 23:32:11 volumio volumio[947]: info: Jan 12 23:32:11 volumio volumio[947]: ---------------------------- MPD announces state update: player Jan 12 23:32:11 volumio volumio[947]: info: ControllerMpd::getState Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:32:11 volumio volumio[947]: info: Jan 12 23:32:11 volumio volumio[947]: ---------------------------- MPD announces state update: player Jan 12 23:32:11 volumio volumio[947]: info: sendMpdCommand status took 2 milliseconds Jan 12 23:32:11 volumio volumio[947]: info: ControllerMpd::getState Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::parseState Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:32:11 volumio volumio[947]: info: sendMpdCommand status took 1 milliseconds Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::parseState Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:32:11 volumio volumio[947]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 23:32:11 volumio volumio[947]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:32:11 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:32:11 volumio volumio[947]: info: ControllerMpd::pushState Jan 12 23:32:11 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jan 12 23:32:11 volumio volumio[947]: info: CorePlayQueue::getTrack 5 Jan 12 23:32:11 volumio volumio[947]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"永不失联的爱","artist":"单依纯","album":"2020中国好声音 第3期","uri":"USB/FLASHDEVICE/永不失联的爱 - 单依纯.mp3","trackType":"mp3"} Jan 12 23:32:11 volumio volumio[947]: verbose: CURRENT POSITION 5 Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::syncState stateService play Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus stop Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::pushState Jan 12 23:32:11 volumio volumio[947]: info: CorePlayQueue::getTrack 5 Jan 12 23:32:11 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jan 12 23:32:11 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:32:11 volumio volumio[947]: info: ControllerMpd::pushState Jan 12 23:32:11 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jan 12 23:32:11 volumio volumio[947]: info: CorePlayQueue::getTrack 5 Jan 12 23:32:11 volumio volumio[947]: verbose: STATE SERVICE {"status":"play","position":0,"seek":174,"duration":264,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"永不失联的爱","artist":"单依纯","album":"2020中国好声音 第3期","uri":"USB/FLASHDEVICE/永不失联的爱 - 单依纯.mp3","trackType":"mp3"} Jan 12 23:32:11 volumio volumio[947]: verbose: CURRENT POSITION 5 Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::syncState stateService play Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus play Jan 12 23:32:11 volumio volumio[947]: info: Received an update from plugin. extracting info from payload Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::pushState Jan 12 23:32:11 volumio volumio[947]: info: CorePlayQueue::getTrack 5 Jan 12 23:32:11 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jan 12 23:32:11 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:32:11 volumio volumio[947]: info: CoreStateMachine::pushState Jan 12 23:32:11 volumio volumio[947]: info: CorePlayQueue::getTrack 5 Jan 12 23:32:11 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jan 12 23:32:11 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:32:11 volumio volumio[947]: info: ------------------------------ 10ms Jan 12 23:32:11 volumio volumio[947]: info: ------------------------------ 8ms Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 12 23:32:24 volumio volumio[947]: info: Adding plugin bluetooth to MyMusic Plugins Jan 12 23:32:24 volumio volumio[947]: info: Adding plugin cd_controller to MyMusic Plugins Jan 12 23:32:24 volumio volumio[947]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 12 23:32:24 volumio volumio[947]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 12 23:32:24 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:32:24 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:32:24 volumio volumio[947]: info: Starting MyVolumio Remote Streaming Endpoints Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 12 23:32:24 volumio volumio[947]: info: Streaming services startup Jan 12 23:32:24 volumio volumio[947]: info: Starting Streaming Daemon Jan 12 23:32:24 volumio sudo[2279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 23:32:24 volumio sudo[2279]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 23:32:24 volumio systemd[1]: Stopping Volumio Streaming Daemon... Jan 12 23:32:24 volumio systemd[1]: Starting Volumio Streaming Daemon... Jan 12 23:32:24 volumio volumio[947]: info: MyVolumio not started Jan 12 23:32:24 volumio systemd[1]: Started Volumio Streaming Daemon. Jan 12 23:32:24 volumio volumio[947]: info: Initializing device activation check Jan 12 23:32:24 volumio volumio[947]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 12 23:32:24 volumio volumio-streaming-daemon[2287]: ############################ Jan 12 23:32:24 volumio volumio-streaming-daemon[2287]: # Volumio Streaming Daemon # Jan 12 23:32:24 volumio volumio-streaming-daemon[2287]: # Running on port 7777 # Jan 12 23:32:24 volumio volumio-streaming-daemon[2287]: ############################ Jan 12 23:32:24 volumio volumio-streaming-daemon[2287]: Environment: production Jan 12 23:32:24 volumio sudo[2279]: pam_unix(sudo:session): session closed for user root Jan 12 23:32:27 volumio volumio-streaming-daemon[2287]: Environment: production Jan 12 23:32:27 volumio volumio[947]: info: Fetching Streaming Services browse cache Jan 12 23:32:31 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 23:32:31 volumio volumio[947]: info: CURURI: playlists Jan 12 23:32:31 volumio volumio[947]: info: Listing playlists Jan 12 23:32:35 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 23:32:35 volumio volumio[947]: info: CURURI: playlists/undefined Jan 12 23:32:48 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 23:32:48 volumio volumio[947]: info: CURURI: music-library Jan 12 23:32:53 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 23:32:53 volumio volumio[947]: info: CURURI: music-library/USB Jan 12 23:33:15 volumio volumio[947]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%83%91%E7%A7%80%E6%96%87/a6cf7e2d-7842-4cc8-bff9-f1295d4ff2d4.jpg' Jan 12 23:33:15 volumio volumio[947]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/XXXTENTACION/17/366e8b90-45e1-4cc2-a85f-abb8714507fe.jpg' Jan 12 23:33:21 volumio volumio[947]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/EVERGLOW/ALL%20MY%20GIRLS/9ff68660-30b2-483e-b9f8-77518451fd3e.jpg' Jan 12 23:33:23 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 23:33:23 volumio volumio[947]: info: CURURI: music-library/USB/FLASHDEVICE Jan 12 23:33:23 volumio volumio[947]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 23:33:23 volumio volumio[947]: { Error: connect ETIMEDOUT 199.59.148.246:443 Jan 12 23:33:23 volumio volumio[947]: at Object._errnoException (util.js:1022:11) Jan 12 23:33:23 volumio volumio[947]: at _exceptionWithHostPort (util.js:1044:20) Jan 12 23:33:23 volumio volumio[947]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Jan 12 23:33:23 volumio volumio[947]: code: 'ETIMEDOUT', Jan 12 23:33:23 volumio volumio[947]: errno: 'ETIMEDOUT', Jan 12 23:33:23 volumio volumio[947]: syscall: 'connect', Jan 12 23:33:23 volumio volumio[947]: address: '199.59.148.246', Jan 12 23:33:23 volumio volumio[947]: port: 443 } Jan 12 23:33:23 volumio volumio[947]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 23:33:23 volumio sudo[2451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-12 23:32 Jan 12 23:33:23 volumio sudo[2451]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"