2020-12-24, 04:16 PM
I watched a show in bed last and had this problem happen again. This time I got logs.
The RPi on the living room tv (192.168.143.26) was running Kodi & knewc and was in NextPVR on the recordings list. I used the bedroom one (192.168.143.24), launched knewc, watch a recording, and then used the NextPVR main menu option to Exit. It took me back to the Kodi menu fine. Then when I went back to the living room Pi later, I found that it was also back at the Kodi menu. As I mentioned in the other thread this seems to be happening regularly now.
Logs can be found here. The kodi debug log from the living room client is in there and shows (I think) that it got an "exit" command from the server at 21:20:02.058:
The NextPVR server log shows:
I guess I don't have debug logging turned on for the bedroom Pi. So it's info is very limited and really only showing the player commands:
The RPi on the living room tv (192.168.143.26) was running Kodi & knewc and was in NextPVR on the recordings list. I used the bedroom one (192.168.143.24), launched knewc, watch a recording, and then used the NextPVR main menu option to Exit. It took me back to the Kodi menu fine. Then when I went back to the living room Pi later, I found that it was also back at the Kodi menu. As I mentioned in the other thread this seems to be happening regularly now.
Logs can be found here. The kodi debug log from the living room client is in there and shows (I think) that it got an "exit" command from the server at 21:20:02.058:
Code:
2020-12-23 21:20:01.907 T:2521822064 DEBUG: http://192.168.143.10:8866/activity?format=json&updates=1&client=sdl-KNEW50xb827ebd2d752&sid=50aed9c89f72443889545bcf40c2de1a
2020-12-23 21:20:01.918 T:2521822064 DEBUG: {u'needsRendering': True}
2020-12-23 21:20:01.918 T:2521822064 DEBUG: http://192.168.143.10:8866/control?format=json&client=sdl-KNEW50xb827ebd2d752&sid=50aed9c89f72443889545bcf40c2de1a
2020-12-23 21:20:02.049 T:2521822064 DEBUG: http://192.168.143.10:8866/activity?format=json&client=sdl-KNEW50xb827ebd2d752&sid=50aed9c89f72443889545bcf40c2de1a
2020-12-23 21:20:02.058 T:2521822064 DEBUG: {u'action': u'exit'}
2020-12-23 21:20:02.109 T:3011654992 DEBUG: ffmpeg[B3823550]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
2020-12-23 21:20:02.311 T:2521822064 DEBUG: clean cache for sid.p
2020-12-23 21:20:02.311 T:2531554160 INFO: CPythonInvoker(5, /storage/.kodi/addons/script.kodi.knewc/default.py): script successfully run
2020-12-23 21:20:02.311 T:2531554160 INFO: CPythonInvoker(5, /storage/.kodi/addons/script.kodi.knewc/default.py): waiting on thread 18446744071936406384
2020-12-23 21:20:02.321 T:3011654992 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
2020-12-23 21:20:02.321 T:3011654992 DEBUG: ------ Window Deinit (/storage/.kodi/addons/script.kodi.knewc/resources/skins/Estuary/1080i/nextpvr_emulate.xml) ------
2020-12-23 21:20:02.322 T:3011654992 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
2020-12-23 21:20:02.322 T:3011654992 DEBUG: ------ Window Init (Home.xml) ------
The NextPVR server log shows:
Code:
2020-12-23 21:19:59.963 [DEBUG][141] Got request [::ffff:192.168.143.26]: /activity
2020-12-23 21:20:00.977 [DEBUG][61] Got request [::ffff:192.168.143.26]: /activity
2020-12-23 21:20:00.977 [DEBUG][99] Got request [::ffff:192.168.143.26]: /control
2020-12-23 21:20:00.977 [DEBUG][99] PlaybackPositionHelper.GetLastPlaybackDate(D:\NPVR Recordings\FBI\Season 03\FBI.S03E03.Liars.Poker.ts)
2020-12-23 21:20:00.993 [DEBUG][99] PlaybackPositionHelper.GetLastPlaybackDate(D:\NPVR Recordings\NCIS\Season 18\NCIS.S18E03.Blood.and.Treasure.ts)
2020-12-23 21:20:00.993 [DEBUG][99] PlaybackPositionHelper.GetLastPlaybackDate(D:\NPVR Recordings\The Late Show With Stephen Colbert\Season 06\The.Late.Show.With.Stephen.Colbert.S06E49.ts)
2020-12-23 21:20:00.993 [DEBUG][99] PlaybackPositionHelper.GetLastPlaybackDate(D:\NPVR Recordings\Nurses\Season 01\Nurses.S01E01.Incoming.ts)
2020-12-23 21:20:01.009 [DEBUG][99] PlaybackPositionHelper.GetLastPlaybackDate(D:\NPVR Recordings\An American Aristocrats Guide to Great Estates\An American Aristocrats Guide to Great Estates_20201207_20002100.ts)
2020-12-23 21:20:01.009 [DEBUG][99] PlaybackPositionHelper.GetLastPlaybackDate(D:\NPVR Recordings\All Rise\Season 02\All.Rise.S02E04.Bad.Beat.ts)
2020-12-23 21:20:01.071 [DEBUG][99] DoCompositeRendering() took 93.5988ms
2020-12-23 21:20:01.118 [DEBUG][45] Got request [::ffff:192.168.143.26]: /activity
2020-12-23 21:20:01.118 [DEBUG][45] ClientGetActivity() returning:
{
"action": "exit"
}
2020-12-23 21:20:01.118 [DEBUG][45] Setting activity:
I guess I don't have debug logging turned on for the bedroom Pi. So it's info is very limited and really only showing the player commands:
Code:
2020-12-23 20:56:57.450 T:1386193792 WARNING: Repository add-on repository.unofficial.addon.pro uses plain HTTP for add-on downloads in path http://unofficial.addon.pro/addons/7.0/RPi2/arm - this is insecure and will make your Kodi installation vulnerable to attacks if enabled!
2020-12-23 20:56:57.578 T:1386193792 WARNING: Previous line repeats 1 times.
2020-12-23 20:56:57.578 T:1386193792 WARNING: Repository add-on repository.skins.pkscout uses plain HTTP for add-on downloads in path http://raw.github.com/pkscout/repository.skins.pkscout/helix/download - this is insecure and will make your Kodi installation vulnerable to attacks if enabled!
2020-12-23 20:56:57.649 T:1386193792 WARNING: Repository add-on repository.unofficial.addon.pro uses plain HTTP for add-on downloads in path http://unofficial.addon.pro/addons/7.0/RPi2/arm - this is insecure and will make your Kodi installation vulnerable to attacks if enabled!
2020-12-23 20:57:51.653 T:1936816576 NOTICE: VideoPlayer::OpenFile: http://192.168.143.10:8866/live?recording_id=51122&client=sdl-KNEW50xb827eb8baccb&sid=d0a2af0f6dbf4674960f426ab40a6a5a
2020-12-23 20:57:51.663 T:1369408384 NOTICE: Creating InputStream
2020-12-23 20:57:51.675 T:1369408384 NOTICE: Creating Demuxer
2020-12-23 20:57:52.213 T:1369408384 NOTICE: Opening stream: 0 source: 256
2020-12-23 20:57:52.582 T:1369408384 NOTICE: Creating video thread
2020-12-23 20:57:52.583 T:1369408384 NOTICE: Opening stream: 1 source: 256
2020-12-23 20:57:52.585 T:1369408384 NOTICE: Creating audio thread
2020-12-23 20:57:52.599 T:1369408384 NOTICE: CDVDDemuxFFmpeg::Read() stream change
2020-12-23 20:57:52.601 T:1369408384 NOTICE: Opening stream: 0 source: 256
2020-12-23 20:57:52.646 T:1369408384 NOTICE: Opening stream: 1 source: 256
2020-12-23 20:57:52.725 T:1369408384 NOTICE: Opening stream: 0 source: 256
2020-12-23 20:57:52.726 T:1369408384 NOTICE: Opening stream: 1 source: 256
2020-12-23 20:57:52.726 T:1369408384 NOTICE: Opening stream: 1 source: 1280
2020-12-23 20:57:52.962 T:1369408384 NOTICE: OMXClock using audio as reference
2020-12-23 20:59:21.170 T:1369408384 NOTICE: Opening stream: 0 source: 256
2020-12-23 20:59:21.171 T:1369408384 NOTICE: Opening stream: 1 source: 256
2020-12-23 20:59:21.174 T:1369408384 NOTICE: Closing stream player 3
2020-12-23 20:59:21.174 T:1369408384 NOTICE: Opening stream: 1 source: 1280
2020-12-23 20:59:23.905 T:1936816576 NOTICE: Samba is idle. Closing the remaining connections
2020-12-23 20:59:28.422 T:1452274560 ERROR: COMXAudioCodecOMX::GetData Unexpected change of size (36864->12288)
2020-12-23 21:13:31.601 T:1369408384 NOTICE: Opening stream: 0 source: 256
2020-12-23 21:13:31.602 T:1369408384 NOTICE: Opening stream: 1 source: 256
2020-12-23 21:13:31.604 T:1369408384 NOTICE: Closing stream player 3
2020-12-23 21:13:31.604 T:1369408384 NOTICE: Opening stream: 1 source: 1280
2020-12-23 21:13:36.107 T:1452274560 ERROR: COMXAudioCodecOMX::GetData Unexpected change of size (12288->36864)
2020-12-23 21:13:42.028 T:1369408384 NOTICE: Opening stream: 0 source: 256
2020-12-23 21:13:42.028 T:1369408384 NOTICE: Opening stream: 1 source: 256
2020-12-23 21:13:42.031 T:1369408384 NOTICE: Closing stream player 3
2020-12-23 21:13:42.032 T:1369408384 NOTICE: Opening stream: 1 source: 1280
2020-12-23 21:18:24.383 T:1487926144 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
2020-12-23 21:19:30.550 T:1936816576 NOTICE: CVideoPlayer::CloseFile()
2020-12-23 21:19:30.550 T:1936816576 NOTICE: VideoPlayer: waiting for threads to exit
2020-12-23 21:19:30.551 T:1369408384 NOTICE: CVideoPlayer::OnExit()
2020-12-23 21:19:30.551 T:1369408384 NOTICE: Closing stream player 1
2020-12-23 21:19:30.557 T:1452274560 NOTICE: thread end: OMXPlayerAudio::OnExit()
2020-12-23 21:19:30.658 T:1369408384 NOTICE: Closing stream player 2
2020-12-23 21:19:30.659 T:1487926144 NOTICE: thread end: video_thread
2020-12-23 21:19:30.711 T:1369408384 NOTICE: Closing stream player 3
2020-12-23 21:19:30.745 T:1936816576 NOTICE: VideoPlayer: finished waiting
2020-12-23 21:19:30.746 T:1936816576 NOTICE: CVideoPlayer::CloseFile()
2020-12-23 21:19:30.746 T:1936816576 NOTICE: VideoPlayer: waiting for threads to exit
2020-12-23 21:19:30.746 T:1936816576 NOTICE: VideoPlayer: finished waiting
2020-12-23 21:19:40.353 T:1487926144 WARNING: Repository add-on repository.unofficial.addon.pro uses plain HTTP for add-on downloads in path http://unofficial.addon.pro/addons/7.0/RPi2/arm - this is insecure and will make your Kodi installation vulnerable to attacks if enabled!
2020-12-23 21:19:40.492 T:1487926144 WARNING: Previous line repeats 1 times.
2020-12-23 21:19:40.492 T:1487926144 WARNING: Repository add-on repository.skins.pkscout uses plain HTTP for add-on downloads in path http://raw.github.com/pkscout/repository.skins.pkscout/helix/download - this is insecure and will make your Kodi installation vulnerable to attacks if enabled!
2020-12-23 21:19:40.561 T:1487926144 WARNING: Repository add-on repository.unofficial.addon.pro uses plain HTTP for add-on downloads in path http://unofficial.addon.pro/addons/7.0/RPi2/arm - this is insecure and will make your Kodi installation vulnerable to attacks if enabled!
2020-12-23 21:21:15.820 T:1936816576 NOTICE: Samba is idle. Closing the remaining connections
2020-12-23 21:57:09.456 T:1413469056 WARNING: Repository add-on repository.unofficial.addon.pro uses plain HTTP for add-on downloads in path http://unofficial.addon.pro/addons/7.0/RPi2/arm - this is insecure and will make your Kodi installation vulnerable to attacks if enabled!