-- Logs begin at Fri 2025-07-18 12:59:49 CST, end at Fri 2025-07-18 13:02:58 CST. -- Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::volumioGetVisibleSources Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Jul 18 13:01:00 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 18 13:01:00 volumio volumio[850]: info: Received Get System Info Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 13:01:00 volumio volumio[850]: info: Discovery: Getting this device information Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Jul 18 13:01:00 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Jul 18 13:01:00 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:01:00 volumio volumio[850]: info: Listing playlists Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Jul 18 13:01:00 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 18 13:01:00 volumio volumio[850]: info: Received Get System Info Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 13:01:00 volumio volumio[850]: info: Discovery: Getting this device information Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Jul 18 13:01:00 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 18 13:01:00 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 18 13:01:02 volumio volumio[850]: error: MyVolumio Plugin failed to start in a timely fashion Jul 18 13:01:02 volumio volumio[850]: info: BOOT COMPLETED Jul 18 13:01:02 volumio volumio[850]: [Metrics] CommandRouter: 48s 90.26ms Jul 18 13:01:02 volumio volumio[850]: info: CoreCommandRouter::volumiosetStartupVolume Jul 18 13:01:02 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 13:01:02 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 13:01:02 volumio volumio[850]: info: CoreCommandRouter::Close All Modals sent Jul 18 13:01:02 volumio volumio[850]: info: CoreCommandRouter::Close All Modals sent Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 18 13:01:02 volumio volumio[850]: info: Adding plugin bluetooth to MyMusic Plugins Jul 18 13:01:02 volumio volumio[850]: info: Adding plugin multiroom to MyMusic Plugins Jul 18 13:01:02 volumio volumio[850]: info: Adding plugin metavolumio to MyMusic Plugins Jul 18 13:01:02 volumio volumio[850]: info: Adding plugin cd_controller to MyMusic Plugins Jul 18 13:01:02 volumio volumio[850]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 18 13:01:02 volumio volumio[850]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 18 13:01:02 volumio volumio[850]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 18 13:01:04 volumio volumio[850]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 18 13:01:04 volumio volumio[850]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 18 13:01:04 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 13:01:04 volumio volumio[850]: info: Starting MyVolumio Remote Streaming Endpoints Jul 18 13:01:04 volumio volumio[850]: info: MyVolumio not started Jul 18 13:01:04 volumio volumio[850]: info: Initializing device activation check Jul 18 13:01:04 volumio volumio[850]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 18 13:01:04 volumio volumio[850]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 18 13:01:06 volumio volumio[850]: info: Starting Streaming Service Transparent Proxy Jul 18 13:01:06 volumio volumio[850]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 18 13:01:06 volumio volumio[850]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 18 13:01:06 volumio volumio[850]: info: Streaming services startup Jul 18 13:01:06 volumio volumio[850]: info: Starting Streaming Daemon Jul 18 13:01:07 volumio volumio[850]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 18 13:01:07 volumio sudo[1770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 18 13:01:07 volumio sudo[1770]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 13:01:07 volumio sudo[1770]: pam_unix(sudo:session): session closed for user root Jul 18 13:01:07 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 13:01:07 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 13:01:07 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 18 13:01:07 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 18 13:01:07 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 18 13:01:07 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 18 13:01:07 volumio volumio[850]: error: Cannot start Volumio Streaming Daemon Jul 18 13:01:07 volumio volumio[850]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 18 13:01:07 volumio volumio[850]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 18 13:01:08 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Jul 18 13:01:08 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:01:09 volumio volumio[850]: STREAMING PROXY: Starting server on port 3245 Jul 18 13:01:09 volumio volumio[850]: Node JS runtime: 14 Jul 18 13:01:09 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 18 13:01:09 volumio volumio-remote-updater[393]: No test mode Jul 18 13:01:09 volumio volumio-remote-updater[393]: No alpha test mode Jul 18 13:01:09 volumio volumio[850]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n\n

NEW ADDITIONS

\n\n","title":"Update v3.819","updateavailable":true} Jul 18 13:01:09 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 18 13:01:19 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Jul 18 13:01:19 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:01:19 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 18 13:01:19 volumio volumio[850]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 33 Jul 18 13:01:19 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Jul 18 13:01:19 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:01:24 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 13:01:24 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 13:01:24 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 18 13:01:24 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 18 13:01:24 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 18 13:01:24 volumio volumio[850]: info: CoreCommandRouter::volumioGetBrowseSources Jul 18 13:01:24 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 13:01:30 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Jul 18 13:01:30 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:01:41 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Jul 18 13:01:41 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:01:52 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Jul 18 13:01:52 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::volumioPlay Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::play index undefined Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::startPlaybackTimer Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/经典老歌1679首【私藏】/001.渡口 蔡琴.wma Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand stop took 3 milliseconds Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand clear took 1 milliseconds Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/经典老歌1679首【私藏】/001.渡口 蔡琴.wma" Jul 18 13:02:03 volumio volumio[850]: info: Jul 18 13:02:03 volumio volumio[850]: ---------------------------- MPD announces system playlist update Jul 18 13:02:03 volumio volumio[850]: info: Ignoring MPD Status Update Jul 18 13:02:03 volumio volumio[850]: info: Jul 18 13:02:03 volumio volumio[850]: ---------------------------- MPD announces system playlist update Jul 18 13:02:03 volumio volumio[850]: info: Ignoring MPD Status Update Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand add "INTERNAL/经典老歌1679首【私藏】/001.渡口 蔡琴.wma" took 6 milliseconds Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand play Jul 18 13:02:03 volumio volumio[850]: info: Jul 18 13:02:03 volumio volumio[850]: ---------------------------- MPD announces system playlist update Jul 18 13:02:03 volumio volumio[850]: info: Ignoring MPD Status Update Jul 18 13:02:03 volumio volumio[850]: info: Jul 18 13:02:03 volumio volumio[850]: ---------------------------- MPD announces system playlist update Jul 18 13:02:03 volumio volumio[850]: info: Ignoring MPD Status Update Jul 18 13:02:03 volumio volumio[850]: info: Jul 18 13:02:03 volumio volumio[850]: ---------------------------- MPD announces system playlist update Jul 18 13:02:03 volumio volumio[850]: info: Ignoring MPD Status Update Jul 18 13:02:03 volumio volumio[850]: info: ------------------------------ 21ms Jul 18 13:02:03 volumio volumio[850]: info: ------------------------------ 19ms Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand play took 18 milliseconds Jul 18 13:02:03 volumio volumio[850]: info: ------------------------------ 17ms Jul 18 13:02:03 volumio volumio[850]: info: ------------------------------ 15ms Jul 18 13:02:03 volumio volumio[850]: info: Jul 18 13:02:03 volumio volumio[850]: ---------------------------- MPD announces system playlist update Jul 18 13:02:03 volumio volumio[850]: info: Ignoring MPD Status Update Jul 18 13:02:03 volumio volumio[850]: info: Jul 18 13:02:03 volumio volumio[850]: ---------------------------- MPD announces system playlist update Jul 18 13:02:03 volumio volumio[850]: info: Ignoring MPD Status Update Jul 18 13:02:03 volumio volumio[850]: info: ------------------------------ 20ms Jul 18 13:02:03 volumio volumio[850]: info: ------------------------------ 6ms Jul 18 13:02:03 volumio volumio[850]: info: ------------------------------ 4ms Jul 18 13:02:03 volumio volumio[850]: info: Jul 18 13:02:03 volumio volumio[850]: ---------------------------- MPD announces system playlist update Jul 18 13:02:03 volumio volumio[850]: info: Ignoring MPD Status Update Jul 18 13:02:03 volumio volumio[850]: error: Upnp client error: Error: This socket has been ended by the other party Jul 18 13:02:03 volumio volumio[850]: info: ------------------------------ 4ms Jul 18 13:02:03 volumio volumio[850]: info: Jul 18 13:02:03 volumio volumio[850]: ---------------------------- MPD announces state update: player Jul 18 13:02:03 volumio volumio[850]: info: ControllerMpd::getState Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jul 18 13:02:03 volumio volumio[850]: info: Jul 18 13:02:03 volumio volumio[850]: ---------------------------- MPD announces state update: player Jul 18 13:02:03 volumio volumio[850]: info: ControllerMpd::getState Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jul 18 13:02:03 volumio volumio[850]: info: Jul 18 13:02:03 volumio volumio[850]: ---------------------------- MPD announces state update: player Jul 18 13:02:03 volumio volumio[850]: info: ControllerMpd::getState Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jul 18 13:02:03 volumio volumio[850]: info: Jul 18 13:02:03 volumio volumio[850]: ---------------------------- MPD announces state update: player Jul 18 13:02:03 volumio volumio[850]: info: ControllerMpd::getState Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand status took 97 milliseconds Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::parseState Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 13:02:03 volumio volumio[850]: info: Jul 18 13:02:03 volumio volumio[850]: ---------------------------- MPD announces state update: player Jul 18 13:02:03 volumio volumio[850]: info: ControllerMpd::getState Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand status took 106 milliseconds Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand status took 102 milliseconds Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand status took 100 milliseconds Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::parseState Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::parseState Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::parseState Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Jul 18 13:02:03 volumio volumio[850]: info: ControllerMpd::pushState Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::servicePushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"121 Kbps","isStreaming":false,"title":"001.渡口 蔡琴.wma","artist":null,"album":null,"uri":"INTERNAL/经典老歌1679首【私藏】/001.渡口 蔡琴.wma","trackType":"wma"} Jul 18 13:02:03 volumio volumio[850]: verbose: CURRENT POSITION 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus stop Jul 18 13:02:03 volumio volumio[850]: info: ------------------------------ 132ms Jul 18 13:02:03 volumio volumio[850]: info: Jul 18 13:02:03 volumio volumio[850]: ---------------------------- MPD announces state update: player Jul 18 13:02:03 volumio volumio[850]: info: ControllerMpd::getState Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jul 18 13:02:03 volumio volumio[850]: info: Jul 18 13:02:03 volumio volumio[850]: ---------------------------- MPD announces state update: player Jul 18 13:02:03 volumio volumio[850]: info: ControllerMpd::getState Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jul 18 13:02:03 volumio volumio[850]: info: Jul 18 13:02:03 volumio volumio[850]: ---------------------------- MPD announces state update: player Jul 18 13:02:03 volumio volumio[850]: info: ControllerMpd::getState Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand status took 31 milliseconds Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand playlistinfo took 24 milliseconds Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand status took 10 milliseconds Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand status took 7 milliseconds Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand status took 5 milliseconds Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::parseState Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::parseState Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::parseState Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::parseState Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 13:02:03 volumio volumio[850]: info: ControllerMpd::pushState Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::servicePushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":681,"duration":229,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"121 Kbps","isStreaming":false,"title":"001.渡口 蔡琴.wma","artist":null,"album":null,"uri":"INTERNAL/经典老歌1679首【私藏】/001.渡口 蔡琴.wma","trackType":"wma"} Jul 18 13:02:03 volumio volumio[850]: verbose: CURRENT POSITION 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus play Jul 18 13:02:03 volumio volumio[850]: info: Received an update from plugin. extracting info from payload Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::pushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::pushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jul 18 13:02:03 volumio volumio[850]: info: ControllerMpd::pushState Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::servicePushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":681,"duration":229,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"121 Kbps","isStreaming":false,"title":"001.渡口 蔡琴.wma","artist":null,"album":null,"uri":"INTERNAL/经典老歌1679首【私藏】/001.渡口 蔡琴.wma","trackType":"wma"} Jul 18 13:02:03 volumio volumio[850]: verbose: CURRENT POSITION 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus play Jul 18 13:02:03 volumio volumio[850]: info: Received an update from plugin. extracting info from payload Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::pushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::pushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jul 18 13:02:03 volumio volumio[850]: info: ControllerMpd::pushState Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::servicePushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":681,"duration":229,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"121 Kbps","isStreaming":false,"title":"001.渡口 蔡琴.wma","artist":null,"album":null,"uri":"INTERNAL/经典老歌1679首【私藏】/001.渡口 蔡琴.wma","trackType":"wma"} Jul 18 13:02:03 volumio volumio[850]: verbose: CURRENT POSITION 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus play Jul 18 13:02:03 volumio volumio[850]: info: Received an update from plugin. extracting info from payload Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::pushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::pushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jul 18 13:02:03 volumio volumio[850]: info: ------------------------------ 264ms Jul 18 13:02:03 volumio volumio[850]: info: ------------------------------ 264ms Jul 18 13:02:03 volumio volumio[850]: info: ------------------------------ 260ms Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand playlistinfo took 136 milliseconds Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand playlistinfo took 134 milliseconds Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand playlistinfo took 133 milliseconds Jul 18 13:02:03 volumio volumio[850]: info: sendMpdCommand playlistinfo took 133 milliseconds Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Jul 18 13:02:03 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Jul 18 13:02:03 volumio volumio[850]: info: ControllerMpd::pushState Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::servicePushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":681,"duration":229,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"121 Kbps","isStreaming":false,"title":"001.渡口 蔡琴.wma","artist":null,"album":null,"uri":"INTERNAL/经典老歌1679首【私藏】/001.渡口 蔡琴.wma","trackType":"wma"} Jul 18 13:02:03 volumio volumio[850]: verbose: CURRENT POSITION 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus play Jul 18 13:02:03 volumio volumio[850]: info: Received an update from plugin. extracting info from payload Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::pushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::pushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jul 18 13:02:03 volumio volumio[850]: info: ControllerMpd::pushState Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::servicePushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":681,"duration":229,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"121 Kbps","isStreaming":false,"title":"001.渡口 蔡琴.wma","artist":null,"album":null,"uri":"INTERNAL/经典老歌1679首【私藏】/001.渡口 蔡琴.wma","trackType":"wma"} Jul 18 13:02:03 volumio volumio[850]: verbose: CURRENT POSITION 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus play Jul 18 13:02:03 volumio volumio[850]: info: Received an update from plugin. extracting info from payload Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::pushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::pushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jul 18 13:02:03 volumio volumio[850]: info: ControllerMpd::pushState Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::servicePushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":681,"duration":229,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"121 Kbps","isStreaming":false,"title":"001.渡口 蔡琴.wma","artist":null,"album":null,"uri":"INTERNAL/经典老歌1679首【私藏】/001.渡口 蔡琴.wma","trackType":"wma"} Jul 18 13:02:03 volumio volumio[850]: verbose: CURRENT POSITION 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus play Jul 18 13:02:03 volumio volumio[850]: info: Received an update from plugin. extracting info from payload Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::pushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::pushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jul 18 13:02:03 volumio volumio[850]: info: ControllerMpd::pushState Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::servicePushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":681,"duration":229,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"121 Kbps","isStreaming":false,"title":"001.渡口 蔡琴.wma","artist":null,"album":null,"uri":"INTERNAL/经典老歌1679首【私藏】/001.渡口 蔡琴.wma","trackType":"wma"} Jul 18 13:02:03 volumio volumio[850]: verbose: CURRENT POSITION 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus play Jul 18 13:02:03 volumio volumio[850]: info: Received an update from plugin. extracting info from payload Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::pushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jul 18 13:02:03 volumio volumio[850]: info: CoreStateMachine::pushState Jul 18 13:02:03 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:03 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jul 18 13:02:03 volumio volumio[850]: info: ------------------------------ 439ms Jul 18 13:02:03 volumio volumio[850]: info: ------------------------------ 416ms Jul 18 13:02:03 volumio volumio[850]: info: ------------------------------ 415ms Jul 18 13:02:03 volumio volumio[850]: info: ------------------------------ 414ms Jul 18 13:02:04 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Jul 18 13:02:04 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:04 volumio sudo[1884]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jul 18 13:02:04 volumio sudo[1884]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 13:02:09 volumio volumio[850]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 7 Jul 18 13:02:11 volumio volumio[850]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 8 Jul 18 13:02:11 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Jul 18 13:02:11 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jul 18 13:02:57 volumio volumio[850]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 13:02:57 volumio volumio[850]: Error: connect ETIMEDOUT 128.121.146.101:443 Jul 18 13:02:57 volumio volumio[850]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jul 18 13:02:57 volumio volumio[850]: errno: -110, Jul 18 13:02:57 volumio volumio[850]: code: 'ETIMEDOUT', Jul 18 13:02:57 volumio volumio[850]: syscall: 'connect', Jul 18 13:02:57 volumio volumio[850]: address: '128.121.146.101', Jul 18 13:02:57 volumio volumio[850]: port: 443 Jul 18 13:02:57 volumio volumio[850]: } Jul 18 13:02:57 volumio volumio[850]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 13:02:58 volumio sudo[2083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-18 13:01 Jul 18 13:02:58 volumio sudo[2083]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"