[janbar]

Janbar,
i couldn't get pastebin to take the log as it was loo large. Its quite alot of info for 5 mins of logging, but I know you need it to troubleshoot my issue. I used the email feature in the forum to send an email with the log and it says it sent successfully. Let me know if you get it or not. Thanks!

Here are some interesting things though...

Code:

19:54:45 T:2644073280   DEBUG: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)cmyth_livetv_chain_switch: switch file: current=0 , dir=0
19:55:01 T:2644073280   DEBUG: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)cmyth_livetv_chain_switch: switch file: current=0 , dir=1
19:55:03 T:2644073280   DEBUG: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)cmyth_livetv_chain_switch: switch file: current=1 , dir=0
19:55:03 T:2644073280   DEBUG: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)cmyth_livetv_chain_switch: switch file: current=1 , dir=0
20:00:06 T:2644073280   DEBUG: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)cmyth_livetv_chain_switch: switch file: current=1 , dir=1
20:00:07 T:2644073280   DEBUG: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)cmyth_livetv_chain_switch: switch file: current=2 , dir=1

Started at 19:54:45. 15s channel switch delay. 19:55:01 channel is changed. Next show is at 20:00:00.

This repeats for a while...

Code:

20:00:07 T:2644073280   ERROR: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)cmyth_livetv_chain_switch: no more file
20:00:07 T:2644073280   ERROR: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)cmyth_livetv_chain_switch: no more file
20:00:07 T:2644073280   ERROR: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)cmyth_livetv_chain_switch: no more file

"wait until recorder is ready for watching" is never posted in the log so it is like the delay loop from below is never triggered. I changed i=20.

cmyth_livetv_chain_switch

Code:

for ( i = 0; i < 20; i++ ) {
                                pthread_mutex_unlock(&mutex);
                                usleep(500000);
                                pthread_mutex_lock(&mutex);
                                if ( rec->rec_livetv_chain->livetv_watch == 1 )
                                        break;
                        }

I think what is happening is it is setting the watch flag right away but there is a period of a few seconds where the hardware becomes ready and then begins to record while the file remains 0 size. Basically the backend says go and the hardware takes a few seconds. The same is true for the channel changes except it is more like 7s instead of 3s because it has to IR blast before the little blue light on the recorder comes on. If it were to wait until the file had a non 0 size AND the watch flag was ready then it may solve both the channel change delay and this issue in one swoop. You probably wouldn't need the watch flag at all really if you just checked the file size. I was playing around with the LiveTVDuration property and if it were 0 i wouldn't let it play or if it stopped increasing and then began increasing again then it was done with the program break. That might be a cheesy way of doing it though but it worked.

Hopefully you get my full log, but maybe the above will help too.

Ok, that is better with log. I understood what happened. The file switch for the program break is ok. Mythtv refill into the new buffer some data but quickly it has no more data to refill:

20:00:06 T:2644073280 DEBUG: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)cmyth_livetv_chain_switch: file switch to 2
20:00:06 T:2644073280 DEBUG: AddOnLog: PVR MythTV Client (cmyth): prog_update_callback
20:00:06 T:2644073280 DEBUG: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)cmyth_file_data_conn_fd {
20:00:06 T:2644073280 DEBUG: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)__cmyth_rcv_string: string received '1'
20:00:06 T:2644073280 DEBUG: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)__cmyth_rcv_string: string received '22140'
20:00:06 T:2644073280 DEBUG: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)__cmyth_rcv_string: string received '32768'
20:00:07 T:2644073280 DEBUG: Previous line repeats 12 times.
20:00:07 T:2644073280 DEBUG: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)__cmyth_rcv_string: string received '26764'
20:00:07 T:2644073280 DEBUG: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)__cmyth_rcv_string: string received '0'
20:00:07 T:2644073280 DEBUG: Previous line repeats 1 times.

So by default if the buffer is empty, we try to switch to next file:

20:00:07 T:2644073280 DEBUG: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)cmyth_livetv_chain_switch: switch file: current=2 , dir=1
20:00:07 T:2644073280 ERROR: AddOnLog: PVR MythTV Client (cmyth): LibCMyth: (cmyth)cmyth_livetv_chain_switch: no more file
20:00:07 T:2644073280 INFO: AddOnLog: PVR MythTV Client (cmyth): ReadLiveStream: Read 0 Bytes!

But there is no next file. This is the last. In your case we must wait refill of buffer. Also the timeout is activated only when livetv_watch != 1. That means only when we are waiting for the new program info (between message LIVETV_WATCH and DONE_RECORDING). Here we have new program info already.
I must to update to enable timeout all the time.