mythtranscode failing and showing wrong progress

[jan@…]

Running the following command:

mythtranscode --verbose "important,general" --honorcutlist --showprogress -i /var/lib/mythtv/recordings/29451_20091212201500.mpg -o /var/lib/mythtv/recordings/29451_20091212201500.new.mpg

gets me:

2009-12-29 10:35:16.120 Using runtime prefix = /usr
2009-12-29 10:35:16.120 Using configuration directory = /home/jan/.mythtv
2009-12-29 10:35:16.152 Empty LocalHostName.
2009-12-29 10:35:16.152 Using localhost value of htpc
2009-12-29 10:35:16.167 New DB connection, total: 1
2009-12-29 10:35:16.170 Connected to database 'mythconverg' at host: localhost
2009-12-29 10:35:16.170 Closing DB connection named 'DBManager0'
2009-12-29 10:35:16.170 Enabled verbose msgs: important general
2009-12-29 10:35:16.171 Connected to database 'mythconverg' at host: localhost
2009-12-29 10:35:16.172 New DB connection, total: 2
2009-12-29 10:35:16.172 Connected to database 'mythconverg' at host: localhost
2009-12-29 10:35:16.173 New DB connection, total: 3
2009-12-29 10:35:16.174 Connected to database 'mythconverg' at host: localhost
2009-12-29 10:35:16.175 Transcoding from /var/lib/mythtv/recordings/29451_20091212201500.mpg to /var/lib/mythtv/recordings/29451_20091212201500.new.mpg
2009-12-29 10:35:16.719 MythContext: Connecting to backend server: htpc.wg.de:6543 (try 1 of 1)
2009-12-29 10:35:16.779 Using protocol version 50
2009-12-29 10:35:16.824 [mpeg2video @ 0x12096c0]ac-tex damaged at 12 6
2009-12-29 10:35:16.824 [mpeg2video @ 0x12096c0]invalid mb type in I Frame at 0 33
2009-12-29 10:35:16.824 [mpeg2video @ 0x12096c0]invalid mb type in I Frame at 0 34
2009-12-29 10:35:16.824 [mpeg2video @ 0x12096c0]invalid mb type in I Frame at 0 35
2009-12-29 10:35:16.824 [mpeg2video @ 0x12096c0]Warning MVs not available
2009-12-29 10:35:17.036 AFD: Opened codec 0x8958880, id(MPEG2VIDEO) type(Video)
2009-12-29 10:35:17.036 AFD: codec MP2 has 2 channels
2009-12-29 10:35:17.036 AFD: Opened codec 0x8958c30, id(MP2) type(Audio)
2009-12-29 10:35:17.039 Honoring the cutlist while transcoding
2009-12-29 10:35:17.040 Cutlist        : 0-720,49560-64692,99744-111729,145587-156564,197901-212280
2009-12-29 10:35:17.040 Original Length: 239925 frames
2009-12-29 10:35:17.040 New Length     : 186732 frames
2009-12-29 10:35:17.041 Transcode: Looking for autodetect profile: Autodetect from 576i
2009-12-29 10:35:17.079 SampleRate: Attempted to add a rate 32000 Hz, which is not in the list of allowed rates.
2009-12-29 10:35:17.081 Transcode: Using autodetect profile: MPEG2
2009-12-29 10:35:17.086 Forcing Recorder option 'videocodec' to ''
2009-12-29 10:35:17.086 RecBase(NULL:/dev/video) Error: SetOption(): Unknown int option: videocodec: 0
2009-12-29 10:35:17.150 Copying Audio while transcoding Video
2009-12-29 10:35:17.151 [mpeg2video @ 0x12096c0]ac-tex damaged at 12 6
2009-12-29 10:35:17.151 [mpeg2video @ 0x12096c0]invalid mb type in I Frame at 0 33
2009-12-29 10:35:17.152 [mpeg2video @ 0x12096c0]invalid mb type in I Frame at 0 34
2009-12-29 10:35:17.152 [mpeg2video @ 0x12096c0]invalid mb type in I Frame at 0 35
2009-12-29 10:35:17.152 [mpeg2video @ 0x12096c0]Warning MVs not available
strange error flushing buffer ... 
2009-12-29 10:35:21.179 Processed: 477 of 239925 frames(19 seconds)
2009-12-29 10:35:26.184 Processed: 1067 of 239925 frames(42 seconds)
2009-12-29 10:35:31.187 Processed: 1722 of 239925 frames(68 seconds)
2009-12-29 10:35:36.192 Processed: 2326 of 239925 frames(93 seconds)
2009-12-29 10:35:41.194 Processed: 2863 of 239925 frames(114 seconds)
2009-12-29 10:35:46.203 Processed: 3406 of 239925 frames(136 seconds)
2009-12-29 10:35:51.211 Processed: 3972 of 239925 frames(158 seconds)
2009-12-29 10:35:56.212 Processed: 4529 of 239925 frames(181 seconds)
2009-12-29 10:36:01.216 Processed: 5064 of 239925 frames(202 seconds)
2009-12-29 10:36:06.217 Processed: 5601 of 239925 frames(224 seconds)
2009-12-29 10:36:11.222 Processed: 6120 of 239925 frames(244 seconds)
2009-12-29 10:36:16.179 mythtranscode: 3% Completed @ 112.081 fps.
2009-12-29 10:36:16.233 Processed: 6620 of 239925 frames(264 seconds)

...

2009-12-29 11:03:36.672 mythtranscode: 99% Completed @ 108.843 fps.
2009-12-29 11:03:37.972 Processed: 185117 of 239925 frames(7404 seconds)
2009-12-29 11:03:42.975 Processed: 185661 of 239925 frames(7426 seconds)
2009-12-29 11:03:47.985 Processed: 186182 of 239925 frames(7447 seconds)
2009-12-29 11:03:52.991 Processed: 186697 of 239925 frames(7467 seconds)
2009-12-29 11:03:56.680 mythtranscode: 100% Completed @ 108.781 fps.
2009-12-29 11:03:57.993 Processed: 187194 of 239925 frames(7487 seconds)
2009-12-29 11:04:02.997 Processed: 187753 of 239925 frames(7510 seconds)
2009-12-29 11:04:08.006 Processed: 188326 of 239925 frames(7533 seconds)
2009-12-29 11:04:13.011 Processed: 188922 of 239925 frames(7556 seconds)
2009-12-29 11:04:16.683 mythtranscode: 101% Completed @ 108.845 fps.
2009-12-29 11:04:18.018 Processed: 189493 of 239925 frames(7579 seconds)

...

2009-12-29 11:11:56.827 mythtranscode: 128% Completed @ 108.66 fps.
2009-12-29 11:11:58.479 Processed: 239192 of 239925 frames(9567 seconds)
2009-12-29 11:12:03.488 Processed: 239701 of 239925 frames(9588 seconds)
2009-12-29 11:12:05.752 [mp2 @ 0x12096c0]incomplete frame
2009-12-29 11:12:05.752 AFD Error: Unknown audio decoding error
2009-12-29 11:12:05.822 Transcoding /var/lib/mythtv/recordings/29451_20091212201500.mpg done
ICE default IO error handler doing an exit(), pid = 12029, errno = 32

[cpinkham]

(In [23389]) Fix a bug in calculating the completion percentage.

Fixes #7820.

The other issue in the ticket appears to be bad data since it references trouble decoding the audio stream audio.

[jan@…]

Thanks for fixing the percentage.

But shouldn't mythtranscode be able to deal with bad data? Recorded streams are full of bad data.