[mythtv-users] Mythtranscode FIFO stopping mid transcode without error

Thu Dec 5 07:26:29 UTC 2013

 Two additional data points. A third run (identical
parameters) failed at about 58000 frames. A 4th run (identical
parameters) succeeded. I’ve put the last few lines of mythtranscode debug all from these
last two runs into the email below but the only difference I can see is the
timecodes (being different as at different points in the file). No error
messages that I can see at all.

Third run – failed after approx. 58000 frames

2013-12-05 02:40:04.024544 I  AFD: video timecode 1565462280 1739402517394025 17393985

2013-12-05 02:40:04.024675 E  AFD: VBI: Unknown
descriptor: 153

2013-12-05 02:40:04.024681 E  AFD: VBI: Unknown
descriptor: 44

2013-12-05 02:40:04.024845 E  AFD: VBI: Unknown
descriptor: 153

2013-12-05 02:40:04.024851 E  AFD: VBI: Unknown
descriptor: 44

2013-12-05 02:40:04.024998 E  AFD: VBI: Unknown
descriptor: 153

2013-12-05 02:40:04.025004 E  AFD: VBI: Unknown
descriptor: 44

2013-12-05 02:40:04.025126 E  AFD: VBI: Unknown
descriptor: 153

2013-12-05 02:40:04.025132 E  AFD: VBI: Unknown
descriptor: 44

2013-12-05 02:40:04.025548 I  AFD: audio timecode 1565468251 156546825117394091 17394123

2013-12-05 02:40:04.025750 I  AFD: audio timecode 1565471131 156547113117394123 17394155

frame=58611 fps= 15 q=28.0 size=  615669kB
time=00:39:02.49 bitrate=2153.1kbits/s    ^Mframe=58619 fps= 15
q=28.0 size=  615797kB time=00:39:02.81
bitrate=2153.2kbits/s    ^Mfram e=58628 fps= 15 q=28.0
size=  615878kB time=00:39:03.20 bitrate=2153.2kbits/s   
^M2013-12-05 02:40:05.595794 I  Player(0): StopPlaying - begin

2013-12-05 02:40:05.597440 I  Player(0): Decoder
thread exiting.

2013-12-05 02:40:05.597580 I  Player(0): Exited
decoder loop.

2013-12-05 02:40:05.600149 I  ~VideoOutputNull()

2013-12-05 02:40:05.611096 I  Player(0): StopPlaying
- end

2013-12-05 02:40:05.613282 N  Transcoding
/media/fs4/recordings/1090_20131204012700.mpg done

2013-12-05 02:40:05.613308 I  Waiting for threads
to exit.

2013-12-05 02:40:05.670176 I 
MythSocket(ffffffffacc0b1c0:23): write -> 23
61      MESSAGE[]:[]MASTER_UPDATE_PROG_INFO 1090
2013-12-04T01:27:00Z

2013-12-05 02:40:05.670779 I 
MythSocket(ffffffffacc0b1c0:23): read  <- 23
2       OK

2013-12-05 02:40:05.673908 I 
MythSocket(ffffffffacc150e8:25): read  <- 25
772     BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE
UPDATE[]:[]Swallows And...

2013-12-05 02:40:05.673935 I  MythEvent:
RECORDING_LIST_CHANGE UPDATE

Fourth run – successful

2013-12-05 14:21:33.724210 I  AFD: video timecode 1907260680 2119178521191785 21191745

2013-12-05 14:21:33.724339 E  AFD: VBI: Unknown
descriptor: 153

2013-12-05 14:21:33.724346 E  AFD: VBI: Unknown
descriptor: 44

2013-12-05 14:21:33.724528 E  AFD: VBI: Unknown
descriptor: 153

2013-12-05 14:21:33.724535 E  AFD: VBI: Unknown
descriptor: 44

2013-12-05 14:21:33.724594 E  AFD: VBI: Unknown
descriptor: 153

2013-12-05 14:21:33.724600 E  AFD: VBI: Unknown
descriptor: 44

2013-12-05 14:21:33.724859 E  AFD: VBI: Unknown
descriptor: 153

2013-12-05 14:21:33.724866 E  AFD: VBI: Unknown
descriptor: 44

2013-12-05 14:21:33.725115 I  AFD: audio timecode 1907266651 190726665121191851 21191883

