-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Sat 2025-02-22 19:15:37 CST. -- Feb 22 19:14:01 volumio volumio[802]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 22 19:14:01 volumio volumio[802]: info: Retrying Login Due to Network Error Feb 22 19:14:04 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 19:14:04 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 19:14:04 volumio volumio[802]: info: Discovery: Getting this device information Feb 22 19:14:04 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Feb 22 19:14:04 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 19:14:05 volumio volumio[802]: verbose: New Socket.io Connection to 192.168.3.156 from 192.168.3.3 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 5 Feb 22 19:14:05 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 22 19:14:05 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 19:14:05 volumio volumio[802]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 19:14:05 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 19:14:05 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Feb 22 19:14:05 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 19:14:05 volumio volumio[802]: info: Received Get System Info Feb 22 19:14:05 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 19:14:05 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 19:14:05 volumio volumio[802]: info: Discovery: Getting this device information Feb 22 19:14:05 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Feb 22 19:14:05 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 19:14:05 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Feb 22 19:14:05 volumio volumio[802]: info: Listing playlists Feb 22 19:14:07 volumio volumio[802]: info: CoreCommandRouter::volumioPause Feb 22 19:14:07 volumio volumio[802]: info: CoreStateMachine::pause Feb 22 19:14:07 volumio volumio[802]: info: CoreStateMachine::stPlaybackTimer Feb 22 19:14:07 volumio volumio[802]: info: CoreStateMachine::servicePause Feb 22 19:14:07 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Feb 22 19:14:07 volumio volumio[802]: info: Error: no service or no trackblock to pause Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 19:14:10 volumio volumio[802]: info: Retrieving Cloud Streaming UI Feb 22 19:14:10 volumio volumio[802]: info: Getting Tidal Cloud Configuration Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 19:14:10 volumio volumio[802]: info: Getting Qobuz Cloud Configuration Feb 22 19:14:10 volumio volumio[802]: info: Asking plugin for UI Config Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 19:14:10 volumio volumio[802]: info: Getting Spotify Cloud Configuration Feb 22 19:14:10 volumio volumio[802]: info: Asking plugin for UI Config Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 19:14:10 volumio volumio[802]: info: Saving Spotify Acccount Feb 22 19:14:10 volumio volumio[802]: info: Got Tidal Cloud Configuration Feb 22 19:14:10 volumio volumio[802]: info: Got it Feb 22 19:14:10 volumio volumio[802]: info: Got it Feb 22 19:14:10 volumio volumio[802]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 19:14:10 volumio volumio[802]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::volumioGetBrowseSources Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 22 19:14:10 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 22 19:14:11 volumio volumio[802]: info: MyVolumio login type: Token Feb 22 19:14:12 volumio volumio[802]: info: Disabling MyMusic plugin upnp Feb 22 19:14:12 volumio sudo[1931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Feb 22 19:14:12 volumio sudo[1931]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 19:14:13 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Feb 22 19:14:13 volumio systemd[1]: upmpdcli.service: Succeeded. Feb 22 19:14:13 volumio systemd[1]: Stopped UPnP Renderer front-end to MPD. Feb 22 19:14:13 volumio sudo[1931]: pam_unix(sudo:session): session closed for user root Feb 22 19:14:13 volumio volumio[802]: info: Disabling plugin upnp Feb 22 19:14:13 volumio volumio[802]: info: Done. Feb 22 19:14:14 volumio volumio[802]: info: Enabling MyMusic plugin upnp Feb 22 19:14:14 volumio volumio[802]: info: Enabling plugin upnp Feb 22 19:14:14 volumio volumio[802]: info: Loading plugin "upnp"... Feb 22 19:14:14 volumio volumio[802]: info: [1740222854145] Starting Upmpd Daemon Feb 22 19:14:14 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 22 19:14:14 volumio volumio[802]: info: Done. Feb 22 19:14:14 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 22 19:14:15 volumio wpa_supplicant[717]: RRM: Ignoring radio measurement request: Not RRM network Feb 22 19:14:15 volumio wpa_supplicant[717]: RRM: Ignoring radio measurement request: Not RRM network Feb 22 19:14:18 volumio volumio[802]: info: Enabling MyMusic plugin bluetooth Feb 22 19:14:18 volumio volumio[802]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Feb 22 19:14:18 volumio volumio[802]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Feb 22 19:14:18 volumio volumio[802]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling Feb 22 19:14:18 volumio volumio[802]: error: Could not Enable MyMusic Plugin: Error Feb 22 19:14:19 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 22 19:14:21 volumio volumio[802]: info: CoreCommandRouter::volumioPause Feb 22 19:14:21 volumio volumio[802]: info: CoreStateMachine::pause Feb 22 19:14:23 volumio volumio[802]: info: CoreCommandRouter::volumioGetQueue Feb 22 19:14:23 volumio volumio[802]: info: CoreStateMachine::getQueue Feb 22 19:14:23 volumio volumio[802]: info: CorePlayQueue::getQueue Feb 22 19:14:24 volumio sudo[1935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 19:14:24 volumio sudo[1935]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 19:14:24 volumio sudo[1935]: pam_unix(sudo:session): session closed for user root Feb 22 19:14:24 volumio sudo[1937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 19:14:24 volumio sudo[1937]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 19:14:24 volumio sudo[1937]: pam_unix(sudo:session): session closed for user root Feb 22 19:14:24 volumio sudo[1941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 22 19:14:24 volumio sudo[1941]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 19:14:24 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 22 19:14:24 volumio sudo[1941]: pam_unix(sudo:session): session closed for user root Feb 22 19:14:24 volumio volumio[802]: info: Upmpdcli Daemon Started Feb 22 19:14:24 volumio volumio[1943]: writing RSA key Feb 22 19:14:26 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 19:14:26 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 19:14:26 volumio volumio[802]: info: Discovery: Getting this device information Feb 22 19:14:26 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Feb 22 19:14:26 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 19:14:36 volumio volumio[802]: info: Feb 22 19:14:36 volumio volumio[802]: ---------------------------- MPD announces state update: player Feb 22 19:14:36 volumio volumio[802]: info: ControllerMpd::getState Feb 22 19:14:36 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Feb 22 19:14:36 volumio volumio[802]: info: sendMpdCommand status took 3 milliseconds Feb 22 19:14:36 volumio volumio[802]: verbose: ControllerMpd::parseState Feb 22 19:14:36 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 19:14:36 volumio volumio[802]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 22 19:14:36 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Feb 22 19:14:36 volumio volumio[802]: info: ControllerMpd::pushState Feb 22 19:14:36 volumio volumio[802]: info: CoreCommandRouter::servicePushState Feb 22 19:14:36 volumio volumio[802]: verbose: In UPNP mode Feb 22 19:14:36 volumio volumio[802]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15687,"duration":5839,"samplerate":"16 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Unknown (streaming?)","artist":"Unknown","album":"Unknown","uri":"http://m701.music.126.net/20250222193857/0970865f3344c4f480b4aeec192f3895/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/26275193579/d34a/9dae/d755/10063d0b79407e5cdb87d0e0550aad18.flac?vuutv=dW/c/lAFeRRXeKQb5fiKWsKSAbWDoCMay9EZv8kSDJsY+p8bsVq65GF1xfR+chLKeQrvrI935CgBnzVhBgc5gMQCTFtTgFd8Il8kwtAMvXQ=","trackType":""} Feb 22 19:14:36 volumio volumio[802]: verbose: CURRENT POSITION 0 Feb 22 19:14:36 volumio volumio[802]: info: CoreStateMachine::syncState stateService pause Feb 22 19:14:36 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus pause Feb 22 19:14:36 volumio volumio[802]: info: CoreStateMachine::pushState Feb 22 19:14:36 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 19:14:36 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Feb 22 19:14:36 volumio volumio[802]: info: CoreStateMachine::stPlaybackTimer Feb 22 19:14:36 volumio volumio[802]: info: ------------------------------ 36ms Feb 22 19:14:36 volumio volumio[802]: info: CoreCommandRouter::volumioGetQueue Feb 22 19:14:36 volumio volumio[802]: info: CoreStateMachine::getQueue Feb 22 19:14:36 volumio volumio[802]: info: CorePlayQueue::getQueue Feb 22 19:14:41 volumio volumio[802]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 22 19:14:41 volumio volumio[802]: info: Retrying Login Due to Network Error Feb 22 19:14:49 volumio wpa_supplicant[717]: RRM: Ignoring radio measurement request: Not RRM network Feb 22 19:14:49 volumio wpa_supplicant[717]: RRM: Ignoring radio measurement request: Not RRM network Feb 22 19:14:51 volumio volumio[802]: info: MyVolumio login type: Token Feb 22 19:15:14 volumio volumio[802]: Upnp client error: Error: This socket has been ended by the other party Feb 22 19:15:14 volumio volumio[802]: info: Feb 22 19:15:14 volumio volumio[802]: ---------------------------- MPD announces state update: player Feb 22 19:15:14 volumio volumio[802]: info: ControllerMpd::getState Feb 22 19:15:14 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Feb 22 19:15:14 volumio volumio[802]: Upnp client error: Error: This socket has been ended by the other party Feb 22 19:15:14 volumio volumio[802]: info: sendMpdCommand status took 3 milliseconds Feb 22 19:15:14 volumio volumio[802]: verbose: ControllerMpd::parseState Feb 22 19:15:14 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 19:15:14 volumio volumio[802]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 22 19:15:14 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Feb 22 19:15:14 volumio volumio[802]: info: ControllerMpd::pushState Feb 22 19:15:14 volumio volumio[802]: info: CoreCommandRouter::servicePushState Feb 22 19:15:14 volumio volumio[802]: verbose: In UPNP mode Feb 22 19:15:14 volumio volumio[802]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":2705,"samplerate":"11.025 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Unknown (streaming?)","artist":"Unknown","album":"Unknown","uri":"http://m701.music.126.net/20250222193857/0970865f3344c4f480b4aeec192f3895/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/26275193579/d34a/9dae/d755/10063d0b79407e5cdb87d0e0550aad18.flac?vuutv=dW/c/lAFeRRXeKQb5fiKWsKSAbWDoCMay9EZv8kSDJsY+p8bsVq65GF1xfR+chLKeQrvrI935CgBnzVhBgc5gMQCTFtTgFd8Il8kwtAMvXQ=","trackType":""} Feb 22 19:15:14 volumio volumio[802]: verbose: CURRENT POSITION 0 Feb 22 19:15:14 volumio volumio[802]: info: CoreStateMachine::syncState stateService pause Feb 22 19:15:14 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus pause Feb 22 19:15:14 volumio volumio[802]: info: CoreStateMachine::pushState Feb 22 19:15:14 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Feb 22 19:15:14 volumio volumio[802]: info: CoreStateMachine::stPlaybackTimer Feb 22 19:15:14 volumio volumio[802]: info: ------------------------------ 28ms Feb 22 19:15:14 volumio volumio[802]: info: CoreCommandRouter::volumioGetQueue Feb 22 19:15:14 volumio volumio[802]: info: CoreStateMachine::getQueue Feb 22 19:15:14 volumio volumio[802]: info: CorePlayQueue::getQueue Feb 22 19:15:20 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 19:15:20 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 19:15:20 volumio volumio[802]: info: Discovery: Getting this device information Feb 22 19:15:20 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Feb 22 19:15:20 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 19:15:21 volumio volumio[802]: verbose: New Socket.io Connection to 192.168.3.156 from 192.168.3.3 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 5 Feb 22 19:15:21 volumio volumio[802]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 22 19:15:21 volumio volumio[802]: info: Retrying Login Due to Network Error Feb 22 19:15:21 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 22 19:15:21 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 19:15:21 volumio volumio[802]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 19:15:21 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 19:15:21 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Feb 22 19:15:21 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 19:15:21 volumio volumio[802]: info: Received Get System Info Feb 22 19:15:21 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 19:15:21 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 19:15:21 volumio volumio[802]: info: Discovery: Getting this device information Feb 22 19:15:21 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Feb 22 19:15:21 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 19:15:21 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Feb 22 19:15:21 volumio volumio[802]: info: Listing playlists Feb 22 19:15:21 volumio volumio[802]: info: CoreCommandRouter::volumioGetQueue Feb 22 19:15:21 volumio volumio[802]: info: CoreStateMachine::getQueue Feb 22 19:15:21 volumio volumio[802]: info: CorePlayQueue::getQueue Feb 22 19:15:26 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 19:15:26 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 19:15:26 volumio volumio[802]: info: Discovery: Getting this device information Feb 22 19:15:26 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Feb 22 19:15:26 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 19:15:29 volumio volumio[802]: info: Feb 22 19:15:29 volumio volumio[802]: ---------------------------- MPD announces state update: player Feb 22 19:15:29 volumio volumio[802]: info: ControllerMpd::getState Feb 22 19:15:29 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Feb 22 19:15:29 volumio volumio[802]: info: sendMpdCommand status took 2 milliseconds Feb 22 19:15:29 volumio volumio[802]: verbose: ControllerMpd::parseState Feb 22 19:15:29 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 19:15:29 volumio volumio[802]: info: Clearing queue after UPNP request Feb 22 19:15:29 volumio volumio[802]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 22 19:15:29 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Feb 22 19:15:29 volumio volumio[802]: info: ControllerMpd::pushState Feb 22 19:15:29 volumio volumio[802]: info: CoreCommandRouter::servicePushState Feb 22 19:15:29 volumio volumio[802]: verbose: In UPNP mode Feb 22 19:15:29 volumio volumio[802]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5839,"samplerate":"16 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Unknown (streaming?)","artist":"Unknown","album":"Unknown","uri":"http://m701.music.126.net/20250222193857/0970865f3344c4f480b4aeec192f3895/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/26275193579/d34a/9dae/d755/10063d0b79407e5cdb87d0e0550aad18.flac?vuutv=dW/c/lAFeRRXeKQb5fiKWsKSAbWDoCMay9EZv8kSDJsY+p8bsVq65GF1xfR+chLKeQrvrI935CgBnzVhBgc5gMQCTFtTgFd8Il8kwtAMvXQ=","trackType":""} Feb 22 19:15:29 volumio volumio[802]: verbose: CURRENT POSITION 0 Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::syncState stateService pause Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus pause Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::pushState Feb 22 19:15:29 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::stPlaybackTimer Feb 22 19:15:29 volumio volumio[802]: info: ------------------------------ 29ms Feb 22 19:15:29 volumio volumio[802]: info: CoreCommandRouter::volumioGetQueue Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::getQueue Feb 22 19:15:29 volumio volumio[802]: info: CorePlayQueue::getQueue Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::ClearQueue Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::stop Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::updateTrackBlock Feb 22 19:15:29 volumio volumio[802]: info: CorePlayQueue::getTrackBlock Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::stPlaybackTimer Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::pushState Feb 22 19:15:29 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Feb 22 19:15:29 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 19:15:29 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::serviceStop Feb 22 19:15:29 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Feb 22 19:15:29 volumio volumio[802]: info: ControllerMpd::stop Feb 22 19:15:29 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 19:15:29 volumio volumio[802]: info: CorePlayQueue::clearPlayQueue Feb 22 19:15:29 volumio volumio[802]: info: CorePlayQueue::saveQueue Feb 22 19:15:29 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Feb 22 19:15:29 volumio volumio[802]: info: CoreCommandRouter::volumioPushQueue Feb 22 19:15:29 volumio volumio[802]: info: Feb 22 19:15:29 volumio volumio[802]: ---------------------------- MPD announces state update: player Feb 22 19:15:29 volumio volumio[802]: info: sendMpdCommand stop took 43 milliseconds Feb 22 19:15:29 volumio volumio[802]: info: ControllerMpd::getState Feb 22 19:15:29 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Feb 22 19:15:29 volumio volumio[802]: info: Feb 22 19:15:29 volumio volumio[802]: ---------------------------- MPD announces system playlist update Feb 22 19:15:29 volumio volumio[802]: info: Ignoring MPD Status Update Feb 22 19:15:29 volumio volumio[802]: info: sendMpdCommand status took 3 milliseconds Feb 22 19:15:29 volumio volumio[802]: verbose: ControllerMpd::parseState Feb 22 19:15:29 volumio volumio[802]: info: ControllerMpd::pushState Feb 22 19:15:29 volumio volumio[802]: info: CoreCommandRouter::servicePushState Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::pushState Feb 22 19:15:29 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Feb 22 19:15:29 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Feb 22 19:15:29 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Feb 22 19:15:29 volumio volumio[802]: 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} Feb 22 19:15:29 volumio volumio[802]: verbose: CURRENT POSITION 0 Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::syncState stateService stop Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus stop Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::pushState Feb 22 19:15:29 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Feb 22 19:15:29 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Feb 22 19:15:29 volumio volumio[802]: info: No code Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::pushState Feb 22 19:15:29 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Feb 22 19:15:29 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Feb 22 19:15:29 volumio volumio[802]: info: ------------------------------ 51ms Feb 22 19:15:29 volumio volumio[802]: error: updateQueue error: null Feb 22 19:15:29 volumio volumio[802]: info: ------------------------------ 50ms Feb 22 19:15:29 volumio volumio[802]: info: Starting UPNP Playback Feb 22 19:15:29 volumio volumio[802]: info: Preparing playback through UPNP Feb 22 19:15:29 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Feb 22 19:15:29 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Feb 22 19:15:29 volumio volumio[802]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 22 19:15:30 volumio volumio[802]: info: Feb 22 19:15:30 volumio volumio[802]: ---------------------------- MPD announces system playlist update Feb 22 19:15:30 volumio volumio[802]: info: Ignoring MPD Status Update Feb 22 19:15:30 volumio volumio[802]: info: Feb 22 19:15:30 volumio volumio[802]: ---------------------------- MPD announces system playlist update Feb 22 19:15:30 volumio volumio[802]: info: Ignoring MPD Status Update Feb 22 19:15:30 volumio volumio[802]: info: ------------------------------ 4ms Feb 22 19:15:30 volumio volumio[802]: info: ------------------------------ 3ms Feb 22 19:15:30 volumio volumio[802]: info: Feb 22 19:15:30 volumio volumio[802]: ---------------------------- MPD announces system playlist update Feb 22 19:15:30 volumio volumio[802]: info: Ignoring MPD Status Update Feb 22 19:15:30 volumio volumio[802]: info: ------------------------------ 2ms Feb 22 19:15:30 volumio volumio[802]: info: Feb 22 19:15:30 volumio volumio[802]: ---------------------------- MPD announces system playlist update Feb 22 19:15:30 volumio volumio[802]: info: Ignoring MPD Status Update Feb 22 19:15:30 volumio volumio[802]: info: ------------------------------ 3ms Feb 22 19:15:30 volumio volumio[802]: info: Feb 22 19:15:30 volumio volumio[802]: ---------------------------- MPD announces system playlist update Feb 22 19:15:30 volumio volumio[802]: info: Ignoring MPD Status Update Feb 22 19:15:30 volumio volumio[802]: info: ------------------------------ 3ms Feb 22 19:15:30 volumio volumio[802]: info: Feb 22 19:15:30 volumio volumio[802]: ---------------------------- MPD announces system playlist update Feb 22 19:15:30 volumio volumio[802]: info: Ignoring MPD Status Update Feb 22 19:15:30 volumio volumio[802]: info: ------------------------------ 3ms Feb 22 19:15:30 volumio volumio[802]: info: Feb 22 19:15:30 volumio volumio[802]: ---------------------------- MPD announces state update: player Feb 22 19:15:30 volumio volumio[802]: info: ControllerMpd::getState Feb 22 19:15:30 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Feb 22 19:15:30 volumio volumio[802]: info: Feb 22 19:15:30 volumio volumio[802]: ---------------------------- MPD announces state update: player Feb 22 19:15:30 volumio volumio[802]: info: sendMpdCommand status took 11 milliseconds Feb 22 19:15:30 volumio volumio[802]: info: ControllerMpd::getState Feb 22 19:15:30 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Feb 22 19:15:30 volumio volumio[802]: verbose: ControllerMpd::parseState Feb 22 19:15:30 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 19:15:30 volumio volumio[802]: info: sendMpdCommand status took 3 milliseconds Feb 22 19:15:30 volumio volumio[802]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 22 19:15:30 volumio volumio[802]: verbose: ControllerMpd::parseState Feb 22 19:15:30 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 19:15:30 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Feb 22 19:15:30 volumio volumio[802]: info: ControllerMpd::pushState Feb 22 19:15:30 volumio volumio[802]: info: CoreCommandRouter::servicePushState Feb 22 19:15:30 volumio volumio[802]: verbose: In UPNP mode Feb 22 19:15:30 volumio volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":6791,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"钟无艳","artist":"谢安琪","album":"3/8(新歌+精选)","uri":"http://m701.music.126.net/20250222194029/ca2da779e4a59600c87fc13ad612374a/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32386581065/2368/6db3/56b7/fe362988059dcbfc02e280b37db05a47.flac?vuutv=Xk+TqdKhktFeCj3JkMRQ1KuOtu0QewhASEOTqy8ZaJAOSUrAd3tpNiVvgO4ZIGTi1/wEBDW20aBOw4CYWZoILwUTRI8u9L6/yzaxtELjRrA=","trackType":""} Feb 22 19:15:30 volumio volumio[802]: verbose: CURRENT POSITION 0 Feb 22 19:15:30 volumio volumio[802]: info: CoreStateMachine::syncState stateService play Feb 22 19:15:30 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus stop Feb 22 19:15:30 volumio volumio[802]: info: ------------------------------ 25ms Feb 22 19:15:30 volumio volumio[802]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 22 19:15:30 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Feb 22 19:15:30 volumio volumio[802]: info: ControllerMpd::pushState Feb 22 19:15:30 volumio volumio[802]: info: CoreCommandRouter::servicePushState Feb 22 19:15:30 volumio volumio[802]: verbose: In UPNP mode Feb 22 19:15:30 volumio volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":6791,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"钟无艳","artist":"谢安琪","album":"3/8(新歌+精选)","uri":"http://m701.music.126.net/20250222194029/ca2da779e4a59600c87fc13ad612374a/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32386581065/2368/6db3/56b7/fe362988059dcbfc02e280b37db05a47.flac?vuutv=Xk+TqdKhktFeCj3JkMRQ1KuOtu0QewhASEOTqy8ZaJAOSUrAd3tpNiVvgO4ZIGTi1/wEBDW20aBOw4CYWZoILwUTRI8u9L6/yzaxtELjRrA=","trackType":""} Feb 22 19:15:30 volumio volumio[802]: verbose: CURRENT POSITION 0 Feb 22 19:15:30 volumio volumio[802]: info: CoreStateMachine::syncState stateService play Feb 22 19:15:30 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus play Feb 22 19:15:30 volumio volumio[802]: info: Received an update from plugin. extracting info from payload Feb 22 19:15:30 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 22 19:15:30 volumio volumio[802]: info: CoreStateMachine::pushState Feb 22 19:15:30 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 19:15:30 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Feb 22 19:15:30 volumio volumio[802]: info: CoreStateMachine::pushState Feb 22 19:15:30 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Feb 22 19:15:30 volumio volumio[802]: info: ------------------------------ 48ms Feb 22 19:15:30 volumio volumio[802]: info: CoreCommandRouter::volumioGetQueue Feb 22 19:15:30 volumio volumio[802]: info: CoreStateMachine::getQueue Feb 22 19:15:30 volumio volumio[802]: info: CorePlayQueue::getQueue Feb 22 19:15:30 volumio volumio[802]: info: CoreCommandRouter::volumioGetQueue Feb 22 19:15:30 volumio volumio[802]: info: CoreStateMachine::getQueue Feb 22 19:15:30 volumio volumio[802]: info: CorePlayQueue::getQueue Feb 22 19:15:31 volumio volumio[802]: info: MyVolumio login type: Token Feb 22 19:15:36 volumio volumio[802]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 19:15:36 volumio volumio[802]: Error: connect ETIMEDOUT 203.111.254.117:443 Feb 22 19:15:36 volumio volumio[802]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Feb 22 19:15:36 volumio volumio[802]: errno: -110, Feb 22 19:15:36 volumio volumio[802]: code: 'ETIMEDOUT', Feb 22 19:15:36 volumio volumio[802]: syscall: 'connect', Feb 22 19:15:36 volumio volumio[802]: address: '203.111.254.117', Feb 22 19:15:36 volumio volumio[802]: port: 443 Feb 22 19:15:36 volumio volumio[802]: } Feb 22 19:15:36 volumio volumio[802]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 19:15:37 volumio sudo[2048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-22 19:14 Feb 22 19:15:37 volumio sudo[2048]: 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="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 05:45:03 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6428d8e2c06e98483f5c5d77e8f01989"