[AtwoodWilliam]

Running Kodi 18.5 on Raspberry Pi, with LibreELEC 9.2.0.
While it sometimes plays more than one recording successfully, it now most frequently shows the following.
1) I watch a recording.
2) While the recording is playing (successfully), there is a small "wheel-within-a-wheel" rotating in the center of the screen.
3) At the end of the showing, the screen goes up a level, to what should be the list of recordings, but it displays a message about "No information", and the screen is frozen.
4) to recover from this I have to reboot the RPi.  This is most irritating.

Two logs are at:
http://users.encs.concordia.ca/kodi/kodi.log
http://users.encs.concordia.ca/kodi/kodi.old.log

I am quite prepared to post more detailed logs, if someone will tell me the level of detail to post.

P.S. I accessed https://paste.kodi.tv /  I got an essentially blank screen.  I found and clicked on a "new" button, which gave me a ">" on the left side of the screen.  I have no idea what do next to upload a log file.  Is there a place to go for instructions?  Am I missing something?

[AtwoodWilliam]

Correction:
The logs are at:
http://users.encs.concordia.ca/~bill/kodi/kodi.log
http://users.encs.concordia.ca/~bill/kodi/kodi.old.log

[AtwoodWilliam]

Since no one has responded to this post so far (except myself), I have replicated the problem with Debug Mode enabled.  The log file is here.

System startup at 14:39 today (Jan 31, 2020).
At 14:43, after descending the selection tree, I started to watch an Episode of The Andy Griffith Show". (This show consistently exhibits the error.  However, it is not episode specific; any episode will do it.)
At 14:43:06.996, an ERROR line appears; I don't know if this is significant.

While the show is displaying, there is a pair of (small) blue circles counter-rotating in the center of the screen, for the whole time of the show.
Various commercial breaks are skipped during the show.
At 15:05, there is an ERROR message about an incomplete frame.
A few seconds later, there is another error message, which says that the process is aborting.
Then, there is a pair of ERROR messages about "server error" and "invalid response", followed (700 ms later) by two additional ERROR messages.

On the screen, once the show has completed, the list of episodes should appear.  In my case, what appears is a screen showing the words "No information available", and the screen is frozen.  (I suspect that the lines about "server error" are pertinent here.)

Since the screen is frozen, I cannot even go back and upload a log file; I had to restart and upload kodi.old.log.  (See link at start of this posting.)

Any help will be much appreciated.

  Bill

[Karellen]

@AtwoodWilliam

Still having problems after all this time. The Debug Log has expired and you will need to provide another. The others are of no use as debug mode is not enabled.

Post the new logs and if no-one has responded in a couple days I will try to ping someone who might have knowledge with MythTV.

[AtwoodWilliam]

@AtwoodWilliam

Still having problems after all this time. The Debug Log has expired and you will need to provide another. The others are of no use as debug mode is not enabled.

Post the new logs and if no-one has responded in a couple days I will try to ping someone who might have knowledge with MythTV.

I have uploaded a fresh log here.
Near 14:09:04, I started watching "The Andy Griffith Show".  After the larger "blue wheel within a blue wheel" symbol appeared (normally appears when buffers are filing), the show started with a smaller "blue wheel within a blue wheel" in the center of the screen.  (This is _always_ an indication that Kodi will hang when the show is finished, and it persists throughout the showing.)
At 14:09:05, there are some warnings about buffer timeout.
At 14:29:33 there is an error message about "incomplete frame".
AT 14:29:36, there is an error massage about "MSGQ_IS_ERROR", followed by "server error" and "invalid response".
At 14:29:37, there are errors about GetDirectory and window focus.

On the screen, after the episode ends, one would normally expect the screen to return to the list of episodes, so that another episode could be picked.  Instead, there is a message that "no information is available".

At this point, pressing any button on the remote results in a "swish" sound, but no action.  The only way to recover is to unplug the RPi, as the remote is not effective.

