[mythtv] Re: [mythtv-commits] mythtv commit: r7382 by danielk

Tue Oct 4 08:21:32 UTC 2005

>> This should fix the "deadlock" that people have been getting
>> when exiting and re-entering LiveTV quickly.
>

Doesn't fix it for me, I'm afraid. I have updated to 7384 on both ends, 
and I'm getting the same behaviour. Here's the frontend log:-

<TV running happily here>
2005-10-04 18:08:29.648 Read(): reqd=128000, rcvd=128000, rept=128000, 
error=0
2005-10-04 18:08:29.709 write -> 16 53      QUERY_RECORDER 
2[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2005-10-04 18:08:29.731 read  <- 16 6       128000
2005-10-04 18:08:29.731 Read(): reqd=128000, rcvd=128000, rept=128000, 
error=0
2005-10-04 18:08:29.895 write -> 16 53      QUERY_RECORDER 
2[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2005-10-04 18:08:29.924 read  <- 16 6       128000
2005-10-04 18:08:29.928 Read(): reqd=128000, rcvd=128000, rept=128000, 
error=0
<now hit escape, and exit to the menu>
2005-10-04 18:08:30.029 Attempting to change from WatchingLiveTV to None
2005-10-04 18:08:30.047 write -> 15 32      QUERY_RECORDER 2[]:[]STOP_LIVETV
2005-10-04 18:08:30.084 read  <-  4 49      
BACKEND_MESSAGE[]:[]DONE_RECORDING 2 43[]:[]empty
2005-10-04 18:08:30.135 read  <- 15 2       ok
2005-10-04 18:08:30.151 read  <-  4 51      
BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2005-10-04 18:08:30.167 write -> 16 33      QUERY_RECORDER 
2[]:[]DONE_RINGBUF
2005-10-04 18:08:30.175 read  <- 16 2       OK
2005-10-04 18:08:30.175 Changing from WatchingLiveTV to None
2005-10-04 18:08:30.179 Attempting to change from None to None
<now hit enter to go back into Live TV. Get a blank screen.>
2005-10-04 18:08:42.818 write -> 14 29      GET_NEXT_FREE_RECORDER[]:[]-1
2005-10-04 18:08:42.831 read  <- 14 28      2[]:[]192.168.1.100[]:[]6543
2005-10-04 18:08:42.832 Attempting to change from None to WatchingLiveTV
2005-10-04 18:08:42.833 write -> 15 21      MYTH_PROTO_VERSION 18
2005-10-04 18:08:42.840 read  <- 15 13      ACCEPT[]:[]18
2005-10-04 18:08:42.840 Using protocol version 18
2005-10-04 18:08:42.841 write -> 15 19      ANN Playback epia 0
2005-10-04 18:08:42.849 read  <- 15 2       OK
2005-10-04 18:08:42.850 write -> 15 44      QUERY_RECORDER 
2[]:[]SETUP_RING_BUFFER[]:[]0
2005-10-04 18:08:42.904 read  <- 15 101     
ok[]:[]rbuf://192.168.1.100:6543/var/media/video/ringbuf2.mpg[]:[...
2005-10-04 18:08:42.906 write -> 16 19      ANN Playback epia 0
2005-10-04 18:08:42.913 read  <- 16 2       OK
2005-10-04 18:08:42.914 write -> 17 21      ANN RingBuffer epia 2
2005-10-04 18:08:42.923 read  <- 17 2       OK
2005-10-04 18:08:42.923 write -> 15 33      QUERY_RECORDER 
2[]:[]SPAWN_LIVETV
2005-10-04 18:09:02.925 ReadStringList timeout (quick).
2005-10-04 18:09:02.925 RemoteEncoder::SendReceiveStringList(): No response.
2005-10-04 18:09:02.926 WriteStringList: Bad socket
2005-10-04 18:09:02.926 ReadStringList: Bad socket
2005-10-04 18:09:02.926 RemoteEncoder::SendReceiveStringList(): No response.
2005-10-04 18:09:02.928 WriteStringList: Bad socket
<snip>
2005-10-04 18:09:22.924 RemoteEncoder::SendReceiveStringList(): No response.
2005-10-04 18:09:22.926 WriteStringList: Bad socket
2005-10-04 18:09:22.926 ReadStringList: Bad socket
2005-10-04 18:09:22.926 RemoteEncoder::SendReceiveStringList(): No response.
2005-10-04 18:09:22.926 WriteStringList: Bad socket
2005-10-04 18:09:22.927 ReadStringList: Bad socket
2005-10-04 18:09:22.927 RemoteEncoder::SendReceiveStringList(): No response.
2005-10-04 18:09:22.927 StartRecorder() -- timed out waiting for 
recorder to start
2005-10-04 18:09:22.927 LiveTV not successfully started
2005-10-04 18:09:22.930 write -> 16 33      QUERY_RECORDER 
2[]:[]DONE_RINGBUF
2005-10-04 18:09:42.931 ReadStringList timeout (quick).
2005-10-04 18:09:42.931 Remote file timeout.

and here's what the backend's doing at the time of the second entry to 
live TV..

2005-10-04 18:08:42.809 Getting next free recorder after : -1
2005-10-04 18:08:42.813 Checking card 1. Best card so far -1
2005-10-04 18:08:42.813 Checking card 2. Best card so far 2
2005-10-04 18:08:42.833 MainServer::HandleAnnounce Playback
2005-10-04 18:08:42.834 adding: epia as a client (events: 0)
2005-10-04 18:08:42.841 TVRec(2): Request: Program(no) channel() input() 
flags(KillRingBuffer,)
2005-10-04 18:08:42.842 TVRec(2): 
ClearFlags(WaitingForSIParser,SIParserRunning,) -> 
RunMainLoop,AskAllowRecording,RingBufferReset,
2005-10-04 18:08:42.843 TVRec(2): ClearFlags(WaitingForSignal,) -> 
RunMainLoop,AskAllowRecording,RingBufferReset,
2005-10-04 18:08:42.843 TVRec(2): Tearing down RingBuffer
2005-10-04 18:08:42.844 DummyDTVRecorder::StopRecordingThread(void)
2005-10-04 18:08:42.845 DummyDTVRecorder::StopRecordingThread(void)
2005-10-04 18:08:42.845 TVRec(2): ClearFlags(DummyRecorderRunning,) -> 
RunMainLoop,AskAllowRecording,RingBufferReset,
2005-10-04 18:08:42.875 TVRec(2): ClearFlags(PENDINGACTIONS,) -> 
RunMainLoop,AskAllowRecording,RingBufferReset,
2005-10-04 18:08:42.875 TVRec(2): Request: Program(no) channel() input() 
flags(KillRingBuffer,)
2005-10-04 18:08:42.876 TVRec(2): 
ClearFlags(WaitingForSIParser,SIParserRunning,) -> 
RunMainLoop,AskAllowRecording,RingBufferReset,
2005-10-04 18:08:42.876 TVRec(2): ClearFlags(WaitingForSignal,) -> 
RunMainLoop,AskAllowRecording,RingBufferReset,
2005-10-04 18:08:42.877 TVRec(2): ClearFlags(PENDINGACTIONS,) -> 
RunMainLoop,AskAllowRecording,RingBufferReset,
2005-10-04 18:08:42.897 MainServer::HandleAnnounce Playback
2005-10-04 18:08:42.898 adding: epia as a client (events: 0)
2005-10-04 18:08:42.905 adding: epia as a remote ringbuffer

This is with a 'make distclean' using a non-debug build. Was the 
backtrace I provided last time useful? If so, I'll rebuild with debug 
and run another one.

Cheers,

Jon