Ticket #5866: mythbackend_live_tv_channel_change_prob.txt

File mythbackend_live_tv_channel_change_prob.txt, 50.5 KB (added by kevin, 11 years ago)

mythbackend log -v record,channel of live tv channel change problem

Line 
12009-01-18 17:56:54.782 mythbackend version: trunk [Unknown] www.mythtv.org
22009-01-18 17:56:54.788 Using runtime prefix = /usr/local
32009-01-18 17:56:54.789 Using localhost value of wambs-server
42009-01-18 17:56:54.797 New DB connection, total: 1
52009-01-18 17:56:54.802 Connected to database 'mythconverg' at host: localhost
62009-01-18 17:56:54.803 Closing DB connection named 'DBManager0'
72009-01-18 17:56:54.805 Connected to database 'mythconverg' at host: localhost
82009-01-18 17:56:54.809 Current Schema Version: 1228
92009-01-18 17:56:54.811 MythBackend: Starting up as the master server.
102009-01-18 17:56:54.814 New DB connection, total: 2
112009-01-18 17:56:54.816 Connected to database 'mythconverg' at host: localhost
122009-01-18 17:56:54.817 mythbackend: MythBackend started as master server
132009-01-18 17:56:54.824 TVRec(1): Start channel: 4_1.
142009-01-18 17:56:54.828 DVBChan(1:/dev/dvb/adapter0/frontend0): Opening DVB channel
152009-01-18 17:56:54.829 DVBChan(1:/dev/dvb/adapter0/frontend0): Using DVB card /dev/dvb/adapter0/frontend0, with frontend 'LG Electronics LGDT3303 VSB/QAM Frontend'.
162009-01-18 17:56:54.830 New DB connection, total: 3
172009-01-18 17:56:54.832 Connected to database 'mythconverg' at host: localhost
182009-01-18 17:56:54.835 ChannelBase(1): Input #1: 'DVBInput' schan(4_1) sourceid(3) ccid(1)
192009-01-18 17:56:54.836 ChannelBase(1): Current Input #1: 'DVBInput'
202009-01-18 17:56:54.837 DVBChan(1:/dev/dvb/adapter0/frontend0): SetChannelByString(4_1):
212009-01-18 17:56:54.854 DVBChan(1:/dev/dvb/adapter0/frontend0): 599000000 8vsb a auto auto a a auto a v
222009-01-18 17:56:54.856 DVBChan(1:/dev/dvb/adapter0/frontend0): Old Params: 0 auto a auto auto a a auto a v
23                        DVBChan(1:/dev/dvb/adapter0/frontend0): New Params: 599000000 8vsb a auto auto a a auto a v
242009-01-18 17:56:54.857 DVBChan(1:/dev/dvb/adapter0/frontend0): Tune(): Tuning to 599000000Hz
252009-01-18 17:56:54.861 dvbchannel.cpp:wait_for_backend: Status:
262009-01-18 17:56:54.862 DVBChan(1:/dev/dvb/adapter0/frontend0): Tune(): Frequency tuning successful.
272009-01-18 17:56:54.863 DVBChan(1:/dev/dvb/adapter0/frontend0): SetChannelByString(4_1): Tuned to frequency.
282009-01-18 17:56:54.870 TVRec(1): SetFlags(RunMainLoop,) -> RunMainLoop,
292009-01-18 17:56:54.871 TVRec(1): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop,
302009-01-18 17:56:54.878 TVRec(2): Start channel: 2_1.
312009-01-18 17:56:54.879 DVBChan(2:/dev/dvb/adapter0/frontend0): Opening DVB channel
322009-01-18 17:56:54.880 DVBChan(1:/dev/dvb/adapter0/frontend0): Opening DVB channel
332009-01-18 17:56:54.883 ChannelBase(2): Input #2: 'DVBInput' schan(2_1) sourceid(3) ccid(2)
342009-01-18 17:56:54.884 ChannelBase(2): Current Input #2: 'DVBInput'
352009-01-18 17:56:54.885 DVBChan(2:/dev/dvb/adapter0/frontend0): SetChannelByString(2_1):
362009-01-18 17:56:54.888 DVBChan(2:/dev/dvb/adapter0/frontend0): 647000000 8vsb a auto auto a a auto a v
372009-01-18 17:56:54.889 DVBChan(2:/dev/dvb/adapter0/frontend0): tuning on slave channel
382009-01-18 17:56:54.890 DVBChan(1:/dev/dvb/adapter0/frontend0): Old Params: 599000000 8vsb a auto auto a a auto a v
39                        DVBChan(1:/dev/dvb/adapter0/frontend0): New Params: 647000000 8vsb a auto auto a a auto a v
402009-01-18 17:56:54.891 DVBChan(1:/dev/dvb/adapter0/frontend0): Tune(): Tuning to 647000000Hz
412009-01-18 17:56:54.896 dvbchannel.cpp:wait_for_backend: Status:
422009-01-18 17:56:54.897 DVBChan(1:/dev/dvb/adapter0/frontend0): Tune(): Frequency tuning successful.
432009-01-18 17:56:54.898 DVBChan(2:/dev/dvb/adapter0/frontend0): SetChannelByString(2_1): Tuned to frequency.
442009-01-18 17:56:54.899 TVRec(2): SetFlags(RunMainLoop,) -> RunMainLoop,
452009-01-18 17:56:54.901 TVRec(2): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop,
462009-01-18 17:56:54.903 TVRec(3): Start channel: 13_1.
472009-01-18 17:56:54.906 DVBChan(3:/dev/dvb/adapter1/frontend0): Opening DVB channel
482009-01-18 17:56:54.907 DVBChan(3:/dev/dvb/adapter1/frontend0): Using DVB card /dev/dvb/adapter1/frontend0, with frontend 'LG Electronics LGDT3303 VSB/QAM Frontend'.
492009-01-18 17:56:54.909 ChannelBase(3): Input #3: 'DVBInput' schan(13_1) sourceid(3) ccid(3)
502009-01-18 17:56:54.910 ChannelBase(3): Current Input #3: 'DVBInput'
512009-01-18 17:56:54.911 DVBChan(3:/dev/dvb/adapter1/frontend0): SetChannelByString(13_1):
522009-01-18 17:56:54.914 DVBChan(3:/dev/dvb/adapter1/frontend0): 473000000 8vsb a auto auto a a auto a v
532009-01-18 17:56:54.915 DVBChan(3:/dev/dvb/adapter1/frontend0): Old Params: 0 auto a auto auto a a auto a v
54                        DVBChan(3:/dev/dvb/adapter1/frontend0): New Params: 473000000 8vsb a auto auto a a auto a v
552009-01-18 17:56:54.916 DVBChan(3:/dev/dvb/adapter1/frontend0): Tune(): Tuning to 473000000Hz
562009-01-18 17:56:54.921 dvbchannel.cpp:wait_for_backend: Status:
572009-01-18 17:56:54.922 DVBChan(3:/dev/dvb/adapter1/frontend0): Tune(): Frequency tuning successful.
582009-01-18 17:56:54.923 DVBChan(3:/dev/dvb/adapter1/frontend0): SetChannelByString(13_1): Tuned to frequency.
592009-01-18 17:56:54.924 TVRec(3): SetFlags(RunMainLoop,) -> RunMainLoop,
602009-01-18 17:56:54.926 TVRec(3): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop,
612009-01-18 17:56:54.928 TVRec(4): Start channel: 8_3.
622009-01-18 17:56:54.929 DVBChan(4:/dev/dvb/adapter1/frontend0): Opening DVB channel
632009-01-18 17:56:54.930 DVBChan(3:/dev/dvb/adapter1/frontend0): Opening DVB channel
642009-01-18 17:56:54.933 ChannelBase(4): Input #4: 'DVBInput' schan(8_3) sourceid(3) ccid(4)
652009-01-18 17:56:54.934 ChannelBase(4): Current Input #4: 'DVBInput'
662009-01-18 17:56:54.935 DVBChan(4:/dev/dvb/adapter1/frontend0): SetChannelByString(8_3):
672009-01-18 17:56:54.938 DVBChan(4:/dev/dvb/adapter1/frontend0): 189000000 8vsb a auto auto a a auto a v
682009-01-18 17:56:54.939 DVBChan(4:/dev/dvb/adapter1/frontend0): tuning on slave channel
692009-01-18 17:56:54.940 DVBChan(3:/dev/dvb/adapter1/frontend0): Old Params: 473000000 8vsb a auto auto a a auto a v
70                        DVBChan(3:/dev/dvb/adapter1/frontend0): New Params: 189000000 8vsb a auto auto a a auto a v
712009-01-18 17:56:54.941 DVBChan(3:/dev/dvb/adapter1/frontend0): Tune(): Tuning to 189000000Hz
722009-01-18 17:56:54.942 dvbchannel.cpp:wait_for_backend: Status:
732009-01-18 17:56:54.943 DVBChan(3:/dev/dvb/adapter1/frontend0): Tune(): Frequency tuning successful.
742009-01-18 17:56:54.943 DVBChan(4:/dev/dvb/adapter1/frontend0): SetChannelByString(8_3): Tuned to frequency.
752009-01-18 17:56:54.945 TVRec(4): SetFlags(RunMainLoop,) -> RunMainLoop,
762009-01-18 17:56:54.946 TVRec(4): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop,
772009-01-18 17:56:54.948 TVRec(5): Start channel: 231.
782009-01-18 17:56:54.949 Channel(/dev/video0): Device name 'Haupauge HD PVR' driver 'hdpvr'.
792009-01-18 17:56:55.022 ChannelBase(5): Input #5: 'Component' schan(231) sourceid(1) ccid(5)
802009-01-18 17:56:55.023 ChannelBase(5): Current Input #5: 'Component'
812009-01-18 17:56:55.025 Global TVFormat Setting 'NTSC'
822009-01-18 17:56:55.026 Channel(/dev/video0): Input #5: 'Component' schan(231) tun() v4l1(NTSC) v4l2(NTSC)
832009-01-18 17:56:55.027 Channel(/dev/video0): SetFormat(Default) fmt(NTSC) input(5)
842009-01-18 17:56:55.028 Channel(/dev/video0): SetInputAndFormat(5, NTSC) (v4l v2)
852009-01-18 17:56:55.069 Channel(/dev/video0): SetFormat(NTSC) fmt(NTSC) input(5)
862009-01-18 17:56:55.071 Channel(/dev/video0)::SwitchToInput(in 5, '')
872009-01-18 17:56:55.072 Channel(/dev/video0): SetInputAndFormat(5, NTSC) (v4l v2)
882009-01-18 17:56:55.112 Channel(/dev/video0): SetChannelByString(231)
892009-01-18 17:56:55.115 Channel(/dev/video0): SetFormat(Default) fmt(NTSC) input(5)
902009-01-18 17:56:55.116 External channel change: /usr/local/share/mythtv/changechannel /dev/ttyD0 231
912009-01-18 17:56:55.117 Waiting for External Tuning program to exit
922009-01-18 17:56:56.119 ret_pid(0) child(2995) status(0x0)
932009-01-18 17:56:56.735 ret_pid(2995) child(2995) status(0x0)
942009-01-18 17:56:56.736 External Tuning program no longer running
952009-01-18 17:56:56.738 External Tuning program exited with no error
962009-01-18 17:56:56.741 Channel(/dev/video0): InitPictureAttribute(brightness):  134
97                        [    0,  255] dflt(  134, 0.53, 1670)
982009-01-18 17:56:56.761 Channel(/dev/video0): InitPictureAttribute(  contrast):  128
99                        [    0,  255] dflt(  128, 0.50, 128)
1002009-01-18 17:56:56.782 Channel(/dev/video0): InitPictureAttribute(    colour):  128
101                        [    0,  255] dflt(  128, 0.50, 128)
1022009-01-18 17:56:56.802 Channel(/dev/video0): InitPictureAttribute(       hue):  128
103                        [    0,  255] dflt(  128, 0.50, 128)
1042009-01-18 17:56:56.822 TVRec(5): SetFlags(RunMainLoop,) -> RunMainLoop,
1052009-01-18 17:56:56.823 TVRec(5): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop,
1062009-01-18 17:56:56.826 TVRec(6): Start channel: 75.
1072009-01-18 17:56:56.827 Channel(/dev/video1): Device name 'Haupauge HD PVR' driver 'hdpvr'.
1082009-01-18 17:56:56.951 ChannelBase(6): Input #6: 'Component' schan(75) sourceid(1) ccid(6)
1092009-01-18 17:56:56.953 ChannelBase(6): Current Input #6: 'Component'
1102009-01-18 17:56:56.954 Global TVFormat Setting 'NTSC'
1112009-01-18 17:56:56.955 Channel(/dev/video1): Input #6: 'Component' schan(75) tun() v4l1(NTSC) v4l2(NTSC)
1122009-01-18 17:56:56.956 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(6)
1132009-01-18 17:56:56.957 Channel(/dev/video1): SetInputAndFormat(6, NTSC) (v4l v2)
1142009-01-18 17:56:56.996 Channel(/dev/video1): SetFormat(NTSC) fmt(NTSC) input(6)
1152009-01-18 17:56:56.998 Channel(/dev/video1)::SwitchToInput(in 6, '')
1162009-01-18 17:56:56.999 Channel(/dev/video1): SetInputAndFormat(6, NTSC) (v4l v2)
1172009-01-18 17:56:57.040 Channel(/dev/video1): SetChannelByString(75)
1182009-01-18 17:56:57.044 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(6)
1192009-01-18 17:56:57.045 External channel change: /usr/local/share/mythtv/changechannel /dev/ttyD3 75
1202009-01-18 17:56:57.047 Waiting for External Tuning program to exit
1212009-01-18 17:56:58.049 ret_pid(0) child(2998) status(0x0)
1222009-01-18 17:56:58.587 ret_pid(2998) child(2998) status(0x0)
1232009-01-18 17:56:58.589 External Tuning program no longer running
1242009-01-18 17:56:58.590 External Tuning program exited with no error
1252009-01-18 17:56:58.593 Channel(/dev/video1): InitPictureAttribute(brightness):  134
126                        [    0,  255] dflt(  134, 0.53, 1670)
1272009-01-18 17:56:58.613 Channel(/dev/video1): InitPictureAttribute(  contrast):  128
128                        [    0,  255] dflt(  128, 0.50, 128)
1292009-01-18 17:56:58.634 Channel(/dev/video1): InitPictureAttribute(    colour):  128
130                        [    0,  255] dflt(  128, 0.50, 128)
1312009-01-18 17:56:58.654 Channel(/dev/video1): InitPictureAttribute(       hue):  128
132                        [    0,  255] dflt(  128, 0.50, 128)
1332009-01-18 17:56:58.674 TVRec(6): SetFlags(RunMainLoop,) -> RunMainLoop,
1342009-01-18 17:56:58.676 TVRec(6): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop,
1352009-01-18 17:56:58.678 New DB scheduler connection
1362009-01-18 17:56:58.680 Connected to database 'mythconverg' at host: localhost
1372009-01-18 17:56:58.723 Enabling Upnpmedia rebuild thread.
1382009-01-18 17:56:59.608 Main::Registering HttpStatus Extension
1392009-01-18 17:56:59.610 Enabled verbose msgs:  important general record channel
1402009-01-18 17:56:59.615 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
1412009-01-18 17:57:01.686 Reschedule requested for id -1.
1422009-01-18 17:57:02.263 Scheduled 198 items in 0.6 = 0.13 match + 0.44 place
1432009-01-18 17:57:02.270 scheduler: Scheduled items: Scheduled 198 items in 0.6 = 0.13 match + 0.44 place
1442009-01-18 17:57:02.274 AUTO-Startup assumed
1452009-01-18 17:57:04.836 mythbackend: Running housekeeping thread
1462009-01-18 17:57:08.728 UPnpMedia: BuildMediaMap VIDEO scan starting in :/share/Movies/dvd:
1472009-01-18 17:57:08.731 UPnpMedia: BuildMediaMap Done. Found 0 objects
1482009-01-18 17:58:18.686 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
1492009-01-18 17:58:27.266 MainServer::ANN Monitor
1502009-01-18 17:58:27.267 adding: living-room as a client (events: 0)
1512009-01-18 17:58:27.269 MainServer::ANN Monitor
1522009-01-18 17:58:27.271 adding: living-room as a client (events: 1)
1532009-01-18 17:58:27.273 Getting next free recorder after : -1
1542009-01-18 17:58:27.275 Checking card 1. Best card so far 1
1552009-01-18 17:58:27.276 Checking card 2. Best card so far 2
1562009-01-18 17:58:27.278 Checking card 3. Best card so far 3
1572009-01-18 17:58:27.279 Checking card 4. Best card so far 4
1582009-01-18 17:58:27.280 Checking card 5. Best card so far 5
1592009-01-18 17:58:27.281 Checking card 6. Best card so far 6
1602009-01-18 17:58:27.310 MainServer::ANN Playback
1612009-01-18 17:58:27.312 adding: living-room as a client (events: 0)
1622009-01-18 17:58:27.315 TVRec(6): Changing from None to WatchingLiveTV
1632009-01-18 17:58:27.316 TVRec(6): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,
1642009-01-18 17:58:27.317 TVRec(6): Request: Program(no) channel() input() flags(LiveTV,)
1652009-01-18 17:58:27.319 TVRec(6): Start channel: 75.
1662009-01-18 17:58:27.324 TVRec(6): HW Tuner: 6->6
1672009-01-18 17:58:27.326 TVRec(6): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,
1682009-01-18 17:58:27.327 TVRec(6): No recorder yet, calling TuningFrequency
1692009-01-18 17:58:27.329 Channel(/dev/video1): Device name 'Haupauge HD PVR' driver 'hdpvr'.
1702009-01-18 17:58:27.440 ChannelBase(6): Input #6: 'Component' schan(75) sourceid(1) ccid(6)
1712009-01-18 17:58:27.442 ChannelBase(6): Current Input #6: 'Component'
1722009-01-18 17:58:27.443 Global TVFormat Setting 'NTSC'
1732009-01-18 17:58:27.443 Channel(/dev/video1): Input #6: 'Component' schan(75) tun() v4l1(NTSC) v4l2(NTSC)
1742009-01-18 17:58:27.444 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(6)
1752009-01-18 17:58:27.445 Channel(/dev/video1)::SwitchToInput(in 6, '')
1762009-01-18 17:58:27.448 Channel(/dev/video1): SetInputAndFormat(6, NTSC) (v4l v2)
1772009-01-18 17:58:27.484 Channel(/dev/video1): SetChannelByString(75)
1782009-01-18 17:58:27.488 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(6)
1792009-01-18 17:58:27.489 External channel change: /usr/local/share/mythtv/changechannel /dev/ttyD3 75
1802009-01-18 17:58:27.491 Waiting for External Tuning program to exit
1812009-01-18 17:58:28.492 ret_pid(0) child(3024) status(0x0)
1822009-01-18 17:58:29.051 ret_pid(3024) child(3024) status(0x0)
1832009-01-18 17:58:29.052 External Tuning program no longer running
1842009-01-18 17:58:29.054 External Tuning program exited with no error
1852009-01-18 17:58:29.057 Channel(/dev/video1): InitPictureAttribute(brightness):  134
186                        [    0,  255] dflt(  134, 0.53, 1670)
1872009-01-18 17:58:29.077 Channel(/dev/video1): InitPictureAttribute(  contrast):  128
188                        [    0,  255] dflt(  128, 0.50, 128)
1892009-01-18 17:58:29.097 Channel(/dev/video1): InitPictureAttribute(    colour):  128
190                        [    0,  255] dflt(  128, 0.50, 128)
1912009-01-18 17:58:29.117 Channel(/dev/video1): InitPictureAttribute(       hue):  128
192                        [    0,  255] dflt(  128, 0.50, 128)
1932009-01-18 17:58:29.135 TVRec(6): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,
1942009-01-18 17:58:29.137 TVRec(6): SetFlags(NeedToStartRecorder,) -> RunMainLoop,NeedToStartRecorder,
1952009-01-18 17:58:29.138 TVRec(6): Starting Recorder
1962009-01-18 17:58:29.157 Using profile 'Live TV' to record
1972009-01-18 17:58:29.158 TVRec(6): CreateLiveTVRingBuffer()
1982009-01-18 17:58:29.159 TVRec(6): GetProgramRingBufferForLiveTV()
1992009-01-18 17:58:30.207 TVRec(6): StartedRecording(0x920d70) fn(/video1/1075_20090118175829.mpg)
2002009-01-18 17:58:30.215 Chain: Appended@0 '1075_20090118175829'
2012009-01-18 17:58:30.218 TVRec(6): SetFlags(RingBufferReady,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady,
2022009-01-18 17:58:30.224 RecBase(6:/dev/video1): SetRingBuffer(0xaccc10) '/video1/1075_20090118175829.mpg'
2032009-01-18 17:58:30.225 RecBase(6:/dev/video1): SetRecording(0xaf7b50) title(Failure to Launch)
2042009-01-18 17:58:30.228 Channel(/dev/video1): InitPictureAttribute(brightness):  134
205                        [    0,  255] dflt(  134, 0.53, 1670)
2062009-01-18 17:58:30.248 Channel(/dev/video1): InitPictureAttribute(  contrast):  128
207                        [    0,  255] dflt(  128, 0.50, 128)
2082009-01-18 17:58:30.268 Channel(/dev/video1): InitPictureAttribute(    colour):  128
209                        [    0,  255] dflt(  128, 0.50, 128)
2102009-01-18 17:58:30.289 Channel(/dev/video1): InitPictureAttribute(       hue):  128
211                        [    0,  255] dflt(  128, 0.50, 128)
2122009-01-18 17:58:30.308 MPEGRec(/dev/video1): usingv4l2(1) has_v4l2_vbi(0) has_buggy_vbi(1)
2132009-01-18 17:58:30.308 TVRec(6): SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RingBufferReady,
2142009-01-18 17:58:30.326 AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 15 min
2152009-01-18 17:58:30.329 MainServer::ANN Playback
2162009-01-18 17:58:30.330 adding: living-room as a client (events: 0)
2172009-01-18 17:58:30.332 MainServer::HandleAnnounce FileTransfer
2182009-01-18 17:58:30.333 adding: living-room as a remote file transfer
2192009-01-18 17:58:30.339 DevRdB(/dev/video1): buffer size 9400 KB
2202009-01-18 17:58:30.341 MPEGRec(/dev/video1): DRB ready
2212009-01-18 17:58:30.342 MPEGRec(/dev/video1): SetStreamData(0xb5fdd0) -- begin
2222009-01-18 17:58:30.344 MPEGRec(/dev/video1): SetStreamData(0xb5fdd0) -- end 1
2232009-01-18 17:58:30.345 MPEGRec(/dev/video1): HandleSingleProgramPAT(NULL)
2242009-01-18 17:58:30.346 MPEGRec(/dev/video1): Initial startup of recorder
2252009-01-18 17:58:30.347 MPEGRec(/dev/video1): Checking Resolution
2262009-01-18 17:58:30.349 TVRec(6): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady,
2272009-01-18 17:58:30.350 TVRec(6): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady,
2282009-01-18 17:58:34.829 MPEGRec(/dev/video1): Got Resolution 1280x720
2292009-01-18 17:58:34.832 MPEGRec(/dev/video1): Old bitrate 13500/20200 VBR
2302009-01-18 17:58:34.833 MPEGRec(/dev/video1): New bitrate 9000/11000 kbps VBR
2312009-01-18 17:58:34.843 MPEGRec(/dev/video1): StartEncoding
2322009-01-18 17:58:35.156 MPEGRec(/dev/video1): Encoding started
2332009-01-18 17:58:35.168 CreatePATSingleProgram()
2342009-01-18 17:58:35.170 PAT in input stream
2352009-01-18 17:58:35.171 Program Association Table
236 PSIP tableID(0x0) length(17) extension(0x0)
237      version(0) current(1) section(0) last_section(0)
238         tsid: 0
239 programCount: 2
240  program number     0 has PID 0x  1f   data  0x 0 0x 0 0xe0 0x1f
241  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
242
2432009-01-18 17:58:35.172 desired_program(1) pid(0x100)
2442009-01-18 17:58:35.173 pmt_pid(0x100)
2452009-01-18 17:58:35.174 PAT for output stream
2462009-01-18 17:58:35.175 Program Association Table
247 PSIP tableID(0x0) length(13) extension(0x0)
248      version(0) current(1) section(0) last_section(0)
249         tsid: 0
250 programCount: 1
251  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
252
2532009-01-18 17:58:35.177 CreatePMTSingleProgram()
2542009-01-18 17:58:35.178 PMT in input stream
2552009-01-18 17:58:35.179 Program Map Table ver(0) pid(0x100) pnum(1) len(59)
256  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
257  Unknown(136) Descriptor (0x88) length(4)
258
259 Stream #0 pid(0x1011) type(video-h264  0x1b)
260  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
261 Stream #1 pid(0x1100) type(audio-ac3  0x81)
262  Registration Descriptor: 'AC-3' ATSC audio stream A/52
263  Audio Stream Descriptor  full_srv(0) sample_rate(48kbps) bit_rate(=192kbps, 10)
264      bsid(8) bs_mode(0) channels(2/2 ) Dolby(Not indicated)
265      mainID(0)
266
2672009-01-18 17:58:35.181 PMT for output stream
2682009-01-18 17:58:35.182 Program Map Table ver(0) pid(0x100) pnum(1) len(59)
269  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
270  Unknown(136) Descriptor (0x88) length(4)
271
272 Stream #0 pid(0x1011) type(video-h264  0x1b)
273  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
274 Stream #1 pid(0x1100) type(audio-ac3  0x81)
275  Registration Descriptor: 'AC-3' ATSC audio stream A/52
276  Audio Stream Descriptor  full_srv(0) sample_rate(48kbps) bit_rate(=192kbps, 10)
277      bsid(8) bs_mode(0) channels(2/2 ) Dolby(Not indicated)
278      mainID(0)
279
2802009-01-18 17:58:35.618 PID 0x1011 Found Payload Start
2812009-01-18 17:58:35.670 PID 0x1100 Found Payload Start
2822009-01-18 17:58:37.161 TVRec(6): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady,
2832009-01-18 17:58:43.521 TVRec(6): SetChannel(71) -- begin
2842009-01-18 17:58:43.522 MPEGRec(/dev/video1): PauseAndWait pause
2852009-01-18 17:58:43.523 TVRec(6): ClearFlags(RingBufferReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,
2862009-01-18 17:58:43.525 TVRec(6): Request: Program(no) channel(71) input() flags(LiveTV,)
2872009-01-18 17:58:43.527 ChannelBase(6): Looking for startchannel '71' on input 'Component'
2882009-01-18 17:58:43.533 MPEGRec(/dev/video1): StopEncoding
2892009-01-18 17:58:43.533 ChannelBase(6): Found startchannel '71' on input 'Component'
2902009-01-18 17:58:43.536 TVRec(6): HW Tuner: 6->6
2912009-01-18 17:58:43.537 TVRec(6): ClearFlags(PENDINGACTIONS,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,
2922009-01-18 17:58:43.538 TVRec(6): Waiting for recorder pause..
2932009-01-18 17:58:43.539 TVRec(6): SetFlags(WaitingForRecPause,) -> FrontendReady,RunMainLoop,CancelNextRecording,WaitingForRecPause,RecorderRunning,
2942009-01-18 17:58:43.540 TVRec(6): ClearFlags(WaitingForRecPause,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,
2952009-01-18 17:58:43.542 TVRec(6): Recorder paused, calling TuningFrequency
2962009-01-18 17:58:43.543 Channel(/dev/video1)::SwitchToInput(in 6, '')
2972009-01-18 17:58:43.547 Channel(/dev/video1): SetInputAndFormat(6, NTSC) (v4l v2)
2982009-01-18 17:58:45.184 MPEGRec(/dev/video1): Encoding stopped
2992009-01-18 17:58:45.224 Channel(/dev/video1): SetChannelByString(71)
3002009-01-18 17:58:45.230 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(6)
3012009-01-18 17:58:45.231 External channel change: /usr/local/share/mythtv/changechannel /dev/ttyD3 71
3022009-01-18 17:58:45.235 Waiting for External Tuning program to exit
3032009-01-18 17:58:46.236 ret_pid(0) child(3031) status(0x0)
3042009-01-18 17:58:46.799 ret_pid(3031) child(3031) status(0x0)
3052009-01-18 17:58:46.800 External Tuning program no longer running
3062009-01-18 17:58:46.801 External Tuning program exited with no error
3072009-01-18 17:58:46.805 Channel(/dev/video1): InitPictureAttribute(brightness):  134
308                        [    0,  255] dflt(  134, 0.53, 1670)
3092009-01-18 17:58:46.825 Channel(/dev/video1): InitPictureAttribute(  contrast):  128
310                        [    0,  255] dflt(  128, 0.50, 128)
3112009-01-18 17:58:46.845 Channel(/dev/video1): InitPictureAttribute(    colour):  128
312                        [    0,  255] dflt(  128, 0.50, 128)
3132009-01-18 17:58:46.866 Channel(/dev/video1): InitPictureAttribute(       hue):  128
314                        [    0,  255] dflt(  128, 0.50, 128)
3152009-01-18 17:58:46.884 TVRec(6): ClearFlags(NeedToStartRecorder,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,
3162009-01-18 17:58:46.886 TVRec(6): SetFlags(NeedToStartRecorder,) -> FrontendReady,RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,
3172009-01-18 17:58:46.887 TVRec(6): Restarting Recorder
3182009-01-18 17:58:46.891 TVRec(6): FinishedRecording(Failure to Launch) in recgroup: LiveTV
3192009-01-18 17:58:46.893 Chain: Updated endtime for '1075_20090118175829' to 20090118175846
3202009-01-18 17:58:46.895 Finished recording Failure to Launch: channel 1075
3212009-01-18 17:58:46.899 scheduler: Finished recording: Failure to Launch: channel 1075
3222009-01-18 17:58:46.902 TVRec(6): SwitchLiveTVRingBuffer(discont 1, set_rec 1)
3232009-01-18 17:58:46.903 TVRec(6): GetProgramRingBufferForLiveTV()
3242009-01-18 17:58:47.947 TVRec(6): StartedRecording(0xb4fec0) fn(/video1/1071_20090118175846.mpg)
3252009-01-18 17:58:47.959 TVRec(6): FinishedRecording(Failure to Launch) in recgroup: LiveTV
3262009-01-18 17:58:47.960 Chain: Updated endtime for '1075_20090118175829' to 20090118175847
3272009-01-18 17:58:47.969 Finished recording Failure to Launch: channel 1075
3282009-01-18 17:58:47.973 Preview: 'myth://192.168.0.6:6543/1075_20090118175829.mpg' is not local,
329                        replacing with '/video1/1075_20090118175829.mpg', which is local.
3302009-01-18 17:58:47.979 Chain: Appended@1 '1071_20090118175846'
3312009-01-18 17:58:47.981 DTVRec(6): SetNextRecord(0xb4fec0, 0xa55b00)
3322009-01-18 17:58:48.046 mythbackend version: trunk [Unknown] www.mythtv.org
3332009-01-18 17:58:48.052 Using runtime prefix = /usr/local
3342009-01-18 17:58:48.053 Using localhost value of wambs-server
3352009-01-18 17:58:48.066 New DB connection, total: 1
3362009-01-18 17:58:48.073 Connected to database 'mythconverg' at host: localhost
3372009-01-18 17:58:48.074 Closing DB connection named 'DBManager0'
3382009-01-18 17:58:48.076 Connected to database 'mythconverg' at host: localhost
3392009-01-18 17:58:48.084 Current Schema Version: 1228
3402009-01-18 17:58:48.087 New DB connection, total: 2
3412009-01-18 17:58:48.089 Connected to database 'mythconverg' at host: localhost
3422009-01-18 17:58:48.106 DTVRec(6): ResetForNewFile(void)
3432009-01-18 17:58:48.108 RecBase(6:/dev/video1): SetRingBuffer(0xa55b00) '/video1/1071_20090118175846.mpg'
3442009-01-18 17:58:48.109 TVRec(6): RingBufferChanged()
3452009-01-18 17:58:48.114 TVRec(6): FinishedRecording(Failure to Launch) in recgroup: LiveTV
3462009-01-18 17:58:48.116 Chain: Updated endtime for '1075_20090118175829' to 20090118175848
3472009-01-18 17:58:48.118 Finished recording Failure to Launch: channel 1075
3482009-01-18 17:58:48.120 TVRec(6): SetFlags(RingBufferReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady,
3492009-01-18 17:58:48.121 MPEGRec(/dev/video1): Reset(void)
3502009-01-18 17:58:48.122 DTVRec(6): ResetForNewFile(void)
3512009-01-18 17:58:48.123 TVRec(6): ClearFlags(NeedToStartRecorder,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady,
3522009-01-18 17:58:48.124 TVRec(6): SetChannel(71) -- end
3532009-01-18 17:58:48.127 AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 15 min
3542009-01-18 17:58:48.184 [h264 @ 0x7f74f9ddf4e0]mmco: unref short failure
3552009-01-18 17:58:48.226 MPEGRec(/dev/video1): PauseAndWait unpause
3562009-01-18 17:58:48.227 MPEGRec(/dev/video1): Checking Resolution
3572009-01-18 17:58:48.375 MainServer::ANN Playback
3582009-01-18 17:58:48.376 adding: living-room as a client (events: 0)
3592009-01-18 17:58:48.379 MainServer::HandleAnnounce FileTransfer
3602009-01-18 17:58:48.380 adding: living-room as a remote file transfer
3612009-01-18 17:58:50.593 AFD: Opened codec 0x80fd20, id(H264) type(Video)
3622009-01-18 17:58:50.595 AFD: codec AC3 has 6 channels
3632009-01-18 17:58:50.597 AFD: Opened codec 0x80eee0, id(AC3) type(Audio)
3642009-01-18 17:58:50.702 [h264 @ 0x7f74f9ddf4e0]mmco: unref short failure
3652009-01-18 17:58:50.704 [h264 @ 0x7f74f9ddf4e0]B picture before any references, skipping
3662009-01-18 17:58:50.705 [h264 @ 0x7f74f9ddf4e0]decode_slice_header error
3672009-01-18 17:58:50.709 [h264 @ 0x7f74f9ddf4e0]no frame!
3682009-01-18 17:58:50.748 AFD Error: Unknown decoding error
3692009-01-18 17:58:50.749 [h264 @ 0x7f74f9ddf4e0]B picture before any references, skipping
3702009-01-18 17:58:50.751 [h264 @ 0x7f74f9ddf4e0]decode_slice_header error
3712009-01-18 17:58:50.752 [h264 @ 0x7f74f9ddf4e0]no frame!
3722009-01-18 17:58:50.753 AFD Error: Unknown decoding error
3732009-01-18 17:58:50.754 [h264 @ 0x7f74f9ddf4e0]B picture before any references, skipping
3742009-01-18 17:58:50.755 [h264 @ 0x7f74f9ddf4e0]decode_slice_header error
3752009-01-18 17:58:50.756 [h264 @ 0x7f74f9ddf4e0]no frame!
3762009-01-18 17:58:50.756 AFD Error: Unknown decoding error
3772009-01-18 17:58:50.772 [h264 @ 0x7f74f9ddf4e0]mmco: unref short failure
3782009-01-18 17:58:50.796 [h264 @ 0x7f74f9ddf4e0]mmco: unref short failure
3792009-01-18 17:58:50.797 [h264 @ 0x7f74f9ddf4e0]B picture before any references, skipping
3802009-01-18 17:58:50.798 [h264 @ 0x7f74f9ddf4e0]decode_slice_header error
3812009-01-18 17:58:50.799 [h264 @ 0x7f74f9ddf4e0]no frame!
3822009-01-18 17:58:50.800 AFD Error: Unknown decoding error
3832009-01-18 17:58:50.801 [h264 @ 0x7f74f9ddf4e0]B picture before any references, skipping
3842009-01-18 17:58:50.802 [h264 @ 0x7f74f9ddf4e0]decode_slice_header error
3852009-01-18 17:58:50.802 [h264 @ 0x7f74f9ddf4e0]no frame!
3862009-01-18 17:58:50.803 AFD Error: Unknown decoding error
3872009-01-18 17:58:50.804 [h264 @ 0x7f74f9ddf4e0]B picture before any references, skipping
3882009-01-18 17:58:50.805 [h264 @ 0x7f74f9ddf4e0]decode_slice_header error
3892009-01-18 17:58:50.806 [h264 @ 0x7f74f9ddf4e0]no frame!
3902009-01-18 17:58:50.807 AFD Error: Unknown decoding error
3912009-01-18 17:58:50.821 [h264 @ 0x7f74f9ddf4e0]mmco: unref short failure
3922009-01-18 17:58:51.185 Preview: Grabbed preview '/video1/1075_20090118175829.mpg' 1280x720@64s
3932009-01-18 17:58:52.672 MPEGRec(/dev/video1): Got Resolution 1280x720
3942009-01-18 17:58:52.675 MPEGRec(/dev/video1): StartEncoding
3952009-01-18 17:58:52.956 MPEGRec(/dev/video1): Encoding started
3962009-01-18 17:58:52.973 CreatePATSingleProgram()
3972009-01-18 17:58:52.974 PAT in input stream
3982009-01-18 17:58:52.975 Program Association Table
399 PSIP tableID(0x0) length(17) extension(0x0)
400      version(0) current(1) section(0) last_section(0)
401         tsid: 0
402 programCount: 2
403  program number     0 has PID 0x  1f   data  0x 0 0x 0 0xe0 0x1f
404  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
405
4062009-01-18 17:58:52.977 desired_program(1) pid(0x100)
4072009-01-18 17:58:52.978 pmt_pid(0x100)
4082009-01-18 17:58:52.979 PAT for output stream
4092009-01-18 17:58:52.980 Program Association Table
410 PSIP tableID(0x0) length(13) extension(0x0)
411      version(0) current(1) section(0) last_section(0)
412         tsid: 0
413 programCount: 1
414  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
415
4162009-01-18 17:58:52.982 CreatePMTSingleProgram()
4172009-01-18 17:58:52.983 PMT in input stream
4182009-01-18 17:58:52.984 Program Map Table ver(0) pid(0x100) pnum(1) len(59)
419  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
420  Unknown(136) Descriptor (0x88) length(4)
421
422 Stream #0 pid(0x1011) type(video-h264  0x1b)
423  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
424 Stream #1 pid(0x1100) type(audio-ac3  0x81)
425  Registration Descriptor: 'AC-3' ATSC audio stream A/52
426  Audio Stream Descriptor  full_srv(1) sample_rate(48kbps) bit_rate(=384kbps, 14)
427      bsid(6) bs_mode(0) channels(2/0) Dolby(Not Dolby surround encoded)
428      mainID(0)
429
4302009-01-18 17:58:52.985 PMT for output stream
4312009-01-18 17:58:52.987 Program Map Table ver(0) pid(0x100) pnum(1) len(59)
432  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
433  Unknown(136) Descriptor (0x88) length(4)
434
435 Stream #0 pid(0x1011) type(video-h264  0x1b)
436  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
437 Stream #1 pid(0x1100) type(audio-ac3  0x81)
438  Registration Descriptor: 'AC-3' ATSC audio stream A/52
439  Audio Stream Descriptor  full_srv(1) sample_rate(48kbps) bit_rate(=384kbps, 14)
440      bsid(6) bs_mode(0) channels(2/0) Dolby(Not Dolby surround encoded)
441      mainID(0)
442
4432009-01-18 17:58:53.122 TVRec(6): StopLiveTV(void) curRec: 0x98fdb0 pseudoRec: 0x0
4442009-01-18 17:58:53.127 TVRec(6): Changing from WatchingLiveTV to None
4452009-01-18 17:58:53.128 TVRec(6): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady,
4462009-01-18 17:58:53.129 TVRec(6): Request: Program(no) channel() input() flags(KillRec,KillRingBuffer,)
4472009-01-18 17:58:53.132 MPEGRec(/dev/video1): StartRecording finishing up
4482009-01-18 17:58:53.161 MPEGRec(/dev/video1): StopEncoding
4492009-01-18 17:58:54.886 RingBuf(/video1/1071_20090118175846.mpg): Invalid file (fd -1) when opening '/video1/1071_20090118175846.mpg'.
4502009-01-18 17:58:55.812 MPEGRec(/dev/video1): Encoding stopped
4512009-01-18 17:58:55.815 MPEGRec(/dev/video1): SetStreamData(0x0) -- begin
4522009-01-18 17:58:55.816 MPEGRec(/dev/video1): SetStreamData(0x0) -- end 1
4532009-01-18 17:58:55.818 TVRec(6): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady,
4542009-01-18 17:58:55.822 TVRec(6): FinishedRecording(Up Close & Personal) in recgroup: LiveTV
4552009-01-18 17:58:55.824 Chain: Updated endtime for '1071_20090118175846' to 20090118175855
4562009-01-18 17:58:55.833 Finished recording Up Close & Personal: channel 1071
4572009-01-18 17:58:55.839 scheduler: Last message repeated 2 times: Finished recording: Failure to Launch: channel 1075
4582009-01-18 17:58:55.842 scheduler: Finished recording: Up Close & Personal: channel 1071
4592009-01-18 17:58:55.844 TVRec(6): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady,
4602009-01-18 17:58:55.846 TVRec(6): Tearing down RingBuffer
4612009-01-18 17:58:55.848 TVRec(6): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady,
4622009-01-18 17:58:55.849 TVRec(6): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,RingBufferReady,
4632009-01-18 17:58:55.871 TVRec(6): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,RingBufferReady,
4642009-01-18 17:58:55.875 Getting next free recorder after : -1
4652009-01-18 17:58:55.877 Checking card 1. Best card so far 1
4662009-01-18 17:58:55.878 Checking card 2. Best card so far 2
4672009-01-18 17:58:55.879 Checking card 3. Best card so far 3
4682009-01-18 17:58:55.880 Checking card 4. Best card so far 4
4692009-01-18 17:58:55.881 Checking card 5. Best card so far 5
4702009-01-18 17:58:55.882 Checking card 6. Best card so far 6
4712009-01-18 17:58:55.910 MainServer::ANN Playback
4722009-01-18 17:58:55.911 adding: living-room as a client (events: 0)
4732009-01-18 17:58:55.913 TVRec(6): Changing from None to WatchingLiveTV
4742009-01-18 17:58:55.914 TVRec(6): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RingBufferReady,
4752009-01-18 17:58:55.915 TVRec(6): Request: Program(no) channel() input() flags(LiveTV,)
4762009-01-18 17:58:55.916 TVRec(6): Start channel: 71.
4772009-01-18 17:58:55.919 TVRec(6): HW Tuner: 6->6
4782009-01-18 17:58:55.920 TVRec(6): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady,
4792009-01-18 17:58:55.921 TVRec(6): No recorder yet, calling TuningFrequency
4802009-01-18 17:58:55.922 Channel(/dev/video1): Device name 'Haupauge HD PVR' driver 'hdpvr'.
4812009-01-18 17:58:56.000 ChannelBase(6): Input #6: 'Component' schan(71) sourceid(1) ccid(6)
4822009-01-18 17:58:56.001 ChannelBase(6): Current Input #6: 'Component'
4832009-01-18 17:58:56.002 Global TVFormat Setting 'NTSC'
4842009-01-18 17:58:56.003 Channel(/dev/video1): Input #6: 'Component' schan(71) tun() v4l1(NTSC) v4l2(NTSC)
4852009-01-18 17:58:56.004 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(6)
4862009-01-18 17:58:56.004 Channel(/dev/video1)::SwitchToInput(in 6, '')
4872009-01-18 17:58:56.007 Channel(/dev/video1): SetInputAndFormat(6, NTSC) (v4l v2)
4882009-01-18 17:58:56.043 Channel(/dev/video1): SetChannelByString(71)
4892009-01-18 17:58:56.047 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(6)
4902009-01-18 17:58:56.048 External channel change: /usr/local/share/mythtv/changechannel /dev/ttyD3 71
4912009-01-18 17:58:56.050 Waiting for External Tuning program to exit
4922009-01-18 17:58:57.051 ret_pid(0) child(3041) status(0x0)
4932009-01-18 17:58:57.607 ret_pid(3041) child(3041) status(0x0)
4942009-01-18 17:58:57.608 External Tuning program no longer running
4952009-01-18 17:58:57.609 External Tuning program exited with no error
4962009-01-18 17:58:57.612 Channel(/dev/video1): InitPictureAttribute(brightness):  134
497                        [    0,  255] dflt(  134, 0.53, 1670)
4982009-01-18 17:58:57.633 Channel(/dev/video1): InitPictureAttribute(  contrast):  128
499                        [    0,  255] dflt(  128, 0.50, 128)
5002009-01-18 17:58:57.654 Channel(/dev/video1): InitPictureAttribute(    colour):  128
501                        [    0,  255] dflt(  128, 0.50, 128)
5022009-01-18 17:58:57.673 Channel(/dev/video1): InitPictureAttribute(       hue):  128
503                        [    0,  255] dflt(  128, 0.50, 128)
5042009-01-18 17:58:57.693 TVRec(6): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,RingBufferReady,
5052009-01-18 17:58:57.694 TVRec(6): SetFlags(NeedToStartRecorder,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady,
5062009-01-18 17:58:57.695 TVRec(6): Starting Recorder
5072009-01-18 17:58:57.712 Using profile 'Live TV' to record
5082009-01-18 17:58:57.713 TVRec(6): CreateLiveTVRingBuffer()
5092009-01-18 17:58:57.714 TVRec(6): GetProgramRingBufferForLiveTV()
5102009-01-18 17:58:58.747 TVRec(6): StartedRecording(0x7f09580633d0) fn(/video1/1071_20090118175857.mpg)
5112009-01-18 17:58:58.755 Chain: Appended@0 '1071_20090118175857'
5122009-01-18 17:58:58.758 TVRec(6): SetFlags(RingBufferReady,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady,
5132009-01-18 17:58:58.763 RecBase(6:/dev/video1): SetRingBuffer(0x7f09580c1fa0) '/video1/1071_20090118175857.mpg'
5142009-01-18 17:58:58.764 RecBase(6:/dev/video1): SetRecording(0x7f095807d3e0) title(Up Close & Personal)
5152009-01-18 17:58:58.767 Channel(/dev/video1): InitPictureAttribute(brightness):  134
516                        [    0,  255] dflt(  134, 0.53, 1670)
5172009-01-18 17:58:58.789 Channel(/dev/video1): InitPictureAttribute(  contrast):  128
518                        [    0,  255] dflt(  128, 0.50, 128)
5192009-01-18 17:58:58.810 Channel(/dev/video1): InitPictureAttribute(    colour):  128
520                        [    0,  255] dflt(  128, 0.50, 128)
5212009-01-18 17:58:58.830 Channel(/dev/video1): InitPictureAttribute(       hue):  128
522                        [    0,  255] dflt(  128, 0.50, 128)
5232009-01-18 17:58:58.849 TVRec(6): SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RingBufferReady,
5242009-01-18 17:58:58.849 MPEGRec(/dev/video1): usingv4l2(1) has_v4l2_vbi(0) has_buggy_vbi(1)
5252009-01-18 17:58:58.854 AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 15 min
5262009-01-18 17:58:58.862 MainServer::ANN Playback
5272009-01-18 17:58:58.864 adding: living-room as a client (events: 0)
5282009-01-18 17:58:58.866 MainServer::HandleAnnounce FileTransfer
5292009-01-18 17:58:58.867 adding: living-room as a remote file transfer
5302009-01-18 17:58:58.881 DevRdB(/dev/video1): buffer size 9400 KB
5312009-01-18 17:58:58.882 MPEGRec(/dev/video1): DRB ready
5322009-01-18 17:58:58.884 MPEGRec(/dev/video1): SetStreamData(0x7f09580af100) -- begin
5332009-01-18 17:58:58.885 MPEGRec(/dev/video1): SetStreamData(0x7f09580af100) -- end 1
5342009-01-18 17:58:58.886 MPEGRec(/dev/video1): HandleSingleProgramPAT(NULL)
5352009-01-18 17:58:58.887 MPEGRec(/dev/video1): Initial startup of recorder
5362009-01-18 17:58:58.888 MPEGRec(/dev/video1): Checking Resolution
5372009-01-18 17:58:58.889 TVRec(6): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady,
5382009-01-18 17:58:58.891 TVRec(6): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady,
5392009-01-18 17:59:01.284 Reschedule requested for id 0.
5402009-01-18 17:59:01.719 Scheduled 198 items in 0.4 = 0.00 match + 0.43 place
5412009-01-18 17:59:01.724 scheduler: Scheduled items: Scheduled 198 items in 0.4 = 0.00 match + 0.43 place
5422009-01-18 17:59:03.345 MPEGRec(/dev/video1): Got Resolution 1280x720
5432009-01-18 17:59:03.346 MPEGRec(/dev/video1): Old bitrate 13500/20200 VBR
5442009-01-18 17:59:03.348 MPEGRec(/dev/video1): New bitrate 9000/11000 kbps VBR
5452009-01-18 17:59:03.357 MPEGRec(/dev/video1): StartEncoding
5462009-01-18 17:59:03.664 MPEGRec(/dev/video1): Encoding started
5472009-01-18 17:59:03.693 CreatePATSingleProgram()
5482009-01-18 17:59:03.714 PAT in input stream
5492009-01-18 17:59:03.733 Program Association Table
550 PSIP tableID(0x0) length(17) extension(0x0)
551      version(0) current(1) section(0) last_section(0)
552         tsid: 0
553 programCount: 2
554  program number     0 has PID 0x  1f   data  0x 0 0x 0 0xe0 0x1f
555  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
556
5572009-01-18 17:59:03.734 desired_program(1) pid(0x100)
5582009-01-18 17:59:03.736 pmt_pid(0x100)
5592009-01-18 17:59:03.737 PAT for output stream
5602009-01-18 17:59:03.738 Program Association Table
561 PSIP tableID(0x0) length(13) extension(0x0)
562      version(0) current(1) section(0) last_section(0)
563         tsid: 0
564 programCount: 1
565  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
566
5672009-01-18 17:59:03.739 CreatePMTSingleProgram()
5682009-01-18 17:59:03.741 PMT in input stream
5692009-01-18 17:59:03.742 Program Map Table ver(0) pid(0x100) pnum(1) len(59)
570  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
571  Unknown(136) Descriptor (0x88) length(4)
572
573 Stream #0 pid(0x1011) type(video-h264  0x1b)
574  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
575 Stream #1 pid(0x1100) type(audio-ac3  0x81)
576  Registration Descriptor: 'AC-3' ATSC audio stream A/52
577  Audio Stream Descriptor  full_srv(0) sample_rate(48kbps) bit_rate(=192kbps, 10)
578      bsid(8) bs_mode(0) channels(2/2 ) Dolby(Not indicated)
579      mainID(0)
580
5812009-01-18 17:59:03.743 PMT for output stream
5822009-01-18 17:59:03.744 Program Map Table ver(0) pid(0x100) pnum(1) len(59)
583  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
584  Unknown(136) Descriptor (0x88) length(4)
585
586 Stream #0 pid(0x1011) type(video-h264  0x1b)
587  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
588 Stream #1 pid(0x1100) type(audio-ac3  0x81)
589  Registration Descriptor: 'AC-3' ATSC audio stream A/52
590  Audio Stream Descriptor  full_srv(0) sample_rate(48kbps) bit_rate(=192kbps, 10)
591      bsid(8) bs_mode(0) channels(2/2 ) Dolby(Not indicated)
592      mainID(0)
5932009-01-18 17:59:04.179 PID 0x1011 Found Payload Start
5942009-01-18 17:59:04.188 TVRec(6): StopLiveTV(void) curRec: 0x7f09580633d0 pseudoRec: 0x0
5952009-01-18 17:59:04.192 PID 0x1100 Found Payload Start
5962009-01-18 17:59:04.193 TVRec(6): Changing from WatchingLiveTV to None
5972009-01-18 17:59:04.194 TVRec(6): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady,
5982009-01-18 17:59:04.196 TVRec(6): Request: Program(no) channel() input() flags(KillRec,KillRingBuffer,)
5992009-01-18 17:59:04.229 MPEGRec(/dev/video1): StartRecording finishing up
6002009-01-18 17:59:04.254 MPEGRec(/dev/video1): StopEncoding
6012009-01-18 17:59:06.920 MPEGRec(/dev/video1): Encoding stopped
6022009-01-18 17:59:06.950 MPEGRec(/dev/video1): SetStreamData(0x0) -- begin
6032009-01-18 17:59:06.951 MPEGRec(/dev/video1): SetStreamData(0x0) -- end 1
6042009-01-18 17:59:06.953 TVRec(6): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady,
6052009-01-18 17:59:06.957 TVRec(6): FinishedRecording(Up Close & Personal) in recgroup: LiveTV
6062009-01-18 17:59:06.958 Chain: Updated endtime for '1071_20090118175857' to 20090118175906
6072009-01-18 17:59:06.961 Finished recording Up Close & Personal: channel 1071
6082009-01-18 17:59:06.964 scheduler: Finished recording: Up Close & Personal: channel 1071
6092009-01-18 17:59:06.967 TVRec(6): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady,
6102009-01-18 17:59:06.968 TVRec(6): Tearing down RingBuffer
6112009-01-18 17:59:06.969 TVRec(6): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady,
6122009-01-18 17:59:30.745 TVRec(5): RecordPending on inputid 5
6132009-01-18 17:59:30.830 TVRec(5): ASK_RECORDING 5 30 0 0
6142009-01-18 18:00:02.770 TVRec(5): StartRecording(Dirty Jobs)
6152009-01-18 18:00:02.823 TVRec(5): StartedRecording(0xb5edc0) fn(/video1/3219_20090118180000.mpg)
6162009-01-18 18:00:02.827 TVRec(5): ClearFlags(CancelNextRecording,) -> RunMainLoop,
6172009-01-18 18:00:02.828 TVRec(5): Changing from None to RecordingOnly
6182009-01-18 18:00:02.830 TVRec(5): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,
6192009-01-18 18:00:02.831 TVRec(5): Request: Program(yes) channel() input() flags(Recording,)
6202009-01-18 18:00:02.834 TVRec(5): HW Tuner: 5->5
6212009-01-18 18:00:02.835 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,
6222009-01-18 18:00:02.836 TVRec(5): No recorder yet, calling TuningFrequency
6232009-01-18 18:00:02.838 Channel(/dev/video0): Device name 'Haupauge HD PVR' driver 'hdpvr'.
6242009-01-18 18:00:02.957 ChannelBase(5): Input #5: 'Component' schan(231) sourceid(1) ccid(5)
6252009-01-18 18:00:02.958 ChannelBase(5): Current Input #5: 'Component'
6262009-01-18 18:00:02.959 Global TVFormat Setting 'NTSC'
6272009-01-18 18:00:02.960 Channel(/dev/video0): Input #5: 'Component' schan(231) tun() v4l1(NTSC) v4l2(NTSC)
6282009-01-18 18:00:02.961 Channel(/dev/video0): SetFormat(Default) fmt(NTSC) input(5)
6292009-01-18 18:00:02.962 Channel(/dev/video0)::SwitchToInput(in 5, '')
6302009-01-18 18:00:02.965 Channel(/dev/video0): SetInputAndFormat(5, NTSC) (v4l v2)
6312009-01-18 18:00:03.004 Channel(/dev/video0): SetChannelByString(278)
6322009-01-18 18:00:03.007 Channel(/dev/video0): SetFormat(Default) fmt(NTSC) input(5)
6332009-01-18 18:00:03.008 External channel change: /usr/local/share/mythtv/changechannel /dev/ttyD0 278
6342009-01-18 18:00:03.011 Waiting for External Tuning program to exit
6352009-01-18 18:00:04.013 ret_pid(0) child(3072) status(0x0)
6362009-01-18 18:00:04.863 ret_pid(3072) child(3072) status(0x0)
6372009-01-18 18:00:04.865 External Tuning program no longer running
6382009-01-18 18:00:04.866 External Tuning program exited with no error
6392009-01-18 18:00:04.869 Channel(/dev/video0): InitPictureAttribute(brightness):  134
640                        [    0,  255] dflt(  134, 0.53, 1670)
6412009-01-18 18:00:04.889 Channel(/dev/video0): InitPictureAttribute(  contrast):  128
642                        [    0,  255] dflt(  128, 0.50, 128)
6432009-01-18 18:00:04.909 Channel(/dev/video0): InitPictureAttribute(    colour):  128
644                        [    0,  255] dflt(  128, 0.50, 128)
6452009-01-18 18:00:04.929 Channel(/dev/video0): InitPictureAttribute(       hue):  128
646                        [    0,  255] dflt(  128, 0.50, 128)
6472009-01-18 18:00:04.948 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,
6482009-01-18 18:00:04.949 TVRec(5): SetFlags(NeedToStartRecorder,) -> RunMainLoop,NeedToStartRecorder,
6492009-01-18 18:00:04.950 TVRec(5): Starting Recorder
6502009-01-18 18:00:04.954 New DB connection, total: 4
6512009-01-18 18:00:04.957 Connected to database 'mythconverg' at host: localhost
6522009-01-18 18:00:04.996 Using profile 'Default' to record
6532009-01-18 18:00:04.999 RecBase(5:/dev/video0): SetRingBuffer(0x7f0958d10960) '/video1/3219_20090118180000.mpg'
6542009-01-18 18:00:05.000 RecBase(5:/dev/video0): SetRecording(0xb5edc0) title(Dirty Jobs)
6552009-01-18 18:00:05.004 Channel(/dev/video0): InitPictureAttribute(brightness):  134
656                        [    0,  255] dflt(  134, 0.53, 1670)
6572009-01-18 18:00:05.025 Channel(/dev/video0): InitPictureAttribute(  contrast):  128
658                        [    0,  255] dflt(  128, 0.50, 128)
6592009-01-18 18:00:05.046 Channel(/dev/video0): InitPictureAttribute(    colour):  128
660                        [    0,  255] dflt(  128, 0.50, 128)
6612009-01-18 18:00:05.066 Channel(/dev/video0): InitPictureAttribute(       hue):  128
662                        [    0,  255] dflt(  128, 0.50, 128)
6632009-01-18 18:00:05.084 MPEGRec(/dev/video0): usingv4l2(1) has_v4l2_vbi(0) has_buggy_vbi(1)
6642009-01-18 18:00:05.094 AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 15 min
6652009-01-18 18:00:05.097 Started recording: Dirty Jobs "Vellum Maker": channel 3219 on cardid 5, sourceid 1
6662009-01-18 18:00:05.101 scheduler: Started recording: Dirty Jobs "Vellum Maker": channel 3219 on cardid 5, sourceid 1
6672009-01-18 18:00:05.121 DevRdB(/dev/video0): buffer size 9400 KB
6682009-01-18 18:00:05.122 MPEGRec(/dev/video0): DRB ready
6692009-01-18 18:00:05.124 MPEGRec(/dev/video0): SetStreamData(0x801500) -- begin
6702009-01-18 18:00:05.125 MPEGRec(/dev/video0): SetStreamData(0x801500) -- end 1
6712009-01-18 18:00:05.126 MPEGRec(/dev/video0): HandleSingleProgramPAT(NULL)
6722009-01-18 18:00:05.127 MPEGRec(/dev/video0): Initial startup of recorder
6732009-01-18 18:00:05.128 MPEGRec(/dev/video0): Checking Resolution
6742009-01-18 18:00:05.130 TVRec(5): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,NeedToStartRecorder,RecorderRunning,RingBufferReady,
6752009-01-18 18:00:05.132 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,RecorderRunning,RingBufferReady,
6762009-01-18 18:00:12.733 MPEGRec(/dev/video0): Got Resolution 1280x720
6772009-01-18 18:00:12.735 MPEGRec(/dev/video0): Old bitrate 13500/20200 VBR
6782009-01-18 18:00:12.737 MPEGRec(/dev/video0): New bitrate 9000/11000 kbps VBR
6792009-01-18 18:00:12.747 MPEGRec(/dev/video0): StartEncoding
6802009-01-18 18:00:13.072 MPEGRec(/dev/video0): Encoding started
6812009-01-18 18:00:13.084 CreatePATSingleProgram()
6822009-01-18 18:00:13.085 PAT in input stream
6832009-01-18 18:00:13.086 Program Association Table
684 PSIP tableID(0x0) length(17) extension(0x0)
685      version(0) current(1) section(0) last_section(0)
686         tsid: 0
687 programCount: 2
688  program number     0 has PID 0x  1f   data  0x 0 0x 0 0xe0 0x1f
689  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
690
6912009-01-18 18:00:13.088 desired_program(1) pid(0x100)
6922009-01-18 18:00:13.089 pmt_pid(0x100)
6932009-01-18 18:00:13.090 PAT for output stream
6942009-01-18 18:00:13.091 Program Association Table
695 PSIP tableID(0x0) length(13) extension(0x0)
696      version(0) current(1) section(0) last_section(0)
697         tsid: 0
698 programCount: 1
699  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
700
7012009-01-18 18:00:13.092 CreatePMTSingleProgram()
7022009-01-18 18:00:13.093 PMT in input stream
7032009-01-18 18:00:13.095 Program Map Table ver(0) pid(0x100) pnum(1) len(59)
704  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
705  Unknown(136) Descriptor (0x88) length(4)
706
707 Stream #0 pid(0x1011) type(video-h264  0x1b)
708  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
709 Stream #1 pid(0x1100) type(audio-ac3  0x81)
710  Registration Descriptor: 'AC-3' ATSC audio stream A/52
711  Audio Stream Descriptor  full_srv(0) sample_rate(48kbps) bit_rate(=192kbps, 10)
712      bsid(8) bs_mode(0) channels(2/2 ) Dolby(Not indicated)
713      mainID(0)
714
7152009-01-18 18:00:13.096 PMT for output stream
7162009-01-18 18:00:13.097 Program Map Table ver(0) pid(0x100) pnum(1) len(59)
717  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
718  Unknown(136) Descriptor (0x88) length(4)
719
720 Stream #0 pid(0x1011) type(video-h264  0x1b)
721  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
722 Stream #1 pid(0x1100) type(audio-ac3  0x81)
723  Registration Descriptor: 'AC-3' ATSC audio stream A/52
724  Audio Stream Descriptor  full_srv(0) sample_rate(48kbps) bit_rate(=192kbps, 10)
725      bsid(8) bs_mode(0) channels(2/2 ) Dolby(Not indicated)
726      mainID(0)
727
728
729
730