Some background.
Kodi is running on a Raspberry Pi (Kodi 189; LibreELEC 9.2.6).  MythTV is running in version 28, on a Dell laptop, with ubuntu 16:04 LTS.  The RPi and the laptop are both connected to a switch; they both have static addresses.

[Karellen]

I can see some MythTV errors, but I have no clue so I won't be much help

Maybe @emveepee might know

[emveepee]

I haven't used MythTV for 15 years but my guess is the spinning circle is because of failures with addon communicating to the backend and leading to other problems.

2020-11-18 14:29:36.691 T:1483977600 DEBUG: AddOnLog: MythTV PVR Client: SetRecordingPlayCount
2020-11-18 14:29:36.742 T:1483977600 ERROR: AddOnLog: MythTV PVR Client: (CPPMyth)WSResponse: server error (500)
2020-11-18 14:29:36.742 T:1483977600 ERROR: AddOnLog: MythTV PVR Client: (CPPMyth)SetSavedBookmark6_2: invalid response
2020-11-18 14:29:36.744 T:1483977600 NOTICE: AddOnLog: MythTV PVR Client: SetRecordingLastPlayedPosition: Setting Bookmark failed

Martin

[janbar]

Running Kodi 18.5 on Raspberry Pi, with LibreELEC 9.2.0.
While it sometimes plays more than one recording successfully, it now most frequently shows the following.
1) I watch a recording.
2) While the recording is playing (successfully), there is a small "wheel-within-a-wheel" rotating in the center of the screen.
3) At the end of the showing, the screen goes up a level, to what should be the list of recordings, but it displays a message about "No information", and the screen is frozen.
4) to recover from this I have to reboot the RPi.  This is most irritating.

Two logs are at:
http://users.encs.concordia.ca/kodi/kodi.log
http://users.encs.concordia.ca/kodi/kodi.old.log

I am quite prepared to post more detailed logs, if someone will tell me the level of detail to post.

P.S. I accessed https://paste.kodi.tv /  I got an essentially blank screen.  I found and clicked on a "new" button, which gave me a ">" on the left side of the screen.  I have no idea what do next to upload a log file.  Is there a place to go for instructions?  Am I missing something?

Hi, that is a deadlock with the kodi GUI. After showing a recording the addon try to display a box for choosing to delete the showed recoding (The default choice is "No"). Probably Kodi locks the GUI to fork an other box to display information about a fail of something. I take care about this type of situation, but seems with Kodi 18.5 a GUI deadlock could occur again. I am trying to reproduce the case ...

Edit: You can disable the box forked by the addon at the end of showing, in the addon settings. It should resolve your case until I fix it.

In your log you use MythTV v0.28.2-6-g9031599 and the call "SetSavedBookmark6_2" failed. This is probably the root cause of the GUI deadlock. To be checked.

[AtwoodWilliam]

Thank you!

Since I can reproduce the problem with greater than 50% probability, I will be happy to provide any debug logs that you may need, at any supported level of detail.

I also took at photo of the screen after the failure, which I can post on my website.

[AtwoodWilliam]

The toggle "Prompt to delete the watched recording" is already set to "no" in my preferences.  So the addon is not trying to do this action.

Would it be worthwhile to have me scan the log and try to relate when certain actions happen?

Is it useful to know that the interface always hangs when the counter-rotating blue circles appear during the playback of the recording, and never hangs when they do not?

Note that the human interface is completely hung at the end of a show.  Once I run off the end of the recording, the remote has no effect on the screen, and the only escape is to power-down the RPi.  However, the remote is still effective _during_ a showing.

[janbar]

The toggle "Prompt to delete the watched recording" is already set to "no" in my preferences.  So the addon is not trying to do this action.

Would it be worthwhile to have me scan the log and try to relate when certain actions happen?

Is it useful to know that the interface always hangs when the counter-rotating blue circles appear during the playback of the recording, and never hangs when they do not?

