[mythtv-users] Mytharchive on Mythbuntu fails with possible UTC problem (0.27)

Wed Sep 25 18:21:52 UTC 2013

Mytharchive calls mythtranscode (from mythburn.py) with incorrect 
starttime, resulting in mythtranscode not finding the recording and 
erroring out.

The actual recording start time of this example is 13:36:00 local time, 
Chicago Time Zone (-5 hours from UTC)
The mythconverge database entry is 18:36:00 (that's the UTC time)

mythtranscode reports, via mythburn.log"
******************************************
*"Couldn't find recording for chanid 1211 @ 2013-09-24T13:36:00Z"
*"Failed while running mythtranscode to cut commercials and/or clean up 
an mpeg2 file."
*"Result: 146, Command was mythtranscode --mpeg2 --chanid 1211 
--starttime 2013-09-24T13:36:00-05:00 --outfile 
"/var/lib/mytharchive/temp/work/1/newfile.mpg""
*"Failed to run mythtranscode to fix any errors"
******************************************
If I run mythtranscode from the command line this works:
mythtranscode --mpeg2 --chanid 1211 --starttime 
2013-09-24T18:36:00-05:00 --outfile 
"/var/lib/mytharchive/temp/work/1/newfile.mpg"

and, oddly enough, this command line works too:
mythtranscode --mpeg2 --chanid 1211 --starttime 2013-09-24T18:36:00 
--outfile "/var/lib/mytharchive/temp/work/1/newfile.mpg

So there are two problems.
1)  The -05:00 at the end of --starttime seems to be ignored
2)   The command line that is passed to mythtranscode has the local 
--starttime in it, not the UTC starttime, which is what is in the 
database and what mythtranscode seems to like.

This worked fine under 0.25, but fails consistently after upgrading 
0.27.  I also tried a clean install of the latest Mythbuntu, upgraded it 
to 0.27, then installed mytharchive via the Mythbunutu control center 
and still get the same failure.  It is consistent and repeatable.

Mythtv Revision: v.027-9-gb6a6574  branch: fixes/0.27
Mythbuntu  12.04.3 LTS

I have attached the complete mythburn.log

Any advice on what I might be doing wrong or a fix would be appreciated.
Thanks,
Ziggy

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://www.mythtv.org/pipermail/mythtv-users/attachments/20130925/82bb7181/attachment.html>
-------------- next part --------------
Using simple_fix_rtl
mythburn.py (0.1.20130911-5) starting up...
script path:/usr/share/mythtv/mytharchive/scripts
myth share path:/usr/share/mythtv
passed job file: /var/lib/mytharchive/temp/config/mydata.xml
passed progress log file: /var/lib/mytharchive/temp/logs/progress.log
mythburn.py (0.1.20130911-5) starting up...
Found 4 CPUs
Obtaining MythTV settings from MySQL database for hostname mythbuntu
temppath: /var/lib/mytharchive/temp/work
logpath:  /var/lib/mytharchive/temp/logs
Setting process priority to 10
Cannot change ionice level
Processing Mythburn job number 1.
Options - mediatype = 0, doburn = 0, createiso = 1, erasedvdrw = 0
          savefilename = ''
Looking for: /usr/share/mythtv/mytharchive/themes/Simple_-_Autoplay/theme.xml
Loading font 0, /usr/share/mythtv/fonts/FreeSans.ttf size 19
Loading font 1, /usr/share/mythtv/fonts/FreeSans.ttf size 15
Loading font 2, /usr/share/mythtv/fonts/FreeSans.ttf size 13
wantIntro: 0, wantMainMenu: 0, wantChapterMenu: 0, wantDetailsPage: 0
Final DVD Video format will be ntsc
There are 1 file(s) to process
Pre-processing recording 1: '/var/lib/mythtv/recordings/1211_20130924183600.mpg'
[mpegts @ 0x13c9e80] PES packet size mismatch
Input #0, mpegts, from '/var/lib/mythtv/recordings/1211_20130924183600.mpg':
  Duration: 00:01:26.98, start: 58.204789, bitrate: 11918 kb/s
    Stream #0:0[0x31]: Video: mpeg2video (Main), yuv420p, 1280x720 [SAR 1:1 DAR 16:9], 59.94 fps, 59.94 tbr, 90k tbn, 119.88 tbc
    Stream #0:1[0x34](eng): Audio: ac3 (AC-3 / 0x332D4341), 48000 Hz, stereo, fltp, 384 kb/s
    Stream #0:2[0x35](spa): Audio: ac3 (AC-3 / 0x332D4341), 48000 Hz, stereo, fltp, 192 kb/s
