-- Logs begin at Thu 2019-02-14 12:11:59 EET, end at Sun 2025-01-19 22:43:13 EET. -- Jan 19 22:42:00 volumio sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=miro,password=mkocev123,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.0.223/Music /mnt/NAS/MYBOOKLIVE Jan 19 22:42:00 volumio sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 22:42:00 volumio sudo[1242]: pam_unix(sudo:session): session closed for user root Jan 19 22:42:00 volumio kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Jan 19 22:42:00 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Jan 19 22:42:00 volumio kernel: CIFS: Attempting to mount //192.168.0.223/Music Jan 19 22:42:00 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 19 22:42:00 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Jan 19 22:42:00 volumio volumio[961]: info: Cannot mount NAS MYBOOKLIVE at system boot, trial number 3 ,retrying in 5 seconds Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 19 22:42:01 volumio volumio[961]: info: Adding plugin bluetooth to MyMusic Plugins Jan 19 22:42:01 volumio volumio[961]: info: Adding plugin multiroom to MyMusic Plugins Jan 19 22:42:01 volumio volumio[961]: info: Adding plugin metavolumio to MyMusic Plugins Jan 19 22:42:01 volumio volumio[961]: info: Adding plugin cd_controller to MyMusic Plugins Jan 19 22:42:01 volumio volumio[961]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 19 22:42:01 volumio volumio[961]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 19 22:42:01 volumio volumio[961]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 19 22:42:02 volumio volumio[961]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 19 22:42:02 volumio volumio[961]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 19 22:42:02 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 22:42:02 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 22:42:02 volumio volumio[961]: info: Starting MyVolumio Remote Streaming Endpoints Jan 19 22:42:02 volumio volumio[961]: info: MyVolumio login type: Token Jan 19 22:42:02 volumio volumio[961]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 19 22:42:02 volumio volumio[961]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 19 22:42:04 volumio volumio[961]: info: Starting Streaming Service Transparent Proxy Jan 19 22:42:04 volumio volumio[961]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 19 22:42:04 volumio volumio[961]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 19 22:42:04 volumio volumio[961]: info: Streaming services startup Jan 19 22:42:04 volumio volumio[961]: info: Starting Streaming Daemon Jan 19 22:42:04 volumio sudo[1253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 19 22:42:04 volumio sudo[1253]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 22:42:04 volumio volumio[961]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 19 22:42:04 volumio sudo[1253]: pam_unix(sudo:session): session closed for user root Jan 19 22:42:04 volumio volumio[961]: error: Cannot start Volumio Streaming Daemon Jan 19 22:42:04 volumio volumio[961]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 19 22:42:04 volumio volumio[961]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 19 22:42:04 volumio volumio[961]: error: MyVolumio Custom Token format not valid, refreshing it Jan 19 22:42:05 volumio volumio[961]: info: MyVolumio login type: Token Jan 19 22:42:05 volumio volumio[961]: STREAMING PROXY: Starting server on port 3245 Jan 19 22:42:05 volumio volumio[961]: Node JS runtime: 14 Jan 19 22:42:05 volumio sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=miro,password=mkocev123,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.0.223/Music /mnt/NAS/MYBOOKLIVE Jan 19 22:42:05 volumio sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 22:42:05 volumio sudo[1261]: pam_unix(sudo:session): session closed for user root Jan 19 22:42:05 volumio kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Jan 19 22:42:05 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Jan 19 22:42:05 volumio kernel: CIFS: Attempting to mount //192.168.0.223/Music Jan 19 22:42:05 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 19 22:42:05 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Jan 19 22:42:05 volumio volumio[961]: info: Cannot mount NAS MYBOOKLIVE at system boot, trial number 4 ,retrying in 5 seconds Jan 19 22:42:05 volumio volumio[961]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 19 22:42:06 volumio volumio[961]: info: MyVolumio token set successfully Jan 19 22:42:06 volumio volumio[961]: info: MYVOLUMIO: Adding device Jan 19 22:42:06 volumio volumio[961]: info: MYVOLUMIO: Evaluating Server Jan 19 22:42:06 volumio volumio[961]: info: MyVolumio status changed Jan 19 22:42:06 volumio volumio[961]: info: Streaming services startup Jan 19 22:42:06 volumio volumio[961]: info: Starting Streaming Daemon Jan 19 22:42:06 volumio volumio[961]: info: Removing browser output: myVolumio user plan is not superstar Jan 19 22:42:06 volumio volumio[961]: info: Removing audio output: Jan 19 22:42:06 volumio volumio[961]: info: Stoppping Tunnel 1 Jan 19 22:42:06 volumio sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 19 22:42:06 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 22:42:06 volumio sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 19 22:42:06 volumio sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 22:42:06 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root Jan 19 22:42:06 volumio volumio[961]: error: Cannot start Volumio Streaming Daemon Jan 19 22:42:06 volumio volumio[961]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 19 22:42:06 volumio volumio[961]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 19 22:42:06 volumio sudo[1285]: pam_unix(sudo:session): session closed for user root Jan 19 22:42:06 volumio volumio[961]: info: Remote SSH Stopped Jan 19 22:42:06 volumio volumio[961]: info: Setting Geolocation for MyVolumio to eu6 Jan 19 22:42:06 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 22:42:06 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 22:42:06 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 22:42:07 volumio volumio[961]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 19 22:42:07 volumio volumio[961]: info: Updating MyVolumio device info Jan 19 22:42:07 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 22:42:08 volumio volumio[961]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 19 22:42:10 volumio sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=miro,password=mkocev123,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.0.223/Music /mnt/NAS/MYBOOKLIVE Jan 19 22:42:10 volumio sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 22:42:10 volumio kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Jan 19 22:42:10 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Jan 19 22:42:10 volumio kernel: CIFS: Attempting to mount //192.168.0.223/Music Jan 19 22:42:10 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 19 22:42:10 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Jan 19 22:42:10 volumio sudo[1306]: pam_unix(sudo:session): session closed for user root Jan 19 22:42:10 volumio volumio[961]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jan 19 22:42:10 volumio volumio[1225]: ..............................................................................................................++++ Jan 19 22:42:11 volumio volumio[961]: info: AutoStart - Plugin is starting Jan 19 22:42:11 volumio volumio[961]: info: CoreCommandRouter::volumioGetQueue Jan 19 22:42:11 volumio volumio[961]: info: CoreStateMachine::getQueue Jan 19 22:42:11 volumio volumio[961]: info: CorePlayQueue::getQueue Jan 19 22:42:11 volumio volumio[961]: info: AutoStart - start playing Jan 19 22:42:11 volumio volumio[961]: info: AutoStart - start playing with no specific position Jan 19 22:42:11 volumio volumio[961]: info: CoreCommandRouter::volumioPlay Jan 19 22:42:11 volumio volumio[961]: info: CoreStateMachine::play index 0 Jan 19 22:42:11 volumio volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 22:42:11 volumio volumio[961]: info: CoreStateMachine::stop Jan 19 22:42:11 volumio volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 22:42:11 volumio volumio[961]: info: CoreStateMachine::play index undefined Jan 19 22:42:11 volumio volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 22:42:11 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:11 volumio volumio[961]: info: CoreStateMachine::startPlaybackTimer Jan 19 22:42:11 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:11 volumio volumio[961]: info: [1737319331206] ControllerWebradio::clearAddPlayTrack Jan 19 22:42:11 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 22:42:11 volumio volumio[961]: info: sendMpdCommand stop took 3 milliseconds Jan 19 22:42:11 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand clear Jan 19 22:42:11 volumio volumio[961]: info: Jan 19 22:42:11 volumio volumio[961]: ---------------------------- MPD announces system playlist update Jan 19 22:42:11 volumio volumio[961]: info: Ignoring MPD Status Update Jan 19 22:42:11 volumio volumio[961]: info: sendMpdCommand clear took 5 milliseconds Jan 19 22:42:11 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radiofrance.fr/fip/fip_hifi.m3u8?id=radiofrance+85" Jan 19 22:42:11 volumio volumio[961]: info: Jan 19 22:42:11 volumio volumio[961]: ---------------------------- MPD announces system playlist update Jan 19 22:42:11 volumio volumio[961]: info: Ignoring MPD Status Update Jan 19 22:42:11 volumio volumio[961]: info: Jan 19 22:42:11 volumio volumio[961]: ---------------------------- MPD announces system playlist update Jan 19 22:42:11 volumio volumio[961]: info: Ignoring MPD Status Update Jan 19 22:42:11 volumio volumio[961]: error: updateQueue error: null Jan 19 22:42:11 volumio volumio[961]: info: Jan 19 22:42:11 volumio volumio[961]: ---------------------------- MPD announces system playlist update Jan 19 22:42:11 volumio volumio[961]: info: Ignoring MPD Status Update Jan 19 22:42:11 volumio volumio[961]: info: ------------------------------ 13ms Jan 19 22:42:11 volumio volumio[961]: info: sendMpdCommand add "https://stream.radiofrance.fr/fip/fip_hifi.m3u8?id=radiofrance+85" took 9 milliseconds Jan 19 22:42:11 volumio volumio[961]: info: ------------------------------ 8ms Jan 19 22:42:11 volumio volumio[961]: info: ------------------------------ 6ms Jan 19 22:42:11 volumio volumio[961]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 19 22:42:11 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand play Jan 19 22:42:11 volumio volumio[961]: info: Jan 19 22:42:11 volumio volumio[961]: ---------------------------- MPD announces system playlist update Jan 19 22:42:11 volumio volumio[961]: info: Ignoring MPD Status Update Jan 19 22:42:11 volumio volumio[961]: info: Jan 19 22:42:11 volumio volumio[961]: ---------------------------- MPD announces system playlist update Jan 19 22:42:11 volumio volumio[961]: info: Ignoring MPD Status Update Jan 19 22:42:11 volumio volumio[961]: info: ------------------------------ 14ms Jan 19 22:42:11 volumio volumio[961]: info: sendMpdCommand play took 10 milliseconds Jan 19 22:42:11 volumio volumio[961]: info: ------------------------------ 9ms Jan 19 22:42:11 volumio volumio[961]: info: ------------------------------ 6ms Jan 19 22:42:11 volumio volumio[961]: info: MYVOLUMIO: Adding device Jan 19 22:42:11 volumio volumio[961]: info: MYVOLUMIO: Evaluating Server Jan 19 22:42:11 volumio volumio[961]: info: Setting Geolocation for MyVolumio to eu6 Jan 19 22:42:11 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 22:42:11 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 22:42:12 volumio volumio[961]: info: Updating MyVolumio device info Jan 19 22:42:12 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 22:42:13 volumio systemd[1]: systemd-timedated.service: Succeeded. Jan 19 22:42:13 volumio volumio[961]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 19 22:42:13 volumio volumio[961]: info: Jan 19 22:42:13 volumio volumio[961]: ---------------------------- MPD announces state update: player Jan 19 22:42:13 volumio volumio[961]: info: ControllerMpd::getState Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Jan 19 22:42:13 volumio volumio[961]: info: Jan 19 22:42:13 volumio volumio[961]: ---------------------------- MPD announces state update: player Jan 19 22:42:13 volumio volumio[961]: info: ControllerMpd::getState Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Jan 19 22:42:13 volumio volumio[961]: info: Jan 19 22:42:13 volumio volumio[961]: ---------------------------- MPD announces state update: player Jan 19 22:42:13 volumio volumio[961]: info: ControllerMpd::getState Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Jan 19 22:42:13 volumio volumio[961]: info: sendMpdCommand status took 54 milliseconds Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::parseState Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 22:42:13 volumio volumio[961]: info: Jan 19 22:42:13 volumio volumio[961]: ---------------------------- MPD announces state update: player Jan 19 22:42:13 volumio volumio[961]: info: ControllerMpd::getState Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Jan 19 22:42:13 volumio volumio[961]: info: sendMpdCommand status took 49 milliseconds Jan 19 22:42:13 volumio volumio[961]: info: sendMpdCommand status took 45 milliseconds Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::parseState Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::parseState Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 22:42:13 volumio volumio[961]: info: Jan 19 22:42:13 volumio volumio[961]: ---------------------------- MPD announces state update: player Jan 19 22:42:13 volumio volumio[961]: info: ControllerMpd::getState Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Jan 19 22:42:13 volumio volumio[961]: info: Jan 19 22:42:13 volumio volumio[961]: ---------------------------- MPD announces state update: player Jan 19 22:42:13 volumio volumio[961]: info: ControllerMpd::getState Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Jan 19 22:42:13 volumio volumio[961]: info: sendMpdCommand playlistinfo took 24 milliseconds Jan 19 22:42:13 volumio volumio[961]: info: sendMpdCommand status took 21 milliseconds Jan 19 22:42:13 volumio volumio[961]: info: sendMpdCommand playlistinfo took 20 milliseconds Jan 19 22:42:13 volumio volumio[961]: info: sendMpdCommand playlistinfo took 18 milliseconds Jan 19 22:42:13 volumio volumio[961]: info: sendMpdCommand status took 14 milliseconds Jan 19 22:42:13 volumio volumio[961]: info: sendMpdCommand status took 28 milliseconds Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::parseState Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::parseState Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::parseState Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 22:42:13 volumio volumio[961]: info: ControllerMpd::pushState Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::servicePushState Jan 19 22:42:13 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:13 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fip_hifi.m3u8?id=radiofrance+85","artist":null,"album":null,"uri":"https://stream.radiofrance.fr/fip/fip_hifi.m3u8?id=radiofrance+85","trackType":""} Jan 19 22:42:13 volumio volumio[961]: verbose: CURRENT POSITION 0 Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus stop Jan 19 22:42:13 volumio volumio[961]: info: ControllerMpd::pushState Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::servicePushState Jan 19 22:42:13 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:13 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":377,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fip_hifi.m3u8?id=radiofrance+85","artist":null,"album":null,"uri":"https://stream.radiofrance.fr/fip/fip_hifi.m3u8?id=radiofrance+85","trackType":""} Jan 19 22:42:13 volumio volumio[961]: verbose: CURRENT POSITION 0 Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus play Jan 19 22:42:13 volumio volumio[961]: info: Received an update from plugin. extracting info from payload Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::pushState Jan 19 22:42:13 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::pushState Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Jan 19 22:42:13 volumio volumio[961]: info: ControllerMpd::pushState Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::servicePushState Jan 19 22:42:13 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:13 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":398,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fip_hifi.m3u8?id=radiofrance+85","artist":null,"album":null,"uri":"https://stream.radiofrance.fr/fip/fip_hifi.m3u8?id=radiofrance+85","trackType":""} Jan 19 22:42:13 volumio volumio[961]: verbose: CURRENT POSITION 0 Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus play Jan 19 22:42:13 volumio volumio[961]: info: Received an update from plugin. extracting info from payload Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::pushState Jan 19 22:42:13 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::pushState Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Jan 19 22:42:13 volumio volumio[961]: info: ------------------------------ 148ms Jan 19 22:42:13 volumio volumio[961]: info: ------------------------------ 154ms Jan 19 22:42:13 volumio volumio[961]: info: ------------------------------ 141ms Jan 19 22:42:13 volumio volumio[961]: info: sendMpdCommand playlistinfo took 48 milliseconds Jan 19 22:42:13 volumio volumio[961]: info: sendMpdCommand playlistinfo took 44 milliseconds Jan 19 22:42:13 volumio volumio[961]: info: sendMpdCommand playlistinfo took 44 milliseconds Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Jan 19 22:42:13 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Jan 19 22:42:13 volumio volumio[961]: info: ControllerMpd::pushState Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::servicePushState Jan 19 22:42:13 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:13 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fip_hifi.m3u8?id=radiofrance+85","artist":null,"album":null,"uri":"https://stream.radiofrance.fr/fip/fip_hifi.m3u8?id=radiofrance+85","trackType":""} Jan 19 22:42:13 volumio volumio[961]: verbose: CURRENT POSITION 0 Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus play Jan 19 22:42:13 volumio volumio[961]: info: Received an update from plugin. extracting info from payload Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::pushState Jan 19 22:42:13 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::pushState Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Jan 19 22:42:13 volumio volumio[961]: info: ControllerMpd::pushState Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::servicePushState Jan 19 22:42:13 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:13 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fip_hifi.m3u8?id=radiofrance+85","artist":null,"album":null,"uri":"https://stream.radiofrance.fr/fip/fip_hifi.m3u8?id=radiofrance+85","trackType":""} Jan 19 22:42:13 volumio volumio[961]: verbose: CURRENT POSITION 0 Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus play Jan 19 22:42:13 volumio volumio[961]: info: Received an update from plugin. extracting info from payload Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::pushState Jan 19 22:42:13 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::pushState Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Jan 19 22:42:13 volumio volumio[961]: info: ControllerMpd::pushState Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::servicePushState Jan 19 22:42:13 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:13 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fip_hifi.m3u8?id=radiofrance+85","artist":null,"album":null,"uri":"https://stream.radiofrance.fr/fip/fip_hifi.m3u8?id=radiofrance+85","trackType":""} Jan 19 22:42:13 volumio volumio[961]: verbose: CURRENT POSITION 0 Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus play Jan 19 22:42:13 volumio volumio[961]: info: Received an update from plugin. extracting info from payload Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::pushState Jan 19 22:42:13 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Jan 19 22:42:13 volumio volumio[961]: info: CoreStateMachine::pushState Jan 19 22:42:13 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Jan 19 22:42:13 volumio volumio[961]: info: ------------------------------ 144ms Jan 19 22:42:13 volumio volumio[961]: info: ------------------------------ 135ms Jan 19 22:42:13 volumio volumio[961]: info: ------------------------------ 132ms Jan 19 22:42:13 volumio volumio[961]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 19 22:42:17 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 19 22:42:17 volumio volumio[961]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 19 22:42:17 volumio volumio[961]: info: CoreCommandRouter::volumioGetState Jan 19 22:42:19 volumio volumio[1225]: .............................................................................++++ Jan 19 22:42:19 volumio volumio[1225]: e is 65537 (0x010001) Jan 19 22:42:19 volumio volumio[1225]: writing RSA key Jan 19 22:42:21 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 19 22:42:21 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 22:42:21 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 19 22:42:21 volumio volumio-remote-updater[558]: No test mode Jan 19 22:42:21 volumio volumio-remote-updater[558]: No alpha test mode Jan 19 22:42:21 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 19 22:42:21 volumio volumio[961]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 19 22:42:21 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 19 22:42:22 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 22:42:22 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 22:42:22 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 19 22:42:22 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 19 22:42:22 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 19 22:42:22 volumio volumio[961]: info: CoreCommandRouter::volumioGetBrowseSources Jan 19 22:42:22 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 19 22:42:24 volumio sudo[1440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 19 22:42:24 volumio sudo[1440]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 22:42:24 volumio volumio[961]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 19 22:42:24 volumio volumio[961]: info: Completed starting MyVolumio Plugin Jan 19 22:42:24 volumio sudo[1440]: pam_unix(sudo:session): session closed for user root Jan 19 22:42:24 volumio volumio[961]: [Metrics] CommandRouter: 40s 261.16ms Jan 19 22:42:24 volumio volumio[961]: info: CoreCommandRouter::volumiosetStartupVolume Jan 19 22:42:24 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 22:42:24 volumio volumio[961]: info: VolumeController:: Setting startup Volume 100 Jan 19 22:42:24 volumio sudo[1442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 19 22:42:24 volumio volumio[961]: info: VolumeController::SetAlsaVolume100 Jan 19 22:42:24 volumio sudo[1442]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 22:42:24 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 22:42:24 volumio sudo[1442]: pam_unix(sudo:session): session closed for user root Jan 19 22:42:24 volumio volumio[961]: info: CoreCommandRouter::Close All Modals sent Jan 19 22:42:24 volumio volumio[961]: info: CoreCommandRouter::Close All Modals sent Jan 19 22:42:24 volumio volumio[961]: info: CoreStateMachine::pushState Jan 19 22:42:24 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 22:42:24 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Jan 19 22:42:24 volumio volumio[961]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jan 19 22:42:24 volumio volumio[961]: aplay: main:828: audio open error: Device or resource busy Jan 19 22:42:24 volumio sudo[1450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 19 22:42:24 volumio sudo[1450]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 22:42:24 volumio sudo[1450]: pam_unix(sudo:session): session closed for user root Jan 19 22:42:24 volumio sudo[1452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 19 22:42:24 volumio sudo[1452]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 22:42:24 volumio sudo[1452]: pam_unix(sudo:session): session closed for user root Jan 19 22:42:24 volumio volumio[961]: verbose: New Socket.io Connection to 192.168.0.157 from 192.168.0.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 4 Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::volumioGetVisibleSources Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::volumioGetState Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 19 22:42:25 volumio volumio[961]: info: Received Get System Info Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 22:42:25 volumio volumio[961]: info: Discovery: Getting this device information Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::volumioGetState Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::volumioGetState Jan 19 22:42:25 volumio volumio[961]: info: Listing playlists Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 19 22:42:25 volumio volumio[961]: info: CoreCommandRouter::volumioGetState Jan 19 22:42:26 volumio volumio[961]: info: CoreCommandRouter::volumioStop Jan 19 22:42:26 volumio volumio[961]: info: CoreStateMachine::stop Jan 19 22:42:26 volumio volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 22:42:26 volumio volumio[961]: info: CoreStateMachine::stPlaybackTimer Jan 19 22:42:26 volumio volumio[961]: info: CoreStateMachine::updateTrackBlock Jan 19 22:42:26 volumio volumio[961]: info: CorePlayQueue::getTrackBlock Jan 19 22:42:26 volumio volumio[961]: info: CoreStateMachine::pushState Jan 19 22:42:26 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:26 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 22:42:26 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Jan 19 22:42:26 volumio volumio[961]: info: CoreStateMachine::serviceStop Jan 19 22:42:26 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:26 volumio volumio[961]: info: CoreCommandRouter::serviceStop Jan 19 22:42:26 volumio volumio[961]: info: [1737319346255] ControllerWebradio::stop Jan 19 22:42:26 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 22:42:26 volumio volumio[961]: info: sendMpdCommand stop took 31 milliseconds Jan 19 22:42:26 volumio volumio[961]: info: Jan 19 22:42:26 volumio volumio[961]: ---------------------------- MPD announces state update: player Jan 19 22:42:26 volumio volumio[961]: info: ControllerMpd::getState Jan 19 22:42:26 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Jan 19 22:42:26 volumio volumio[961]: info: Jan 19 22:42:26 volumio volumio[961]: ---------------------------- MPD announces state update: player Jan 19 22:42:26 volumio volumio[961]: info: ControllerMpd::getState Jan 19 22:42:26 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Jan 19 22:42:26 volumio volumio[961]: info: Jan 19 22:42:26 volumio volumio[961]: ---------------------------- MPD announces state update: player Jan 19 22:42:26 volumio volumio[961]: info: ControllerMpd::getState Jan 19 22:42:26 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Jan 19 22:42:26 volumio volumio[961]: info: sendMpdCommand status took 6 milliseconds Jan 19 22:42:26 volumio volumio[961]: info: sendMpdCommand status took 6 milliseconds Jan 19 22:42:26 volumio volumio[961]: info: sendMpdCommand status took 4 milliseconds Jan 19 22:42:26 volumio volumio[961]: verbose: ControllerMpd::parseState Jan 19 22:42:26 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 22:42:26 volumio volumio[961]: verbose: ControllerMpd::parseState Jan 19 22:42:26 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 22:42:26 volumio volumio[961]: verbose: ControllerMpd::parseState Jan 19 22:42:26 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 22:42:26 volumio volumio[961]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 19 22:42:26 volumio volumio[961]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 19 22:42:26 volumio volumio[961]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 19 22:42:26 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Jan 19 22:42:26 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Jan 19 22:42:26 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Jan 19 22:42:26 volumio volumio[961]: info: ControllerMpd::pushState Jan 19 22:42:26 volumio volumio[961]: info: CoreCommandRouter::servicePushState Jan 19 22:42:26 volumio volumio[961]: info: CoreStateMachine::pushState Jan 19 22:42:26 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:26 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Jan 19 22:42:26 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:26 volumio volumio[961]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 19 22:42:26 volumio volumio[961]: info: ControllerMpd::pushState Jan 19 22:42:26 volumio volumio[961]: info: CoreCommandRouter::servicePushState Jan 19 22:42:26 volumio volumio[961]: info: CoreStateMachine::pushState Jan 19 22:42:26 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:26 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Jan 19 22:42:26 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:26 volumio volumio[961]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 19 22:42:26 volumio volumio[961]: info: ControllerMpd::pushState Jan 19 22:42:26 volumio volumio[961]: info: CoreCommandRouter::servicePushState Jan 19 22:42:26 volumio volumio[961]: info: CoreStateMachine::pushState Jan 19 22:42:26 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:26 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Jan 19 22:42:26 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:26 volumio volumio[961]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 19 22:42:26 volumio volumio[961]: info: ------------------------------ 46ms Jan 19 22:42:26 volumio volumio[961]: info: ------------------------------ 44ms Jan 19 22:42:26 volumio volumio[961]: info: ------------------------------ 43ms Jan 19 22:42:26 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 19 22:42:26 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 19 22:42:26 volumio volumio[961]: info: Received Get System Info Jan 19 22:42:26 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 22:42:26 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 22:42:26 volumio volumio[961]: info: Discovery: Getting this device information Jan 19 22:42:26 volumio volumio[961]: info: CoreCommandRouter::volumioGetState Jan 19 22:42:26 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:26 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 22:42:27 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 19 22:42:27 volumio volumio[961]: info: Received Get System Info Jan 19 22:42:27 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 22:42:27 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 22:42:27 volumio volumio[961]: info: Discovery: Getting this device information Jan 19 22:42:27 volumio volumio[961]: info: CoreCommandRouter::volumioGetState Jan 19 22:42:27 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:27 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 22:42:29 volumio volumio[961]: info: CoreCommandRouter::volumioGetQueue Jan 19 22:42:29 volumio volumio[961]: info: CoreStateMachine::getQueue Jan 19 22:42:29 volumio volumio[961]: info: CorePlayQueue::getQueue Jan 19 22:42:31 volumio volumio[961]: info: BOOT COMPLETED Jan 19 22:42:31 volumio ntpd[765]: Soliciting pool server 213.91.190.205 Jan 19 22:42:31 volumio ntpd[765]: Soliciting pool server 195.238.84.23 Jan 19 22:42:32 volumio ntpd[765]: Soliciting pool server 91.210.88.37 Jan 19 22:42:32 volumio ntpd[765]: Soliciting pool server 151.251.152.123 Jan 19 22:42:32 volumio ntpd[765]: Soliciting pool server 195.85.215.215 Jan 19 22:42:33 volumio ntpd[765]: Soliciting pool server 162.159.200.123 Jan 19 22:42:33 volumio ntpd[765]: Soliciting pool server 2.56.14.18 Jan 19 22:42:33 volumio ntpd[765]: Soliciting pool server 84.43.191.2 Jan 19 22:42:33 volumio ntpd[765]: Soliciting pool server 213.91.190.163 Jan 19 22:42:34 volumio ntpd[765]: Soliciting pool server 162.159.200.1 Jan 19 22:42:34 volumio ntpd[765]: Soliciting pool server 151.237.71.222 Jan 19 22:42:34 volumio ntpd[765]: Soliciting pool server 84.43.191.4 Jan 19 22:42:34 volumio ntpd[765]: Soliciting pool server 78.130.168.61 Jan 19 22:42:34 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 19 22:42:35 volumio ntpd[765]: Soliciting pool server 185.117.82.71 Jan 19 22:42:35 volumio ntpd[765]: Soliciting pool server 185.117.82.66 Jan 19 22:42:36 volumio ntpd[765]: Soliciting pool server 84.54.128.102 Jan 19 22:42:37 volumio ntpd[765]: Soliciting pool server 2a07:5740:600::a20e:57a4 Jan 19 22:42:37 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 22:42:37 volumio volumio[961]: info: Preload queue cleared Jan 19 22:42:40 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 22:42:40 volumio volumio[961]: info: Preload queue cleared Jan 19 22:42:43 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 19 22:42:43 volumio volumio[961]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 19 22:42:43 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 19 22:42:43 volumio volumio[961]: info: Received Get System Version Jan 19 22:42:43 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 19 22:42:43 volumio volumio[961]: info: Received Get System Info Jan 19 22:42:43 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 22:42:43 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 22:42:43 volumio volumio[961]: info: Discovery: Getting this device information Jan 19 22:42:43 volumio volumio[961]: info: CoreCommandRouter::volumioGetState Jan 19 22:42:43 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Jan 19 22:42:43 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 22:42:43 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 22:42:43 volumio volumio[961]: info: Preload queue cleared Jan 19 22:42:52 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 22:42:53 volumio volumio[961]: info: Preload queue cleared Jan 19 22:43:06 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 22:43:07 volumio volumio[961]: info: Preload queue cleared Jan 19 22:43:09 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 22:43:09 volumio volumio[961]: info: Preload queue cleared Jan 19 22:43:12 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 22:43:12 volumio volumio[961]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 22:43:12 volumio volumio[961]: TypeError: Cannot read property 'length' of undefined Jan 19 22:43:12 volumio volumio[961]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jan 19 22:43:12 volumio volumio[961]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jan 19 22:43:12 volumio volumio[961]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jan 19 22:43:12 volumio volumio[961]: at Parser.emit (events.js:315:20) Jan 19 22:43:12 volumio volumio[961]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jan 19 22:43:12 volumio volumio[961]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jan 19 22:43:12 volumio volumio[961]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jan 19 22:43:12 volumio volumio[961]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jan 19 22:43:12 volumio volumio[961]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jan 19 22:43:12 volumio volumio[961]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jan 19 22:43:12 volumio volumio[961]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jan 19 22:43:12 volumio volumio[961]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jan 19 22:43:12 volumio volumio[961]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jan 19 22:43:12 volumio volumio[961]: at IncomingMessage.emit (events.js:327:22) Jan 19 22:43:12 volumio volumio[961]: at endReadableNT (internal/streams/readable.js:1327:12) Jan 19 22:43:12 volumio volumio[961]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 19 22:43:12 volumio volumio[961]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 22:43:13 volumio sudo[1555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 22:42 Jan 19 22:43:13 volumio sudo[1555]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"