Note that the human interface is completely hung at the end of a show.  Once I run off the end of the recording, the remote has no effect on the screen, and the only escape is to power-down the RPi.  However, the remote is still effective _during_ a showing.

So the hang is on the kodi side. I will check it by simulating an error returned by the addon on the counter update: that is your case in the previous log; i will let you know.

[AtwoodWilliam]

The toggle "Prompt to delete the watched recording" is already set to "no" in my preferences.  So the addon is not trying to do this action.

Would it be worthwhile to have me scan the log and try to relate when certain actions happen?

Is it useful to know that the interface always hangs when the counter-rotating blue circles appear during the playback of the recording, and never hangs when they do not?

Note that the human interface is completely hung at the end of a show.  Once I run off the end of the recording, the remote has no effect on the screen, and the only escape is to power-down the RPi.  However, the remote is still effective _during_ a showing.

So the hang is on the kodi side. I will check it by simulating an error returned by the addon on the counter update: that is your case in the previous log; i will let you know.

Janbar,
 
I have posted a new Kodi log, which shows three successful episodes of a show, followed by one that fails.  The log is here.
 My hope is that this might give you some additional insight.
 
The following lines give a summary of what happens.
 
Begin watching first episode of Andy Griffith (s03e04)
2020-11-27 19:13:58.717 T:1937534992  NOTICE: VideoPlayer::OpenFile: pvr://recordings/tv/active/Default/The Andy Griffith Show/The%20Andy%20Griffith%20Show s03e04%20Andy%20and%20Opie%20--%20Bachelors, TV%20(Me%20TV), 20180217_013000, 1053_1518831000_273.pvr
 
Finish watching first episode
2020-11-27 19:36:28.117 T:1516745600   DEBUG: CInputStreamPVRRecording - ClosePVRStream - closed recording stream pvr://recordings/tv/active/Default/The Andy Griffith Show/The%20Andy%20Griffith%20Show s03e04%20Andy%20and%20Opie%20--%20Bachelors, TV%20(Me%20TV), 20180217_013000, 1053_1518831000_273.pvr
 
Start watching second episode (s03e03)
2020-11-27 19:37:27.049 T:1937534992  NOTICE: VideoPlayer::OpenFile: pvr://recordings/tv/active/Default/The Andy Griffith Show/The%20Andy%20Griffith%20Show s03e03%20Andy%20and%20the%20New%20Mayor, TV%20(Me%20TV), 20180217_010000, 1053_1518829200_272.pvr
 