/var/lib/mytharchive/temp/work/1/streaminfo.xml:-
<?xml version="1.0" ?><!DOCTYPE FILEINFO><file cutduration="86" duration="86" filename="/var/lib/mythtv/recordings/1211_20130924183600.mpg" type="mpegts">    
        <streams count="3">        
                <video aspectratio="1.77778" bitrate="0" codec="mpeg2video (Main)" ffmpegindex="0" fps="59.9401" height="720" id="49" start_time="5.305097" streamindex="0" width="1280"/>        
                <audio bitrate="384000" channels="2" codec="ac3 (AC-3 / 0x332D4341)" ffmpegindex="1" id="52" language="eng" samplerate="48000" start_time="5.238431" streamindex="1"/>        
                <audio bitrate="192000" channels="2" codec="ac3 (AC-3 / 0x332D4341)" ffmpegindex="2" id="53" language="spa" samplerate="48000" start_time="5.249229" streamindex="2"/>        
        </streams>    
</file>
          WordGirl
Node = chanid, Data = 1211
Node = type, Data = recording
Node = filename, Data = /var/lib/mythtv/recordings/1211_20130924183600.mpg
Node = title, Data = WordGirl
Node = recordingdate, Data = Tue  Sep  24
Node = recordingtime, Data = 01:30 PM
Node = subtitle, Data = Chuck With a Sidekick of Brent; Yarn-4-Gold
Node = description, Data = Chuck sets out to turn the city into a giant sandwich; WordGirl and Bampy try to stop Granny May's rampage.
Node = rating, Data = 0.0
Node = coverfile, Data = 
Node = cutlist, Data = 
Node = starttime, Data = 2013-09-24T13:36:00-05:00
Node = hascutlist, Data = no
Video resolution is 1280 by 720
*************************************************************
Processing recording 1: '/var/lib/mythtv/recordings/1211_20130924183600.mpg'
*************************************************************
File type is 'mpegts'
Video codec is 'mpeg2video (Main)'
Running mythtranscode --mpeg2 to fix any errors
2013-09-24 13:38:19.603512 C  mythtranscode version: fixes/0.27 [v0.27-1-g5b917e8] www.mythtv.org
2013-09-24 13:38:19.603539 C  Qt version: compile: 4.8.1, runtime: 4.8.1
2013-09-24 13:38:19.603545 N  Enabled verbose msgs:  general
2013-09-24 13:38:19.603560 N  Setting Log Level to LOG_INFO
2013-09-24 13:38:19.614339 I  Added logging to the console
2013-09-24 13:38:19.615041 I  Setup Interrupt handler
2013-09-24 13:38:19.615055 I  Setup Terminated handler
2013-09-24 13:38:19.615067 I  Setup Segmentation fault handler
2013-09-24 13:38:19.615078 I  Setup Aborted handler
2013-09-24 13:38:19.615088 I  Setup Bus error handler
2013-09-24 13:38:19.615098 I  Setup Floating point exception handler
2013-09-24 13:38:19.615110 I  Setup Illegal instruction handler
2013-09-24 13:38:19.615126 I  Setup Real-time signal 0 handler
2013-09-24 13:38:19.615166 N  Using runtime prefix = /usr
2013-09-24 13:38:19.615181 N  Using configuration directory = /home/ziggy/.mythtv
2013-09-24 13:38:19.615256 I  Assumed character encoding: en_US.UTF-8
2013-09-24 13:38:19.615654 N  Empty LocalHostName.
2013-09-24 13:38:19.615664 I  Using localhost value of mythbuntu
2013-09-24 13:38:19.635855 N  Setting QT default locale to EN_US
2013-09-24 13:38:19.635935 I  Current locale EN_US
2013-09-24 13:38:19.635991 N  Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
2013-09-24 13:38:19.641898 I  Loading en_us translation for module mythfrontend
2013-09-24 13:38:19.642826 E  Couldn't find recording for chanid 1211 @ 2013-09-24T13:36:00Z
2013-09-24 13:38:19.716068 I  New Client:  (#1)
Failed while running mythtranscode to cut commercials and/or clean up an mpeg2 file.
Result: 146, Command was mythtranscode --mpeg2 --chanid 1211 --starttime 2013-09-24T13:36:00-05:00 --outfile "/var/lib/mytharchive/temp/work/1/newfile.mpg"
Failed to run mythtranscode to fix any errors
SNIP------------------------------------------------