2013-12-05 14:21:33.725310 I  AFD: audio timecode 1907269531 190726953121191883 21191915

2013-12-05 14:21:33.725326 I  DelMap: Tracker
wants to jump to: 206976

2013-12-05 14:21:33.725338 I  Player(0):
Fast-Forwarding from 181120 to 206976

frame=132011 fps= 14 q=28.0 size= 1342781kB
time=01:27:58.49 bitrate=2083.9kbits/s    ^Mframe=132019 fps= 14
q=28.0 size= 1342852kB time=01:27:58.81 bitrate=2083.9kbits/s   
^Mframe=132028 fps= 14 q=28.0 size= 1342926kB time=01:27:59.20
bitrate=2083.9kbits/s    ^M2013-12-05 14:21:35.342689 I 
Player(0): StopPlaying - begin

2013-12-05 14:21:35.351749 I  Player(0): Decoder
thread exiting.

2013-12-05 14:21:35.351905 I  Player(0): Exited
decoder loop.

2013-12-05 14:21:35.357745 I  ~VideoOutputNull()

2013-12-05 14:21:35.365416 I  Player(0):
StopPlaying - end

2013-12-05 14:21:35.370075 N  Transcoding
/media/fs4/recordings/1090_20131204012700.mpg done

2013-12-05 14:21:35.370125 I  Waiting for threads
to exit.

2013-12-05 14:21:35.423792 I  MythCoreContext:
Connecting to backend server: 192.168.1.33:6543 (try 1 of 1)

2013-12-05 14:21:35.423825 I 
MythSocket(ffffffffad717f50:-1): MythSocket(-1, 0x0) ctor

2013-12-05 14:21:35.431844 I 
MythSocket(ffffffffad717f50:-1): IP is local, using loopback address instead

2013-12-05 14:21:35.431862 I 
MythSocket(ffffffffad717f50:-1): attempting connect() to (127.0.0.1:6543)

2013-12-05 14:21:35.432237 I 
MythSocket(ffffffffad717f50:21): Connected to (127.0.0.1:6543)

2013-12-05 14:21:35.434297 I 
MythSocket(ffffffffad717f50:21): write -> 21
30      MYTH_PROTO_VERSION 77 WindMark

2013-12-05 14:21:35.435766 I  MythSocket(ffffffffad717f50:21):
read  <- 21 13      ACCEPT[]:[]77

2013-12-05 14:21:35.439749 I 
MythSocket(ffffffffad717f50:21): write -> 21
29      ANN Monitor mark-P5K-Deluxe 0

2013-12-05 14:21:35.440019 I 
MythSocket(ffffffffad717f50:21): read  <- 21 2      
OK

2013-12-05 14:21:35.440057 I 
MythSocket(ffffffffad716ef0:-1): MythSocket(-1, 0x9d608c4) ctor

2013-12-05 14:21:35.450564 I 
MythSocket(ffffffffad716ef0:-1): IP is local, using loopback address instead

2013-12-05 14:21:35.450581 I  MythSocket(ffffffffad716ef0:-1):
attempting connect() to (127.0.0.1:6543)

2013-12-05 14:21:35.451135 I 
MythSocket(ffffffffad716ef0:23): Connected to (127.0.0.1:6543)

2013-12-05 14:21:35.455769 I 
MythSocket(ffffffffad716ef0:23): write -> 23
29      ANN Monitor mark-P5K-Deluxe 1

2013-12-05 14:21:35.456349 I 
MythSocket(ffffffffad716ef0:23): read  <- 23
2       OK

2013-12-05 14:21:35.457616 I 
MythSocket(ffffffffad717f50:21): write -> 21
61      MESSAGE[]:[]MASTER_UPDATE_PROG_INFO 1090
2013-12-04T01:27:00Z

2013-12-05 14:21:35.457860 I 
MythSocket(ffffffffad717f50:21): read  <- 21
2       OK

2013-12-05 14:21:35.460157 I 
MythSocket(ffffffffad716ef0:23): read  <- 23
773     BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE
UPDATE[]:[]Swallows And...

2013-12-05 14:21:35.460188 I  MythEvent:
RECORDING_LIST_CHANGE UPDATE

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://www.mythtv.org/pipermail/mythtv-users/attachments/20131205/2317a17a/attachment-0001.html>