Finish watching second episode (yes, it's the next day)
2020-11-28 15:09:12.037 T:1525138304   DEBUG: CInputStreamPVRRecording - ClosePVRStream - closed recording stream pvr://recordings/tv/active/Default/The Andy Griffith Show/The%20Andy%20Griffith%20Show s03e03%20Andy%20and%20the%20New%20Mayor, TV%20(Me%20TV), 20180217_010000, 1053_1518829200_272.pvr
 
Start watching third episode (s03e03 again)
2020-11-28 18:57:14.889 T:1937534992  NOTICE: VideoPlayer::OpenFile: pvr://recordings/tv/active/Default/The Andy Griffith Show/The%20Andy%20Griffith%20Show s03e03%20Andy%20and%20the%20New%20Mayor, TV%20(Me%20TV), 20180217_010000, 1053_1518829200_272.pvr
 
Finish watching third episode
2020-11-28 19:18:11.783 T:1748972416   DEBUG: CInputStreamPVRRecording - ClosePVRStream - closed recording stream pvr://recordings/tv/active/Default/The Andy Griffith Show/The%20Andy%20Griffith%20Show s03e03%20Andy%20and%20the%20New%20Mayor, TV%20(Me%20TV), 20180217_010000, 1053_1518829200_272.pvr
 
All three of the above episodes ran properly to completion.  There were no counter-rotating blue circles on the screen during the showing.
 
Start watching the fourth episode (s02e31)
2020-11-28 19:21:31.515 T:1937534992  NOTICE: VideoPlayer::OpenFile: pvr://recordings/tv/active/Default/The Andy Griffith Show/The%20Andy%20Griffith%20Show s02e31%20Deputy%20Otis, TV%20(Me%20TV), 20180215_013000, 1053_1518658200_270.pvr
 
This episode had the counter-rotating blue circles displayed, from the beginning of the playback.
 
At about 19:32, I hit pause, as I had another task to do.  This resulted in about 9100 lines of lines similar to this one:
2020-11-28 20:18:59.857 T:1937534992   DEBUG: CMMALRenderer::RenderUpdate - vsync 5478328 (+1)
 
At about 20:19, I hit "resume".
 
At about 20:28:15, an error occurs (incomplete frame)
2020-11-28 20:28:15.641 T:1455416192   ERROR: ffmpeg[56BFE380]: [ac3] incomplete frame
 
At about 20:28:22, a second error occurs (Msgq error)
2020-11-28 20:28:22.159 T:1463808896   ERROR: OMXPlayerVideo: Got MSGQ_IS_ERROR(-1) Aborting
 
End watching the fourth episode
2020-11-28 20:28:22.370 T:1486996352   DEBUG: CInputStreamPVRRecording - ClosePVRStream - closed recording stream pvr://recordings/tv/active/Default/The Andy Griffith Show/The%20Andy%20Griffith%20Show s02e31%20Deputy%20Otis, TV%20(Me%20TV), 20180215_013000, 1053_1518658200_270.pvr
 
Some additional errors come after this.
 
Once the episode completes, the menu should return to a list of episodes, but instead it shows a "No information available" message, and the screen is locked (i.e., the remote has no effect, except to produce the "swish" sound when a key is pressed).  I find it strange that the errors do not show up until the episode has finished, but the "hint" that things are not OK occurs right at the start of the episode.

[janbar]

The toggle "Prompt to delete the watched recording" is already set to "no" in my preferences.  So the addon is not trying to do this action.

Would it be worthwhile to have me scan the log and try to relate when certain actions happen?

Is it useful to know that the interface always hangs when the counter-rotating blue circles appear during the playback of the recording, and never hangs when they do not?

Note that the human interface is completely hung at the end of a show.  Once I run off the end of the recording, the remote has no effect on the screen, and the only escape is to power-down the RPi.  However, the remote is still effective _during_ a showing.

So the hang is on the kodi side. I will check it by simulating an error returned by the addon on the counter update: that is your case in the previous log; i will let you know.

Janbar,
 
I have posted a new Kodi log, which shows three successful episodes of a show, followed by one that fails.  The log is here.
 My hope is that this might give you some additional insight.
 
The following lines give a summary of what happens.
 
Begin watching first episode of Andy Griffith (s03e04)
2020-11-27 19:13:58.717 T:1937534992  NOTICE: VideoPlayer::OpenFile: pvr://recordings/tv/active/Default/The Andy Griffith Show/The%20Andy%20Griffith%20Show s03e04%20Andy%20and%20Opie%20--%20Bachelors, TV%20(Me%20TV), 20180217_013000, 1053_1518831000_273.pvr
 
Finish watching first episode
2020-11-27 19:36:28.117 T:1516745600   DEBUG: CInputStreamPVRRecording - ClosePVRStream - closed recording stream pvr://recordings/tv/active/Default/The Andy Griffith Show/The%20Andy%20Griffith%20Show s03e04%20Andy%20and%20Opie%20--%20Bachelors, TV%20(Me%20TV), 20180217_013000, 1053_1518831000_273.pvr
 
Start watching second episode (s03e03)
2020-11-27 19:37:27.049 T:1937534992  NOTICE: VideoPlayer::OpenFile: pvr://recordings/tv/active/Default/The Andy Griffith Show/The%20Andy%20Griffith%20Show s03e03%20Andy%20and%20the%20New%20Mayor, TV%20(Me%20TV), 20180217_010000, 1053_1518829200_272.pvr
 
Finish watching second episode (yes, it's the next day)
2020-11-28 15:09:12.037 T:1525138304   DEBUG: CInputStreamPVRRecording - ClosePVRStream - closed recording stream pvr://recordings/tv/active/Default/The Andy Griffith Show/The%20Andy%20Griffith%20Show s03e03%20Andy%20and%20the%20New%20Mayor, TV%20(Me%20TV), 20180217_010000, 1053_1518829200_272.pvr
 
Start watching third episode (s03e03 again)
2020-11-28 18:57:14.889 T:1937534992  NOTICE: VideoPlayer::OpenFile: pvr://recordings/tv/active/Default/The Andy Griffith Show/The%20Andy%20Griffith%20Show s03e03%20Andy%20and%20the%20New%20Mayor, TV%20(Me%20TV), 20180217_010000, 1053_1518829200_272.pvr
 
Finish watching third episode
2020-11-28 19:18:11.783 T:1748972416   DEBUG: CInputStreamPVRRecording - ClosePVRStream - closed recording stream pvr://recordings/tv/active/Default/The Andy Griffith Show/The%20Andy%20Griffith%20Show s03e03%20Andy%20and%20the%20New%20Mayor, TV%20(Me%20TV), 20180217_010000, 1053_1518829200_272.pvr
 
All three of the above episodes ran properly to completion.  There were no counter-rotating blue circles on the screen during the showing.
 
Start watching the fourth episode (s02e31)
2020-11-28 19:21:31.515 T:1937534992  NOTICE: VideoPlayer::OpenFile: pvr://recordings/tv/active/Default/The Andy Griffith Show/The%20Andy%20Griffith%20Show s02e31%20Deputy%20Otis, TV%20(Me%20TV), 20180215_013000, 1053_1518658200_270.pvr
 
This episode had the counter-rotating blue circles displayed, from the beginning of the playback.
 
At about 19:32, I hit pause, as I had another task to do.  This resulted in about 9100 lines of lines similar to this one:
2020-11-28 20:18:59.857 T:1937534992   DEBUG: CMMALRenderer::RenderUpdate - vsync 5478328 (+1)
 
At about 20:19, I hit "resume".
 
At about 20:28:15, an error occurs (incomplete frame)
2020-11-28 20:28:15.641 T:1455416192   ERROR: ffmpeg[56BFE380]: [ac3] incomplete frame
 
At about 20:28:22, a second error occurs (Msgq error)
2020-11-28 20:28:22.159 T:1463808896   ERROR: OMXPlayerVideo: Got MSGQ_IS_ERROR(-1) Aborting
 
End watching the fourth episode
2020-11-28 20:28:22.370 T:1486996352   DEBUG: CInputStreamPVRRecording - ClosePVRStream - closed recording stream pvr://recordings/tv/active/Default/The Andy Griffith Show/The%20Andy%20Griffith%20Show s02e31%20Deputy%20Otis, TV%20(Me%20TV), 20180215_013000, 1053_1518658200_270.pvr
 
Some additional errors come after this.
 
Once the episode completes, the menu should return to a list of episodes, but instead it shows a "No information available" message, and the screen is locked (i.e., the remote has no effect, except to produce the "swish" sound when a key is pressed).  I find it strange that the errors do not show up until the episode has finished, but the "hint" that things are not OK occurs right at the start of the episode.

Mmm, these logs are dropped by the player thread, not the addon itself. It seems the stream is broken and the player stuck.

[stuckinthehome]

Are you running OTA? I had to turn off ALL commercial detection. It just couldn't handle all of this. Add a bad frame which is almost a given OTA and it would cut out big chunks of the show thinking it was a commercial. Probably didn't help that I didn't have 5G wifi modem. I just skip them with the remote.