-- Logs begin at Fri 2024-04-26 06:27:39 UTC, end at Fri 2024-04-26 06:41:54 UTC. -- Apr 26 06:40:00 volumio volumio[2542]: info: CoreCommandRouter::volumioPlay Apr 26 06:40:00 volumio volumio[2542]: info: CoreStateMachine::play index undefined Apr 26 06:40:00 volumio volumio[2542]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 06:40:00 volumio volumio[2542]: info: CorePlayQueue::getTrack 0 Apr 26 06:40:00 volumio volumio[2542]: info: CoreStateMachine::startPlaybackTimer Apr 26 06:40:00 volumio volumio[2542]: info: CorePlayQueue::getTrack 0 Apr 26 06:40:00 volumio volumio[2542]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/世爵超级抓轨/Chopin_ Complete Nocturnes [Disc 1]/1-01 Chopin_ Nocturne #1 In B Flat Minor, Op. 9_1, CT 108.wav Apr 26 06:40:00 volumio volumio[2542]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 06:40:00 volumio volumio[2542]: info: sendMpdCommand stop took 3 milliseconds Apr 26 06:40:00 volumio volumio[2542]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 06:40:00 volumio volumio[2542]: info: Apr 26 06:40:00 volumio volumio[2542]: ---------------------------- MPD announces system playlist update Apr 26 06:40:00 volumio volumio[2542]: info: Ignoring MPD Status Update Apr 26 06:40:00 volumio volumio[2542]: info: sendMpdCommand clear took 2 milliseconds Apr 26 06:40:00 volumio volumio[2542]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/世爵超级抓轨/Chopin_ Complete Nocturnes [Disc 1]/1-01 Chopin_ Nocturne #1 In B Flat Minor, Op. 9_1, CT 108.wav" Apr 26 06:40:00 volumio volumio[2542]: error: updateQueue error: null Apr 26 06:40:00 volumio volumio[2542]: info: Apr 26 06:40:00 volumio volumio[2542]: ---------------------------- MPD announces system playlist update Apr 26 06:40:00 volumio volumio[2542]: info: Ignoring MPD Status Update Apr 26 06:40:00 volumio volumio[2542]: info: ------------------------------ 4ms Apr 26 06:40:00 volumio volumio[2542]: info: sendMpdCommand add "INTERNAL/世爵超级抓轨/Chopin_ Complete Nocturnes [Disc 1]/1-01 Chopin_ Nocturne #1 In B Flat Minor, Op. 9_1, CT 108.wav" took 3 milliseconds Apr 26 06:40:00 volumio volumio[2542]: verbose: ControllerMpd::sendMpdCommand play Apr 26 06:40:00 volumio volumio[2542]: info: ------------------------------ 4ms Apr 26 06:40:00 volumio volumio[2542]: info: Apr 26 06:40:00 volumio volumio[2542]: ---------------------------- MPD announces state update: player Apr 26 06:40:00 volumio volumio[2542]: info: sendMpdCommand play took 4 milliseconds Apr 26 06:40:00 volumio volumio[2542]: info: ControllerMpd::getState Apr 26 06:40:00 volumio volumio[2542]: verbose: ControllerMpd::sendMpdCommand status Apr 26 06:40:00 volumio volumio[2542]: info: Apr 26 06:40:00 volumio volumio[2542]: ---------------------------- MPD announces state update: player Apr 26 06:40:00 volumio volumio[2542]: info: sendMpdCommand status took 13 milliseconds Apr 26 06:40:00 volumio volumio[2542]: info: ControllerMpd::getState Apr 26 06:40:00 volumio volumio[2542]: verbose: ControllerMpd::sendMpdCommand status Apr 26 06:40:00 volumio volumio[2542]: verbose: ControllerMpd::parseState Apr 26 06:40:00 volumio volumio[2542]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 06:40:00 volumio volumio[2542]: info: sendMpdCommand status took 2 milliseconds Apr 26 06:40:00 volumio volumio[2542]: verbose: ControllerMpd::parseState Apr 26 06:40:00 volumio volumio[2542]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 06:40:00 volumio volumio[2542]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 26 06:40:00 volumio volumio[2542]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 26 06:40:00 volumio volumio[2542]: verbose: ControllerMpd::parseTrackInfo Apr 26 06:40:00 volumio volumio[2542]: verbose: ControllerMpd::parseTrackInfo Apr 26 06:40:00 volumio volumio[2542]: info: ControllerMpd::pushState Apr 26 06:40:00 volumio volumio[2542]: info: CoreCommandRouter::servicePushState Apr 26 06:40:00 volumio volumio[2542]: info: CorePlayQueue::getTrack 0 Apr 26 06:40:00 volumio volumio[2542]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":343,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1-01 Chopin_ Nocturne #1 In B Flat Minor, Op. 9_1, CT 108.wav","artist":null,"album":null,"uri":"INTERNAL/世爵超级抓轨/Chopin_ Complete Nocturnes [Disc 1]/1-01 Chopin_ Nocturne #1 In B Flat Minor, Op. 9_1, CT 108.wav","trackType":"wav"} Apr 26 06:40:00 volumio volumio[2542]: verbose: CURRENT POSITION 0 Apr 26 06:40:00 volumio volumio[2542]: info: CoreStateMachine::syncState stateService play Apr 26 06:40:00 volumio volumio[2542]: info: CoreStateMachine::syncState currentStatus stop Apr 26 06:40:00 volumio volumio[2542]: info: ControllerMpd::pushState Apr 26 06:40:00 volumio volumio[2542]: info: CoreCommandRouter::servicePushState Apr 26 06:40:00 volumio volumio[2542]: info: CorePlayQueue::getTrack 0 Apr 26 06:40:00 volumio volumio[2542]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":343,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1-01 Chopin_ Nocturne #1 In B Flat Minor, Op. 9_1, CT 108.wav","artist":null,"album":null,"uri":"INTERNAL/世爵超级抓轨/Chopin_ Complete Nocturnes [Disc 1]/1-01 Chopin_ Nocturne #1 In B Flat Minor, Op. 9_1, CT 108.wav","trackType":"wav"} Apr 26 06:40:00 volumio volumio[2542]: verbose: CURRENT POSITION 0 Apr 26 06:40:00 volumio volumio[2542]: info: CoreStateMachine::syncState stateService play Apr 26 06:40:00 volumio volumio[2542]: info: CoreStateMachine::syncState currentStatus play Apr 26 06:40:00 volumio volumio[2542]: info: Received an update from plugin. extracting info from payload Apr 26 06:40:00 volumio volumio[2542]: info: CoreStateMachine::pushState Apr 26 06:40:00 volumio volumio[2542]: info: CorePlayQueue::getTrack 0 Apr 26 06:40:00 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 06:40:00 volumio volumio[2542]: info: CoreCommandRouter::volumioPushState Apr 26 06:40:00 volumio volumio[2542]: info: CoreStateMachine::pushState Apr 26 06:40:00 volumio volumio[2542]: info: CorePlayQueue::getTrack 0 Apr 26 06:40:00 volumio volumio[2542]: info: CoreCommandRouter::volumioPushState Apr 26 06:40:00 volumio volumio[2542]: info: ------------------------------ 33ms Apr 26 06:40:00 volumio volumio[2542]: info: ------------------------------ 24ms Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 26 06:40:02 volumio volumio[2542]: info: Adding plugin bluetooth to MyMusic Plugins Apr 26 06:40:02 volumio volumio[2542]: info: Adding plugin multiroom to MyMusic Plugins Apr 26 06:40:02 volumio volumio[2542]: info: Adding plugin metavolumio to MyMusic Plugins Apr 26 06:40:02 volumio volumio[2542]: info: Adding plugin cd_controller to MyMusic Plugins Apr 26 06:40:02 volumio volumio[2542]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 26 06:40:02 volumio volumio[2542]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 26 06:40:02 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 06:40:02 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 06:40:02 volumio volumio[2542]: info: Starting MyVolumio Remote Streaming Endpoints Apr 26 06:40:02 volumio volumio[2542]: info: MyVolumio not started Apr 26 06:40:02 volumio volumio[2542]: info: Initializing device activation check Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 26 06:40:02 volumio volumio[2542]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 26 06:40:03 volumio volumio[2542]: info: Starting Streaming Service Transparent Proxy Apr 26 06:40:03 volumio volumio[2542]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 26 06:40:03 volumio volumio[2542]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 26 06:40:03 volumio volumio[2542]: info: Streaming services startup Apr 26 06:40:03 volumio volumio[2542]: info: Starting Streaming Daemon Apr 26 06:40:03 volumio sudo[2732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 26 06:40:03 volumio sudo[2732]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 06:40:03 volumio sudo[2732]: pam_unix(sudo:session): session closed for user root Apr 26 06:40:03 volumio volumio[2542]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 26 06:40:03 volumio volumio[2542]: error: Cannot start Volumio Streaming Daemon Apr 26 06:40:03 volumio volumio[2542]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 26 06:40:03 volumio volumio[2542]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 26 06:40:04 volumio volumio[2542]: STREAMING PROXY: Starting server on port 3245 Apr 26 06:40:04 volumio volumio[2542]: Node JS runtime: 14 Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 26 06:40:04 volumio volumio[2542]: info: Adding plugin bluetooth to MyMusic Plugins Apr 26 06:40:04 volumio volumio[2542]: info: Adding plugin multiroom to MyMusic Plugins Apr 26 06:40:04 volumio volumio[2542]: info: Adding plugin metavolumio to MyMusic Plugins Apr 26 06:40:04 volumio volumio[2542]: info: Adding plugin cd_controller to MyMusic Plugins Apr 26 06:40:04 volumio volumio[2542]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 26 06:40:04 volumio volumio[2542]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 26 06:40:04 volumio volumio[2542]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 26 06:40:04 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 06:40:04 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 06:40:04 volumio volumio[2542]: info: Starting MyVolumio Remote Streaming Endpoints Apr 26 06:40:07 volumio volumio[2542]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 06:40:07 volumio volumio[2542]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 06:40:07 volumio volumio[2542]: info: Retrieving Cloud Streaming UI Apr 26 06:40:07 volumio volumio[2542]: info: Getting Tidal Cloud Configuration Apr 26 06:40:07 volumio volumio[2542]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 06:40:07 volumio volumio[2542]: info: Getting Qobuz Cloud Configuration Apr 26 06:40:07 volumio volumio[2542]: info: Asking plugin for UI Config Apr 26 06:40:07 volumio volumio[2542]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 06:40:07 volumio volumio[2542]: info: Getting Spotify Cloud Configuration Apr 26 06:40:07 volumio volumio[2542]: info: Asking plugin for UI Config Apr 26 06:40:07 volumio volumio[2542]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 06:40:07 volumio volumio[2542]: info: Saving Spotify Acccount Apr 26 06:40:07 volumio volumio[2542]: info: Got Tidal Cloud Configuration Apr 26 06:40:07 volumio volumio[2542]: info: Got it Apr 26 06:40:07 volumio volumio[2542]: info: Got it Apr 26 06:40:07 volumio volumio[2542]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 26 06:40:07 volumio volumio[2542]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 06:40:07 volumio volumio[2542]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 26 06:40:07 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 26 06:40:07 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 26 06:40:07 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 26 06:40:07 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 06:40:07 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 06:40:07 volumio volumio[2542]: info: CoreCommandRouter::volumioGetBrowseSources Apr 26 06:40:07 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 06:40:07 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 26 06:40:08 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 26 06:40:08 volumio volumio[2542]: verbose: ControllerMpd::sendMpdCommand update Apr 26 06:40:08 volumio volumio[2542]: verbose: ControllerMpd::sendMpdCommand status Apr 26 06:40:08 volumio volumio[2542]: info: Apr 26 06:40:08 volumio volumio[2542]: ---------------------------- MPD announces state update: update Apr 26 06:40:08 volumio volumio[2542]: info: sendMpdCommand update took 4 milliseconds Apr 26 06:40:08 volumio volumio[2542]: info: ControllerMpd::getState Apr 26 06:40:08 volumio volumio[2542]: verbose: ControllerMpd::sendMpdCommand status Apr 26 06:40:08 volumio volumio[2542]: info: sendMpdCommand status took 3 milliseconds Apr 26 06:40:08 volumio volumio[2542]: verbose: ControllerMpd::parseState Apr 26 06:40:08 volumio volumio[2542]: info: Command Router : Notfying DB Updatetrue Apr 26 06:40:08 volumio volumio[2542]: info: sendMpdCommand status took 40 milliseconds Apr 26 06:40:08 volumio volumio[2542]: verbose: ControllerMpd::parseState Apr 26 06:40:08 volumio volumio[2542]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 06:40:08 volumio volumio[2542]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 26 06:40:08 volumio volumio[2542]: verbose: ControllerMpd::parseTrackInfo Apr 26 06:40:08 volumio volumio[2542]: info: ControllerMpd::pushState Apr 26 06:40:08 volumio volumio[2542]: info: CoreCommandRouter::servicePushState Apr 26 06:40:08 volumio volumio[2542]: info: CorePlayQueue::getTrack 0 Apr 26 06:40:08 volumio volumio[2542]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9470,"duration":343,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1-01 Chopin_ Nocturne #1 In B Flat Minor, Op. 9_1, CT 108.wav","artist":null,"album":null,"uri":"INTERNAL/世爵超级抓轨/Chopin_ Complete Nocturnes [Disc 1]/1-01 Chopin_ Nocturne #1 In B Flat Minor, Op. 9_1, CT 108.wav","trackType":"wav"} Apr 26 06:40:08 volumio volumio[2542]: verbose: CURRENT POSITION 0 Apr 26 06:40:08 volumio volumio[2542]: info: CoreStateMachine::syncState stateService play Apr 26 06:40:08 volumio volumio[2542]: info: CoreStateMachine::syncState currentStatus play Apr 26 06:40:08 volumio volumio[2542]: info: Received an update from plugin. extracting info from payload Apr 26 06:40:08 volumio volumio[2542]: info: CoreStateMachine::pushState Apr 26 06:40:08 volumio volumio[2542]: info: CorePlayQueue::getTrack 0 Apr 26 06:40:08 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 06:40:08 volumio volumio[2542]: info: CoreCommandRouter::volumioPushState Apr 26 06:40:08 volumio volumio[2542]: info: CoreStateMachine::pushState Apr 26 06:40:08 volumio volumio[2542]: info: CorePlayQueue::getTrack 0 Apr 26 06:40:08 volumio volumio[2542]: info: CoreCommandRouter::volumioPushState Apr 26 06:40:08 volumio volumio[2542]: info: ------------------------------ 63ms Apr 26 06:40:09 volumio volumio[2542]: verbose: ControllerMpd::sendMpdCommand status Apr 26 06:40:09 volumio volumio[2542]: info: Apr 26 06:40:09 volumio volumio[2542]: ---------------------------- MPD announces state update: update Apr 26 06:40:09 volumio volumio[2542]: info: ControllerMpd::getState Apr 26 06:40:09 volumio volumio[2542]: verbose: ControllerMpd::sendMpdCommand status Apr 26 06:40:09 volumio volumio[2542]: info: sendMpdCommand status took 2 milliseconds Apr 26 06:40:09 volumio volumio[2542]: info: sendMpdCommand status took 2 milliseconds Apr 26 06:40:09 volumio volumio[2542]: verbose: ControllerMpd::parseState Apr 26 06:40:09 volumio volumio[2542]: info: Command Router : Notfying DB Updatefalse Apr 26 06:40:09 volumio volumio[2542]: info: CoreCommandRouter::Close All Modals sent Apr 26 06:40:09 volumio volumio[2542]: verbose: ControllerMpd::parseState Apr 26 06:40:09 volumio volumio[2542]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 06:40:09 volumio volumio[2542]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 26 06:40:09 volumio volumio[2542]: verbose: ControllerMpd::parseTrackInfo Apr 26 06:40:09 volumio volumio[2542]: info: ControllerMpd::pushState Apr 26 06:40:09 volumio volumio[2542]: info: CoreCommandRouter::servicePushState Apr 26 06:40:09 volumio volumio[2542]: info: CorePlayQueue::getTrack 0 Apr 26 06:40:09 volumio volumio[2542]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10335,"duration":343,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1-01 Chopin_ Nocturne #1 In B Flat Minor, Op. 9_1, CT 108.wav","artist":null,"album":null,"uri":"INTERNAL/世爵超级抓轨/Chopin_ Complete Nocturnes [Disc 1]/1-01 Chopin_ Nocturne #1 In B Flat Minor, Op. 9_1, CT 108.wav","trackType":"wav"} Apr 26 06:40:09 volumio volumio[2542]: verbose: CURRENT POSITION 0 Apr 26 06:40:09 volumio volumio[2542]: info: CoreStateMachine::syncState stateService play Apr 26 06:40:09 volumio volumio[2542]: info: CoreStateMachine::syncState currentStatus play Apr 26 06:40:09 volumio volumio[2542]: info: Received an update from plugin. extracting info from payload Apr 26 06:40:09 volumio volumio[2542]: info: CoreStateMachine::pushState Apr 26 06:40:09 volumio volumio[2542]: info: CorePlayQueue::getTrack 0 Apr 26 06:40:09 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 06:40:09 volumio volumio[2542]: info: CoreCommandRouter::volumioPushState Apr 26 06:40:09 volumio volumio[2542]: info: CoreStateMachine::pushState Apr 26 06:40:09 volumio volumio[2542]: info: CorePlayQueue::getTrack 0 Apr 26 06:40:09 volumio volumio[2542]: info: CoreCommandRouter::volumioPushState Apr 26 06:40:09 volumio volumio[2542]: info: ------------------------------ 56ms Apr 26 06:40:11 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 06:40:13 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 06:40:13 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 06:40:13 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 06:40:14 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 26 06:40:15 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 06:40:17 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 26 06:40:17 volumio volumio[2542]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 31 Apr 26 06:40:17 volumio volumio[2542]: info: CoreCommandRouter::volumioGetState Apr 26 06:40:17 volumio volumio[2542]: info: CorePlayQueue::getTrack 0 Apr 26 06:40:19 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 06:40:19 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 26 06:40:19 volumio volumio[2542]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 32 Apr 26 06:40:19 volumio volumio[2542]: info: CoreCommandRouter::volumioGetState Apr 26 06:40:19 volumio volumio[2542]: info: CorePlayQueue::getTrack 0 Apr 26 06:40:23 volumio ntpd[1135]: 119.28.183.184 local addr 192.168.3.111 -> Apr 26 06:40:23 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 06:40:23 volumio volumio[2542]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 26 06:40:23 volumio volumio[2542]: info: Completed starting MyVolumio Plugin Apr 26 06:40:23 volumio volumio[2542]: info: BOOT COMPLETED Apr 26 06:40:23 volumio volumio[2542]: [Metrics] CommandRouter: 43s 15.82ms Apr 26 06:40:23 volumio volumio[2542]: info: CoreCommandRouter::volumiosetStartupVolume Apr 26 06:40:23 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 06:40:23 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 06:40:23 volumio volumio[2542]: info: CoreCommandRouter::Close All Modals sent Apr 26 06:40:23 volumio volumio[2542]: info: CoreCommandRouter::Close All Modals sent Apr 26 06:40:23 volumio volumio[2542]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Apr 26 06:40:23 volumio volumio[2542]: aplay: main:828: audio open error: Device or resource busy Apr 26 06:40:24 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 26 06:40:24 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 26 06:40:24 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 26 06:40:27 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 06:40:31 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 06:40:33 volumio volumio[2542]: info: CALLMETHOD: miscellanea my_music updateMusicLibraryBrowseSourcesVisibility [object Object] Apr 26 06:40:33 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibraryBrowseSourcesVisibility Apr 26 06:40:33 volumio volumio[2542]: info: CoreCommandRouter::volumioUpdateToBrowseSources Apr 26 06:40:33 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 06:40:33 volumio volumio[2542]: Cannot find translation for sourceMedia Servers Apr 26 06:40:35 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 06:40:39 volumio volumio[2542]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 06:40:39 volumio volumio[2542]: /bin/cat: /sys/class/net/wlan0/flags: No such file or directory Apr 26 06:40:39 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 26 06:40:39 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 26 06:40:39 volumio sudo[2756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 26 06:40:39 volumio sudo[2756]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 06:40:39 volumio sudo[2756]: pam_unix(sudo:session): session closed for user root Apr 26 06:40:39 volumio sudo[2762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 26 06:40:39 volumio sudo[2762]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 06:40:39 volumio sudo[2771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 26 06:40:39 volumio sudo[2771]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 06:40:39 volumio sudo[2762]: pam_unix(sudo:session): session closed for user root Apr 26 06:40:39 volumio sudo[2777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 26 06:40:39 volumio sudo[2777]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 06:40:39 volumio sudo[2771]: pam_unix(sudo:session): session closed for user root Apr 26 06:40:39 volumio sudo[2788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 06:40:39 volumio sudo[2788]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 06:40:39 volumio sudo[2777]: pam_unix(sudo:session): session closed for user root Apr 26 06:40:39 volumio sudo[2788]: pam_unix(sudo:session): session closed for user root Apr 26 06:40:39 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 26 06:40:39 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 26 06:40:39 volumio sudo[2797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 06:40:39 volumio sudo[2797]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 06:40:39 volumio sudo[2797]: pam_unix(sudo:session): session closed for user root Apr 26 06:40:39 volumio sudo[2801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 26 06:40:39 volumio sudo[2801]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 06:40:39 volumio sudo[2801]: pam_unix(sudo:session): session closed for user root Apr 26 06:40:39 volumio volumio[2542]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 26 06:40:39 volumio volumio[2542]: wlan0 Interface doesn't support scanning. Apr 26 06:40:39 volumio volumio[2542]: info: Cannot use regular scanning, forcing with ap-force Apr 26 06:40:39 volumio sudo[2807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 26 06:40:39 volumio sudo[2807]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 06:40:39 volumio sudo[2807]: pam_unix(sudo:session): session closed for user root Apr 26 06:40:39 volumio volumio[2542]: command failed: No such device (-19) Apr 26 06:40:39 volumio volumio[2542]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Apr 26 06:40:39 volumio volumio[2542]: command failed: No such device (-19) Apr 26 06:40:57 volumio volumio[2542]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 06:40:57 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 26 06:40:57 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 26 06:40:57 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 26 06:40:57 volumio volumio[2542]: info: Received Get System Version Apr 26 06:40:57 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 06:41:13 volumio volumio[2542]: info: CoreCommandRouter::Close All Modals sent Apr 26 06:41:15 volumio volumio[2542]: info: CoreCommandRouter::Close All Modals sent Apr 26 06:41:17 volumio volumio[2542]: info: CoreCommandRouter::Close All Modals sent Apr 26 06:41:22 volumio volumio[2542]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Apr 26 06:41:22 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Apr 26 06:41:22 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 26 06:41:22 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 06:41:22 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 26 06:41:24 volumio volumio[2542]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Apr 26 06:41:24 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Apr 26 06:41:24 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 26 06:41:24 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 06:41:24 volumio volumio[2542]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Apr 26 06:41:24 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Apr 26 06:41:24 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 26 06:41:24 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 06:41:24 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 26 06:41:24 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 26 06:41:26 volumio volumio[2542]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Apr 26 06:41:26 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Apr 26 06:41:26 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 26 06:41:26 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 06:41:26 volumio volumio[2542]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Apr 26 06:41:26 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Apr 26 06:41:26 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 26 06:41:26 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 06:41:26 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 26 06:41:26 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 26 06:41:26 volumio volumio[2542]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Apr 26 06:41:26 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Apr 26 06:41:26 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 26 06:41:26 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 06:41:26 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 26 06:41:32 volumio volumio[2542]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 06:41:32 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 26 06:41:32 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 26 06:41:32 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 26 06:41:32 volumio volumio[2542]: info: Received Get System Version Apr 26 06:41:32 volumio volumio[2542]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 06:41:41 volumio volumio[2542]: info: CoreCommandRouter::Close All Modals sent Apr 26 06:41:45 volumio volumio[2542]: info: CoreCommandRouter::Close All Modals sent Apr 26 06:41:54 volumio volumio[2542]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 06:41:54 volumio volumio[2542]: Error: connect ETIMEDOUT 31.13.71.19:443 Apr 26 06:41:54 volumio volumio[2542]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 26 06:41:54 volumio volumio[2542]: errno: -110, Apr 26 06:41:54 volumio volumio[2542]: code: 'ETIMEDOUT', Apr 26 06:41:54 volumio volumio[2542]: syscall: 'connect', Apr 26 06:41:54 volumio volumio[2542]: address: '31.13.71.19', Apr 26 06:41:54 volumio volumio[2542]: port: 443 Apr 26 06:41:54 volumio volumio[2542]: } Apr 26 06:41:54 volumio volumio[2542]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 06:41:54 volumio sudo[2854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-26 06:40 Apr 26 06:41:54 volumio sudo[2854]: 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="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 06:13:59 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6a6571e2ab129da64c127254a7a779b9"