2009-01-18 17:56:54.782 mythbackend version: trunk [Unknown] www.mythtv.org 2009-01-18 17:56:54.788 Using runtime prefix = /usr/local 2009-01-18 17:56:54.789 Using localhost value of wambs-server 2009-01-18 17:56:54.797 New DB connection, total: 1 2009-01-18 17:56:54.802 Connected to database 'mythconverg' at host: localhost 2009-01-18 17:56:54.803 Closing DB connection named 'DBManager0' 2009-01-18 17:56:54.805 Connected to database 'mythconverg' at host: localhost 2009-01-18 17:56:54.809 Current Schema Version: 1228 2009-01-18 17:56:54.811 MythBackend: Starting up as the master server. 2009-01-18 17:56:54.814 New DB connection, total: 2 2009-01-18 17:56:54.816 Connected to database 'mythconverg' at host: localhost 2009-01-18 17:56:54.817 mythbackend: MythBackend started as master server 2009-01-18 17:56:54.824 TVRec(1): Start channel: 4_1. 2009-01-18 17:56:54.828 DVBChan(1:/dev/dvb/adapter0/frontend0): Opening DVB channel 2009-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'. 2009-01-18 17:56:54.830 New DB connection, total: 3 2009-01-18 17:56:54.832 Connected to database 'mythconverg' at host: localhost 2009-01-18 17:56:54.835 ChannelBase(1): Input #1: 'DVBInput' schan(4_1) sourceid(3) ccid(1) 2009-01-18 17:56:54.836 ChannelBase(1): Current Input #1: 'DVBInput' 2009-01-18 17:56:54.837 DVBChan(1:/dev/dvb/adapter0/frontend0): SetChannelByString(4_1): 2009-01-18 17:56:54.854 DVBChan(1:/dev/dvb/adapter0/frontend0): 599000000 8vsb a auto auto a a auto a v 2009-01-18 17:56:54.856 DVBChan(1:/dev/dvb/adapter0/frontend0): Old Params: 0 auto a auto auto a a auto a v DVBChan(1:/dev/dvb/adapter0/frontend0): New Params: 599000000 8vsb a auto auto a a auto a v 2009-01-18 17:56:54.857 DVBChan(1:/dev/dvb/adapter0/frontend0): Tune(): Tuning to 599000000Hz 2009-01-18 17:56:54.861 dvbchannel.cpp:wait_for_backend: Status: 2009-01-18 17:56:54.862 DVBChan(1:/dev/dvb/adapter0/frontend0): Tune(): Frequency tuning successful. 2009-01-18 17:56:54.863 DVBChan(1:/dev/dvb/adapter0/frontend0): SetChannelByString(4_1): Tuned to frequency. 2009-01-18 17:56:54.870 TVRec(1): SetFlags(RunMainLoop,) -> RunMainLoop, 2009-01-18 17:56:54.871 TVRec(1): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2009-01-18 17:56:54.878 TVRec(2): Start channel: 2_1. 2009-01-18 17:56:54.879 DVBChan(2:/dev/dvb/adapter0/frontend0): Opening DVB channel 2009-01-18 17:56:54.880 DVBChan(1:/dev/dvb/adapter0/frontend0): Opening DVB channel 2009-01-18 17:56:54.883 ChannelBase(2): Input #2: 'DVBInput' schan(2_1) sourceid(3) ccid(2) 2009-01-18 17:56:54.884 ChannelBase(2): Current Input #2: 'DVBInput' 2009-01-18 17:56:54.885 DVBChan(2:/dev/dvb/adapter0/frontend0): SetChannelByString(2_1): 2009-01-18 17:56:54.888 DVBChan(2:/dev/dvb/adapter0/frontend0): 647000000 8vsb a auto auto a a auto a v 2009-01-18 17:56:54.889 DVBChan(2:/dev/dvb/adapter0/frontend0): tuning on slave channel 2009-01-18 17:56:54.890 DVBChan(1:/dev/dvb/adapter0/frontend0): Old Params: 599000000 8vsb a auto auto a a auto a v DVBChan(1:/dev/dvb/adapter0/frontend0): New Params: 647000000 8vsb a auto auto a a auto a v 2009-01-18 17:56:54.891 DVBChan(1:/dev/dvb/adapter0/frontend0): Tune(): Tuning to 647000000Hz 2009-01-18 17:56:54.896 dvbchannel.cpp:wait_for_backend: Status: 2009-01-18 17:56:54.897 DVBChan(1:/dev/dvb/adapter0/frontend0): Tune(): Frequency tuning successful. 2009-01-18 17:56:54.898 DVBChan(2:/dev/dvb/adapter0/frontend0): SetChannelByString(2_1): Tuned to frequency. 2009-01-18 17:56:54.899 TVRec(2): SetFlags(RunMainLoop,) -> RunMainLoop, 2009-01-18 17:56:54.901 TVRec(2): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2009-01-18 17:56:54.903 TVRec(3): Start channel: 13_1. 2009-01-18 17:56:54.906 DVBChan(3:/dev/dvb/adapter1/frontend0): Opening DVB channel 2009-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'. 2009-01-18 17:56:54.909 ChannelBase(3): Input #3: 'DVBInput' schan(13_1) sourceid(3) ccid(3) 2009-01-18 17:56:54.910 ChannelBase(3): Current Input #3: 'DVBInput' 2009-01-18 17:56:54.911 DVBChan(3:/dev/dvb/adapter1/frontend0): SetChannelByString(13_1): 2009-01-18 17:56:54.914 DVBChan(3:/dev/dvb/adapter1/frontend0): 473000000 8vsb a auto auto a a auto a v 2009-01-18 17:56:54.915 DVBChan(3:/dev/dvb/adapter1/frontend0): Old Params: 0 auto a auto auto a a auto a v DVBChan(3:/dev/dvb/adapter1/frontend0): New Params: 473000000 8vsb a auto auto a a auto a v 2009-01-18 17:56:54.916 DVBChan(3:/dev/dvb/adapter1/frontend0): Tune(): Tuning to 473000000Hz 2009-01-18 17:56:54.921 dvbchannel.cpp:wait_for_backend: Status: 2009-01-18 17:56:54.922 DVBChan(3:/dev/dvb/adapter1/frontend0): Tune(): Frequency tuning successful. 2009-01-18 17:56:54.923 DVBChan(3:/dev/dvb/adapter1/frontend0): SetChannelByString(13_1): Tuned to frequency. 2009-01-18 17:56:54.924 TVRec(3): SetFlags(RunMainLoop,) -> RunMainLoop, 2009-01-18 17:56:54.926 TVRec(3): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2009-01-18 17:56:54.928 TVRec(4): Start channel: 8_3. 2009-01-18 17:56:54.929 DVBChan(4:/dev/dvb/adapter1/frontend0): Opening DVB channel 2009-01-18 17:56:54.930 DVBChan(3:/dev/dvb/adapter1/frontend0): Opening DVB channel 2009-01-18 17:56:54.933 ChannelBase(4): Input #4: 'DVBInput' schan(8_3) sourceid(3) ccid(4) 2009-01-18 17:56:54.934 ChannelBase(4): Current Input #4: 'DVBInput' 2009-01-18 17:56:54.935 DVBChan(4:/dev/dvb/adapter1/frontend0): SetChannelByString(8_3): 2009-01-18 17:56:54.938 DVBChan(4:/dev/dvb/adapter1/frontend0): 189000000 8vsb a auto auto a a auto a v 2009-01-18 17:56:54.939 DVBChan(4:/dev/dvb/adapter1/frontend0): tuning on slave channel 2009-01-18 17:56:54.940 DVBChan(3:/dev/dvb/adapter1/frontend0): Old Params: 473000000 8vsb a auto auto a a auto a v DVBChan(3:/dev/dvb/adapter1/frontend0): New Params: 189000000 8vsb a auto auto a a auto a v 2009-01-18 17:56:54.941 DVBChan(3:/dev/dvb/adapter1/frontend0): Tune(): Tuning to 189000000Hz 2009-01-18 17:56:54.942 dvbchannel.cpp:wait_for_backend: Status: 2009-01-18 17:56:54.943 DVBChan(3:/dev/dvb/adapter1/frontend0): Tune(): Frequency tuning successful. 2009-01-18 17:56:54.943 DVBChan(4:/dev/dvb/adapter1/frontend0): SetChannelByString(8_3): Tuned to frequency. 2009-01-18 17:56:54.945 TVRec(4): SetFlags(RunMainLoop,) -> RunMainLoop, 2009-01-18 17:56:54.946 TVRec(4): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2009-01-18 17:56:54.948 TVRec(5): Start channel: 231. 2009-01-18 17:56:54.949 Channel(/dev/video0): Device name 'Haupauge HD PVR' driver 'hdpvr'. 2009-01-18 17:56:55.022 ChannelBase(5): Input #5: 'Component' schan(231) sourceid(1) ccid(5) 2009-01-18 17:56:55.023 ChannelBase(5): Current Input #5: 'Component' 2009-01-18 17:56:55.025 Global TVFormat Setting 'NTSC' 2009-01-18 17:56:55.026 Channel(/dev/video0): Input #5: 'Component' schan(231) tun() v4l1(NTSC) v4l2(NTSC) 2009-01-18 17:56:55.027 Channel(/dev/video0): SetFormat(Default) fmt(NTSC) input(5) 2009-01-18 17:56:55.028 Channel(/dev/video0): SetInputAndFormat(5, NTSC) (v4l v2) 2009-01-18 17:56:55.069 Channel(/dev/video0): SetFormat(NTSC) fmt(NTSC) input(5) 2009-01-18 17:56:55.071 Channel(/dev/video0)::SwitchToInput(in 5, '') 2009-01-18 17:56:55.072 Channel(/dev/video0): SetInputAndFormat(5, NTSC) (v4l v2) 2009-01-18 17:56:55.112 Channel(/dev/video0): SetChannelByString(231) 2009-01-18 17:56:55.115 Channel(/dev/video0): SetFormat(Default) fmt(NTSC) input(5) 2009-01-18 17:56:55.116 External channel change: /usr/local/share/mythtv/changechannel /dev/ttyD0 231 2009-01-18 17:56:55.117 Waiting for External Tuning program to exit 2009-01-18 17:56:56.119 ret_pid(0) child(2995) status(0x0) 2009-01-18 17:56:56.735 ret_pid(2995) child(2995) status(0x0) 2009-01-18 17:56:56.736 External Tuning program no longer running 2009-01-18 17:56:56.738 External Tuning program exited with no error 2009-01-18 17:56:56.741 Channel(/dev/video0): InitPictureAttribute(brightness): 134 [ 0, 255] dflt( 134, 0.53, 1670) 2009-01-18 17:56:56.761 Channel(/dev/video0): InitPictureAttribute( contrast): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:56:56.782 Channel(/dev/video0): InitPictureAttribute( colour): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:56:56.802 Channel(/dev/video0): InitPictureAttribute( hue): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:56:56.822 TVRec(5): SetFlags(RunMainLoop,) -> RunMainLoop, 2009-01-18 17:56:56.823 TVRec(5): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2009-01-18 17:56:56.826 TVRec(6): Start channel: 75. 2009-01-18 17:56:56.827 Channel(/dev/video1): Device name 'Haupauge HD PVR' driver 'hdpvr'. 2009-01-18 17:56:56.951 ChannelBase(6): Input #6: 'Component' schan(75) sourceid(1) ccid(6) 2009-01-18 17:56:56.953 ChannelBase(6): Current Input #6: 'Component' 2009-01-18 17:56:56.954 Global TVFormat Setting 'NTSC' 2009-01-18 17:56:56.955 Channel(/dev/video1): Input #6: 'Component' schan(75) tun() v4l1(NTSC) v4l2(NTSC) 2009-01-18 17:56:56.956 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(6) 2009-01-18 17:56:56.957 Channel(/dev/video1): SetInputAndFormat(6, NTSC) (v4l v2) 2009-01-18 17:56:56.996 Channel(/dev/video1): SetFormat(NTSC) fmt(NTSC) input(6) 2009-01-18 17:56:56.998 Channel(/dev/video1)::SwitchToInput(in 6, '') 2009-01-18 17:56:56.999 Channel(/dev/video1): SetInputAndFormat(6, NTSC) (v4l v2) 2009-01-18 17:56:57.040 Channel(/dev/video1): SetChannelByString(75) 2009-01-18 17:56:57.044 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(6) 2009-01-18 17:56:57.045 External channel change: /usr/local/share/mythtv/changechannel /dev/ttyD3 75 2009-01-18 17:56:57.047 Waiting for External Tuning program to exit 2009-01-18 17:56:58.049 ret_pid(0) child(2998) status(0x0) 2009-01-18 17:56:58.587 ret_pid(2998) child(2998) status(0x0) 2009-01-18 17:56:58.589 External Tuning program no longer running 2009-01-18 17:56:58.590 External Tuning program exited with no error 2009-01-18 17:56:58.593 Channel(/dev/video1): InitPictureAttribute(brightness): 134 [ 0, 255] dflt( 134, 0.53, 1670) 2009-01-18 17:56:58.613 Channel(/dev/video1): InitPictureAttribute( contrast): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:56:58.634 Channel(/dev/video1): InitPictureAttribute( colour): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:56:58.654 Channel(/dev/video1): InitPictureAttribute( hue): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:56:58.674 TVRec(6): SetFlags(RunMainLoop,) -> RunMainLoop, 2009-01-18 17:56:58.676 TVRec(6): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2009-01-18 17:56:58.678 New DB scheduler connection 2009-01-18 17:56:58.680 Connected to database 'mythconverg' at host: localhost 2009-01-18 17:56:58.723 Enabling Upnpmedia rebuild thread. 2009-01-18 17:56:59.608 Main::Registering HttpStatus Extension 2009-01-18 17:56:59.610 Enabled verbose msgs: important general record channel 2009-01-18 17:56:59.615 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2009-01-18 17:57:01.686 Reschedule requested for id -1. 2009-01-18 17:57:02.263 Scheduled 198 items in 0.6 = 0.13 match + 0.44 place 2009-01-18 17:57:02.270 scheduler: Scheduled items: Scheduled 198 items in 0.6 = 0.13 match + 0.44 place 2009-01-18 17:57:02.274 AUTO-Startup assumed 2009-01-18 17:57:04.836 mythbackend: Running housekeeping thread 2009-01-18 17:57:08.728 UPnpMedia: BuildMediaMap VIDEO scan starting in :/share/Movies/dvd: 2009-01-18 17:57:08.731 UPnpMedia: BuildMediaMap Done. Found 0 objects 2009-01-18 17:58:18.686 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2009-01-18 17:58:27.266 MainServer::ANN Monitor 2009-01-18 17:58:27.267 adding: living-room as a client (events: 0) 2009-01-18 17:58:27.269 MainServer::ANN Monitor 2009-01-18 17:58:27.271 adding: living-room as a client (events: 1) 2009-01-18 17:58:27.273 Getting next free recorder after : -1 2009-01-18 17:58:27.275 Checking card 1. Best card so far 1 2009-01-18 17:58:27.276 Checking card 2. Best card so far 2 2009-01-18 17:58:27.278 Checking card 3. Best card so far 3 2009-01-18 17:58:27.279 Checking card 4. Best card so far 4 2009-01-18 17:58:27.280 Checking card 5. Best card so far 5 2009-01-18 17:58:27.281 Checking card 6. Best card so far 6 2009-01-18 17:58:27.310 MainServer::ANN Playback 2009-01-18 17:58:27.312 adding: living-room as a client (events: 0) 2009-01-18 17:58:27.315 TVRec(6): Changing from None to WatchingLiveTV 2009-01-18 17:58:27.316 TVRec(6): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, 2009-01-18 17:58:27.317 TVRec(6): Request: Program(no) channel() input() flags(LiveTV,) 2009-01-18 17:58:27.319 TVRec(6): Start channel: 75. 2009-01-18 17:58:27.324 TVRec(6): HW Tuner: 6->6 2009-01-18 17:58:27.326 TVRec(6): ClearFlags(PENDINGACTIONS,) -> RunMainLoop, 2009-01-18 17:58:27.327 TVRec(6): No recorder yet, calling TuningFrequency 2009-01-18 17:58:27.329 Channel(/dev/video1): Device name 'Haupauge HD PVR' driver 'hdpvr'. 2009-01-18 17:58:27.440 ChannelBase(6): Input #6: 'Component' schan(75) sourceid(1) ccid(6) 2009-01-18 17:58:27.442 ChannelBase(6): Current Input #6: 'Component' 2009-01-18 17:58:27.443 Global TVFormat Setting 'NTSC' 2009-01-18 17:58:27.443 Channel(/dev/video1): Input #6: 'Component' schan(75) tun() v4l1(NTSC) v4l2(NTSC) 2009-01-18 17:58:27.444 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(6) 2009-01-18 17:58:27.445 Channel(/dev/video1)::SwitchToInput(in 6, '') 2009-01-18 17:58:27.448 Channel(/dev/video1): SetInputAndFormat(6, NTSC) (v4l v2) 2009-01-18 17:58:27.484 Channel(/dev/video1): SetChannelByString(75) 2009-01-18 17:58:27.488 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(6) 2009-01-18 17:58:27.489 External channel change: /usr/local/share/mythtv/changechannel /dev/ttyD3 75 2009-01-18 17:58:27.491 Waiting for External Tuning program to exit 2009-01-18 17:58:28.492 ret_pid(0) child(3024) status(0x0) 2009-01-18 17:58:29.051 ret_pid(3024) child(3024) status(0x0) 2009-01-18 17:58:29.052 External Tuning program no longer running 2009-01-18 17:58:29.054 External Tuning program exited with no error 2009-01-18 17:58:29.057 Channel(/dev/video1): InitPictureAttribute(brightness): 134 [ 0, 255] dflt( 134, 0.53, 1670) 2009-01-18 17:58:29.077 Channel(/dev/video1): InitPictureAttribute( contrast): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:58:29.097 Channel(/dev/video1): InitPictureAttribute( colour): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:58:29.117 Channel(/dev/video1): InitPictureAttribute( hue): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:58:29.135 TVRec(6): ClearFlags(NeedToStartRecorder,) -> RunMainLoop, 2009-01-18 17:58:29.137 TVRec(6): SetFlags(NeedToStartRecorder,) -> RunMainLoop,NeedToStartRecorder, 2009-01-18 17:58:29.138 TVRec(6): Starting Recorder 2009-01-18 17:58:29.157 Using profile 'Live TV' to record 2009-01-18 17:58:29.158 TVRec(6): CreateLiveTVRingBuffer() 2009-01-18 17:58:29.159 TVRec(6): GetProgramRingBufferForLiveTV() 2009-01-18 17:58:30.207 TVRec(6): StartedRecording(0x920d70) fn(/video1/1075_20090118175829.mpg) 2009-01-18 17:58:30.215 Chain: Appended@0 '1075_20090118175829' 2009-01-18 17:58:30.218 TVRec(6): SetFlags(RingBufferReady,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady, 2009-01-18 17:58:30.224 RecBase(6:/dev/video1): SetRingBuffer(0xaccc10) '/video1/1075_20090118175829.mpg' 2009-01-18 17:58:30.225 RecBase(6:/dev/video1): SetRecording(0xaf7b50) title(Failure to Launch) 2009-01-18 17:58:30.228 Channel(/dev/video1): InitPictureAttribute(brightness): 134 [ 0, 255] dflt( 134, 0.53, 1670) 2009-01-18 17:58:30.248 Channel(/dev/video1): InitPictureAttribute( contrast): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:58:30.268 Channel(/dev/video1): InitPictureAttribute( colour): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:58:30.289 Channel(/dev/video1): InitPictureAttribute( hue): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:58:30.308 MPEGRec(/dev/video1): usingv4l2(1) has_v4l2_vbi(0) has_buggy_vbi(1) 2009-01-18 17:58:30.308 TVRec(6): SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RingBufferReady, 2009-01-18 17:58:30.326 AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 15 min 2009-01-18 17:58:30.329 MainServer::ANN Playback 2009-01-18 17:58:30.330 adding: living-room as a client (events: 0) 2009-01-18 17:58:30.332 MainServer::HandleAnnounce FileTransfer 2009-01-18 17:58:30.333 adding: living-room as a remote file transfer 2009-01-18 17:58:30.339 DevRdB(/dev/video1): buffer size 9400 KB 2009-01-18 17:58:30.341 MPEGRec(/dev/video1): DRB ready 2009-01-18 17:58:30.342 MPEGRec(/dev/video1): SetStreamData(0xb5fdd0) -- begin 2009-01-18 17:58:30.344 MPEGRec(/dev/video1): SetStreamData(0xb5fdd0) -- end 1 2009-01-18 17:58:30.345 MPEGRec(/dev/video1): HandleSingleProgramPAT(NULL) 2009-01-18 17:58:30.346 MPEGRec(/dev/video1): Initial startup of recorder 2009-01-18 17:58:30.347 MPEGRec(/dev/video1): Checking Resolution 2009-01-18 17:58:30.349 TVRec(6): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, 2009-01-18 17:58:30.350 TVRec(6): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2009-01-18 17:58:34.829 MPEGRec(/dev/video1): Got Resolution 1280x720 2009-01-18 17:58:34.832 MPEGRec(/dev/video1): Old bitrate 13500/20200 VBR 2009-01-18 17:58:34.833 MPEGRec(/dev/video1): New bitrate 9000/11000 kbps VBR 2009-01-18 17:58:34.843 MPEGRec(/dev/video1): StartEncoding 2009-01-18 17:58:35.156 MPEGRec(/dev/video1): Encoding started 2009-01-18 17:58:35.168 CreatePATSingleProgram() 2009-01-18 17:58:35.170 PAT in input stream 2009-01-18 17:58:35.171 Program Association Table PSIP tableID(0x0) length(17) extension(0x0) version(0) current(1) section(0) last_section(0) tsid: 0 programCount: 2 program number 0 has PID 0x 1f data 0x 0 0x 0 0xe0 0x1f program number 1 has PID 0x 100 data 0x 0 0x 1 0xe1 0x 0 2009-01-18 17:58:35.172 desired_program(1) pid(0x100) 2009-01-18 17:58:35.173 pmt_pid(0x100) 2009-01-18 17:58:35.174 PAT for output stream 2009-01-18 17:58:35.175 Program Association Table PSIP tableID(0x0) length(13) extension(0x0) version(0) current(1) section(0) last_section(0) tsid: 0 programCount: 1 program number 1 has PID 0x 100 data 0x 0 0x 1 0xe1 0x 0 2009-01-18 17:58:35.177 CreatePMTSingleProgram() 2009-01-18 17:58:35.178 PMT in input stream 2009-01-18 17:58:35.179 Program Map Table ver(0) pid(0x100) pnum(1) len(59) Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS) Unknown(136) Descriptor (0x88) length(4) Stream #0 pid(0x1011) type(video-h264 0x1b) Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS) Stream #1 pid(0x1100) type(audio-ac3 0x81) Registration Descriptor: 'AC-3' ATSC audio stream A/52 Audio Stream Descriptor full_srv(0) sample_rate(48kbps) bit_rate(=192kbps, 10) bsid(8) bs_mode(0) channels(2/2 ) Dolby(Not indicated) mainID(0) 2009-01-18 17:58:35.181 PMT for output stream 2009-01-18 17:58:35.182 Program Map Table ver(0) pid(0x100) pnum(1) len(59) Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS) Unknown(136) Descriptor (0x88) length(4) Stream #0 pid(0x1011) type(video-h264 0x1b) Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS) Stream #1 pid(0x1100) type(audio-ac3 0x81) Registration Descriptor: 'AC-3' ATSC audio stream A/52 Audio Stream Descriptor full_srv(0) sample_rate(48kbps) bit_rate(=192kbps, 10) bsid(8) bs_mode(0) channels(2/2 ) Dolby(Not indicated) mainID(0) 2009-01-18 17:58:35.618 PID 0x1011 Found Payload Start 2009-01-18 17:58:35.670 PID 0x1100 Found Payload Start 2009-01-18 17:58:37.161 TVRec(6): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2009-01-18 17:58:43.521 TVRec(6): SetChannel(71) -- begin 2009-01-18 17:58:43.522 MPEGRec(/dev/video1): PauseAndWait pause 2009-01-18 17:58:43.523 TVRec(6): ClearFlags(RingBufferReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning, 2009-01-18 17:58:43.525 TVRec(6): Request: Program(no) channel(71) input() flags(LiveTV,) 2009-01-18 17:58:43.527 ChannelBase(6): Looking for startchannel '71' on input 'Component' 2009-01-18 17:58:43.533 MPEGRec(/dev/video1): StopEncoding 2009-01-18 17:58:43.533 ChannelBase(6): Found startchannel '71' on input 'Component' 2009-01-18 17:58:43.536 TVRec(6): HW Tuner: 6->6 2009-01-18 17:58:43.537 TVRec(6): ClearFlags(PENDINGACTIONS,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning, 2009-01-18 17:58:43.538 TVRec(6): Waiting for recorder pause.. 2009-01-18 17:58:43.539 TVRec(6): SetFlags(WaitingForRecPause,) -> FrontendReady,RunMainLoop,CancelNextRecording,WaitingForRecPause,RecorderRunning, 2009-01-18 17:58:43.540 TVRec(6): ClearFlags(WaitingForRecPause,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning, 2009-01-18 17:58:43.542 TVRec(6): Recorder paused, calling TuningFrequency 2009-01-18 17:58:43.543 Channel(/dev/video1)::SwitchToInput(in 6, '') 2009-01-18 17:58:43.547 Channel(/dev/video1): SetInputAndFormat(6, NTSC) (v4l v2) 2009-01-18 17:58:45.184 MPEGRec(/dev/video1): Encoding stopped 2009-01-18 17:58:45.224 Channel(/dev/video1): SetChannelByString(71) 2009-01-18 17:58:45.230 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(6) 2009-01-18 17:58:45.231 External channel change: /usr/local/share/mythtv/changechannel /dev/ttyD3 71 2009-01-18 17:58:45.235 Waiting for External Tuning program to exit 2009-01-18 17:58:46.236 ret_pid(0) child(3031) status(0x0) 2009-01-18 17:58:46.799 ret_pid(3031) child(3031) status(0x0) 2009-01-18 17:58:46.800 External Tuning program no longer running 2009-01-18 17:58:46.801 External Tuning program exited with no error 2009-01-18 17:58:46.805 Channel(/dev/video1): InitPictureAttribute(brightness): 134 [ 0, 255] dflt( 134, 0.53, 1670) 2009-01-18 17:58:46.825 Channel(/dev/video1): InitPictureAttribute( contrast): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:58:46.845 Channel(/dev/video1): InitPictureAttribute( colour): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:58:46.866 Channel(/dev/video1): InitPictureAttribute( hue): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:58:46.884 TVRec(6): ClearFlags(NeedToStartRecorder,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning, 2009-01-18 17:58:46.886 TVRec(6): SetFlags(NeedToStartRecorder,) -> FrontendReady,RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning, 2009-01-18 17:58:46.887 TVRec(6): Restarting Recorder 2009-01-18 17:58:46.891 TVRec(6): FinishedRecording(Failure to Launch) in recgroup: LiveTV 2009-01-18 17:58:46.893 Chain: Updated endtime for '1075_20090118175829' to 20090118175846 2009-01-18 17:58:46.895 Finished recording Failure to Launch: channel 1075 2009-01-18 17:58:46.899 scheduler: Finished recording: Failure to Launch: channel 1075 2009-01-18 17:58:46.902 TVRec(6): SwitchLiveTVRingBuffer(discont 1, set_rec 1) 2009-01-18 17:58:46.903 TVRec(6): GetProgramRingBufferForLiveTV() 2009-01-18 17:58:47.947 TVRec(6): StartedRecording(0xb4fec0) fn(/video1/1071_20090118175846.mpg) 2009-01-18 17:58:47.959 TVRec(6): FinishedRecording(Failure to Launch) in recgroup: LiveTV 2009-01-18 17:58:47.960 Chain: Updated endtime for '1075_20090118175829' to 20090118175847 2009-01-18 17:58:47.969 Finished recording Failure to Launch: channel 1075 2009-01-18 17:58:47.973 Preview: 'myth://192.168.0.6:6543/1075_20090118175829.mpg' is not local, replacing with '/video1/1075_20090118175829.mpg', which is local. 2009-01-18 17:58:47.979 Chain: Appended@1 '1071_20090118175846' 2009-01-18 17:58:47.981 DTVRec(6): SetNextRecord(0xb4fec0, 0xa55b00) 2009-01-18 17:58:48.046 mythbackend version: trunk [Unknown] www.mythtv.org 2009-01-18 17:58:48.052 Using runtime prefix = /usr/local 2009-01-18 17:58:48.053 Using localhost value of wambs-server 2009-01-18 17:58:48.066 New DB connection, total: 1 2009-01-18 17:58:48.073 Connected to database 'mythconverg' at host: localhost 2009-01-18 17:58:48.074 Closing DB connection named 'DBManager0' 2009-01-18 17:58:48.076 Connected to database 'mythconverg' at host: localhost 2009-01-18 17:58:48.084 Current Schema Version: 1228 2009-01-18 17:58:48.087 New DB connection, total: 2 2009-01-18 17:58:48.089 Connected to database 'mythconverg' at host: localhost 2009-01-18 17:58:48.106 DTVRec(6): ResetForNewFile(void) 2009-01-18 17:58:48.108 RecBase(6:/dev/video1): SetRingBuffer(0xa55b00) '/video1/1071_20090118175846.mpg' 2009-01-18 17:58:48.109 TVRec(6): RingBufferChanged() 2009-01-18 17:58:48.114 TVRec(6): FinishedRecording(Failure to Launch) in recgroup: LiveTV 2009-01-18 17:58:48.116 Chain: Updated endtime for '1075_20090118175829' to 20090118175848 2009-01-18 17:58:48.118 Finished recording Failure to Launch: channel 1075 2009-01-18 17:58:48.120 TVRec(6): SetFlags(RingBufferReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, 2009-01-18 17:58:48.121 MPEGRec(/dev/video1): Reset(void) 2009-01-18 17:58:48.122 DTVRec(6): ResetForNewFile(void) 2009-01-18 17:58:48.123 TVRec(6): ClearFlags(NeedToStartRecorder,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2009-01-18 17:58:48.124 TVRec(6): SetChannel(71) -- end 2009-01-18 17:58:48.127 AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 15 min 2009-01-18 17:58:48.184 [h264 @ 0x7f74f9ddf4e0]mmco: unref short failure 2009-01-18 17:58:48.226 MPEGRec(/dev/video1): PauseAndWait unpause 2009-01-18 17:58:48.227 MPEGRec(/dev/video1): Checking Resolution 2009-01-18 17:58:48.375 MainServer::ANN Playback 2009-01-18 17:58:48.376 adding: living-room as a client (events: 0) 2009-01-18 17:58:48.379 MainServer::HandleAnnounce FileTransfer 2009-01-18 17:58:48.380 adding: living-room as a remote file transfer 2009-01-18 17:58:50.593 AFD: Opened codec 0x80fd20, id(H264) type(Video) 2009-01-18 17:58:50.595 AFD: codec AC3 has 6 channels 2009-01-18 17:58:50.597 AFD: Opened codec 0x80eee0, id(AC3) type(Audio) 2009-01-18 17:58:50.702 [h264 @ 0x7f74f9ddf4e0]mmco: unref short failure 2009-01-18 17:58:50.704 [h264 @ 0x7f74f9ddf4e0]B picture before any references, skipping 2009-01-18 17:58:50.705 [h264 @ 0x7f74f9ddf4e0]decode_slice_header error 2009-01-18 17:58:50.709 [h264 @ 0x7f74f9ddf4e0]no frame! 2009-01-18 17:58:50.748 AFD Error: Unknown decoding error 2009-01-18 17:58:50.749 [h264 @ 0x7f74f9ddf4e0]B picture before any references, skipping 2009-01-18 17:58:50.751 [h264 @ 0x7f74f9ddf4e0]decode_slice_header error 2009-01-18 17:58:50.752 [h264 @ 0x7f74f9ddf4e0]no frame! 2009-01-18 17:58:50.753 AFD Error: Unknown decoding error 2009-01-18 17:58:50.754 [h264 @ 0x7f74f9ddf4e0]B picture before any references, skipping 2009-01-18 17:58:50.755 [h264 @ 0x7f74f9ddf4e0]decode_slice_header error 2009-01-18 17:58:50.756 [h264 @ 0x7f74f9ddf4e0]no frame! 2009-01-18 17:58:50.756 AFD Error: Unknown decoding error 2009-01-18 17:58:50.772 [h264 @ 0x7f74f9ddf4e0]mmco: unref short failure 2009-01-18 17:58:50.796 [h264 @ 0x7f74f9ddf4e0]mmco: unref short failure 2009-01-18 17:58:50.797 [h264 @ 0x7f74f9ddf4e0]B picture before any references, skipping 2009-01-18 17:58:50.798 [h264 @ 0x7f74f9ddf4e0]decode_slice_header error 2009-01-18 17:58:50.799 [h264 @ 0x7f74f9ddf4e0]no frame! 2009-01-18 17:58:50.800 AFD Error: Unknown decoding error 2009-01-18 17:58:50.801 [h264 @ 0x7f74f9ddf4e0]B picture before any references, skipping 2009-01-18 17:58:50.802 [h264 @ 0x7f74f9ddf4e0]decode_slice_header error 2009-01-18 17:58:50.802 [h264 @ 0x7f74f9ddf4e0]no frame! 2009-01-18 17:58:50.803 AFD Error: Unknown decoding error 2009-01-18 17:58:50.804 [h264 @ 0x7f74f9ddf4e0]B picture before any references, skipping 2009-01-18 17:58:50.805 [h264 @ 0x7f74f9ddf4e0]decode_slice_header error 2009-01-18 17:58:50.806 [h264 @ 0x7f74f9ddf4e0]no frame! 2009-01-18 17:58:50.807 AFD Error: Unknown decoding error 2009-01-18 17:58:50.821 [h264 @ 0x7f74f9ddf4e0]mmco: unref short failure 2009-01-18 17:58:51.185 Preview: Grabbed preview '/video1/1075_20090118175829.mpg' 1280x720@64s 2009-01-18 17:58:52.672 MPEGRec(/dev/video1): Got Resolution 1280x720 2009-01-18 17:58:52.675 MPEGRec(/dev/video1): StartEncoding 2009-01-18 17:58:52.956 MPEGRec(/dev/video1): Encoding started 2009-01-18 17:58:52.973 CreatePATSingleProgram() 2009-01-18 17:58:52.974 PAT in input stream 2009-01-18 17:58:52.975 Program Association Table PSIP tableID(0x0) length(17) extension(0x0) version(0) current(1) section(0) last_section(0) tsid: 0 programCount: 2 program number 0 has PID 0x 1f data 0x 0 0x 0 0xe0 0x1f program number 1 has PID 0x 100 data 0x 0 0x 1 0xe1 0x 0 2009-01-18 17:58:52.977 desired_program(1) pid(0x100) 2009-01-18 17:58:52.978 pmt_pid(0x100) 2009-01-18 17:58:52.979 PAT for output stream 2009-01-18 17:58:52.980 Program Association Table PSIP tableID(0x0) length(13) extension(0x0) version(0) current(1) section(0) last_section(0) tsid: 0 programCount: 1 program number 1 has PID 0x 100 data 0x 0 0x 1 0xe1 0x 0 2009-01-18 17:58:52.982 CreatePMTSingleProgram() 2009-01-18 17:58:52.983 PMT in input stream 2009-01-18 17:58:52.984 Program Map Table ver(0) pid(0x100) pnum(1) len(59) Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS) Unknown(136) Descriptor (0x88) length(4) Stream #0 pid(0x1011) type(video-h264 0x1b) Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS) Stream #1 pid(0x1100) type(audio-ac3 0x81) Registration Descriptor: 'AC-3' ATSC audio stream A/52 Audio Stream Descriptor full_srv(1) sample_rate(48kbps) bit_rate(=384kbps, 14) bsid(6) bs_mode(0) channels(2/0) Dolby(Not Dolby surround encoded) mainID(0) 2009-01-18 17:58:52.985 PMT for output stream 2009-01-18 17:58:52.987 Program Map Table ver(0) pid(0x100) pnum(1) len(59) Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS) Unknown(136) Descriptor (0x88) length(4) Stream #0 pid(0x1011) type(video-h264 0x1b) Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS) Stream #1 pid(0x1100) type(audio-ac3 0x81) Registration Descriptor: 'AC-3' ATSC audio stream A/52 Audio Stream Descriptor full_srv(1) sample_rate(48kbps) bit_rate(=384kbps, 14) bsid(6) bs_mode(0) channels(2/0) Dolby(Not Dolby surround encoded) mainID(0) 2009-01-18 17:58:53.122 TVRec(6): StopLiveTV(void) curRec: 0x98fdb0 pseudoRec: 0x0 2009-01-18 17:58:53.127 TVRec(6): Changing from WatchingLiveTV to None 2009-01-18 17:58:53.128 TVRec(6): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady, 2009-01-18 17:58:53.129 TVRec(6): Request: Program(no) channel() input() flags(KillRec,KillRingBuffer,) 2009-01-18 17:58:53.132 MPEGRec(/dev/video1): StartRecording finishing up 2009-01-18 17:58:53.161 MPEGRec(/dev/video1): StopEncoding 2009-01-18 17:58:54.886 RingBuf(/video1/1071_20090118175846.mpg): Invalid file (fd -1) when opening '/video1/1071_20090118175846.mpg'. 2009-01-18 17:58:55.812 MPEGRec(/dev/video1): Encoding stopped 2009-01-18 17:58:55.815 MPEGRec(/dev/video1): SetStreamData(0x0) -- begin 2009-01-18 17:58:55.816 MPEGRec(/dev/video1): SetStreamData(0x0) -- end 1 2009-01-18 17:58:55.818 TVRec(6): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, 2009-01-18 17:58:55.822 TVRec(6): FinishedRecording(Up Close & Personal) in recgroup: LiveTV 2009-01-18 17:58:55.824 Chain: Updated endtime for '1071_20090118175846' to 20090118175855 2009-01-18 17:58:55.833 Finished recording Up Close & Personal: channel 1071 2009-01-18 17:58:55.839 scheduler: Last message repeated 2 times: Finished recording: Failure to Launch: channel 1075 2009-01-18 17:58:55.842 scheduler: Finished recording: Up Close & Personal: channel 1071 2009-01-18 17:58:55.844 TVRec(6): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, 2009-01-18 17:58:55.846 TVRec(6): Tearing down RingBuffer 2009-01-18 17:58:55.848 TVRec(6): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, 2009-01-18 17:58:55.849 TVRec(6): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,RingBufferReady, 2009-01-18 17:58:55.871 TVRec(6): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,RingBufferReady, 2009-01-18 17:58:55.875 Getting next free recorder after : -1 2009-01-18 17:58:55.877 Checking card 1. Best card so far 1 2009-01-18 17:58:55.878 Checking card 2. Best card so far 2 2009-01-18 17:58:55.879 Checking card 3. Best card so far 3 2009-01-18 17:58:55.880 Checking card 4. Best card so far 4 2009-01-18 17:58:55.881 Checking card 5. Best card so far 5 2009-01-18 17:58:55.882 Checking card 6. Best card so far 6 2009-01-18 17:58:55.910 MainServer::ANN Playback 2009-01-18 17:58:55.911 adding: living-room as a client (events: 0) 2009-01-18 17:58:55.913 TVRec(6): Changing from None to WatchingLiveTV 2009-01-18 17:58:55.914 TVRec(6): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RingBufferReady, 2009-01-18 17:58:55.915 TVRec(6): Request: Program(no) channel() input() flags(LiveTV,) 2009-01-18 17:58:55.916 TVRec(6): Start channel: 71. 2009-01-18 17:58:55.919 TVRec(6): HW Tuner: 6->6 2009-01-18 17:58:55.920 TVRec(6): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, 2009-01-18 17:58:55.921 TVRec(6): No recorder yet, calling TuningFrequency 2009-01-18 17:58:55.922 Channel(/dev/video1): Device name 'Haupauge HD PVR' driver 'hdpvr'. 2009-01-18 17:58:56.000 ChannelBase(6): Input #6: 'Component' schan(71) sourceid(1) ccid(6) 2009-01-18 17:58:56.001 ChannelBase(6): Current Input #6: 'Component' 2009-01-18 17:58:56.002 Global TVFormat Setting 'NTSC' 2009-01-18 17:58:56.003 Channel(/dev/video1): Input #6: 'Component' schan(71) tun() v4l1(NTSC) v4l2(NTSC) 2009-01-18 17:58:56.004 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(6) 2009-01-18 17:58:56.004 Channel(/dev/video1)::SwitchToInput(in 6, '') 2009-01-18 17:58:56.007 Channel(/dev/video1): SetInputAndFormat(6, NTSC) (v4l v2) 2009-01-18 17:58:56.043 Channel(/dev/video1): SetChannelByString(71) 2009-01-18 17:58:56.047 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(6) 2009-01-18 17:58:56.048 External channel change: /usr/local/share/mythtv/changechannel /dev/ttyD3 71 2009-01-18 17:58:56.050 Waiting for External Tuning program to exit 2009-01-18 17:58:57.051 ret_pid(0) child(3041) status(0x0) 2009-01-18 17:58:57.607 ret_pid(3041) child(3041) status(0x0) 2009-01-18 17:58:57.608 External Tuning program no longer running 2009-01-18 17:58:57.609 External Tuning program exited with no error 2009-01-18 17:58:57.612 Channel(/dev/video1): InitPictureAttribute(brightness): 134 [ 0, 255] dflt( 134, 0.53, 1670) 2009-01-18 17:58:57.633 Channel(/dev/video1): InitPictureAttribute( contrast): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:58:57.654 Channel(/dev/video1): InitPictureAttribute( colour): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:58:57.673 Channel(/dev/video1): InitPictureAttribute( hue): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:58:57.693 TVRec(6): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,RingBufferReady, 2009-01-18 17:58:57.694 TVRec(6): SetFlags(NeedToStartRecorder,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady, 2009-01-18 17:58:57.695 TVRec(6): Starting Recorder 2009-01-18 17:58:57.712 Using profile 'Live TV' to record 2009-01-18 17:58:57.713 TVRec(6): CreateLiveTVRingBuffer() 2009-01-18 17:58:57.714 TVRec(6): GetProgramRingBufferForLiveTV() 2009-01-18 17:58:58.747 TVRec(6): StartedRecording(0x7f09580633d0) fn(/video1/1071_20090118175857.mpg) 2009-01-18 17:58:58.755 Chain: Appended@0 '1071_20090118175857' 2009-01-18 17:58:58.758 TVRec(6): SetFlags(RingBufferReady,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady, 2009-01-18 17:58:58.763 RecBase(6:/dev/video1): SetRingBuffer(0x7f09580c1fa0) '/video1/1071_20090118175857.mpg' 2009-01-18 17:58:58.764 RecBase(6:/dev/video1): SetRecording(0x7f095807d3e0) title(Up Close & Personal) 2009-01-18 17:58:58.767 Channel(/dev/video1): InitPictureAttribute(brightness): 134 [ 0, 255] dflt( 134, 0.53, 1670) 2009-01-18 17:58:58.789 Channel(/dev/video1): InitPictureAttribute( contrast): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:58:58.810 Channel(/dev/video1): InitPictureAttribute( colour): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:58:58.830 Channel(/dev/video1): InitPictureAttribute( hue): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 17:58:58.849 TVRec(6): SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RingBufferReady, 2009-01-18 17:58:58.849 MPEGRec(/dev/video1): usingv4l2(1) has_v4l2_vbi(0) has_buggy_vbi(1) 2009-01-18 17:58:58.854 AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 15 min 2009-01-18 17:58:58.862 MainServer::ANN Playback 2009-01-18 17:58:58.864 adding: living-room as a client (events: 0) 2009-01-18 17:58:58.866 MainServer::HandleAnnounce FileTransfer 2009-01-18 17:58:58.867 adding: living-room as a remote file transfer 2009-01-18 17:58:58.881 DevRdB(/dev/video1): buffer size 9400 KB 2009-01-18 17:58:58.882 MPEGRec(/dev/video1): DRB ready 2009-01-18 17:58:58.884 MPEGRec(/dev/video1): SetStreamData(0x7f09580af100) -- begin 2009-01-18 17:58:58.885 MPEGRec(/dev/video1): SetStreamData(0x7f09580af100) -- end 1 2009-01-18 17:58:58.886 MPEGRec(/dev/video1): HandleSingleProgramPAT(NULL) 2009-01-18 17:58:58.887 MPEGRec(/dev/video1): Initial startup of recorder 2009-01-18 17:58:58.888 MPEGRec(/dev/video1): Checking Resolution 2009-01-18 17:58:58.889 TVRec(6): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, 2009-01-18 17:58:58.891 TVRec(6): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2009-01-18 17:59:01.284 Reschedule requested for id 0. 2009-01-18 17:59:01.719 Scheduled 198 items in 0.4 = 0.00 match + 0.43 place 2009-01-18 17:59:01.724 scheduler: Scheduled items: Scheduled 198 items in 0.4 = 0.00 match + 0.43 place 2009-01-18 17:59:03.345 MPEGRec(/dev/video1): Got Resolution 1280x720 2009-01-18 17:59:03.346 MPEGRec(/dev/video1): Old bitrate 13500/20200 VBR 2009-01-18 17:59:03.348 MPEGRec(/dev/video1): New bitrate 9000/11000 kbps VBR 2009-01-18 17:59:03.357 MPEGRec(/dev/video1): StartEncoding 2009-01-18 17:59:03.664 MPEGRec(/dev/video1): Encoding started 2009-01-18 17:59:03.693 CreatePATSingleProgram() 2009-01-18 17:59:03.714 PAT in input stream 2009-01-18 17:59:03.733 Program Association Table PSIP tableID(0x0) length(17) extension(0x0) version(0) current(1) section(0) last_section(0) tsid: 0 programCount: 2 program number 0 has PID 0x 1f data 0x 0 0x 0 0xe0 0x1f program number 1 has PID 0x 100 data 0x 0 0x 1 0xe1 0x 0 2009-01-18 17:59:03.734 desired_program(1) pid(0x100) 2009-01-18 17:59:03.736 pmt_pid(0x100) 2009-01-18 17:59:03.737 PAT for output stream 2009-01-18 17:59:03.738 Program Association Table PSIP tableID(0x0) length(13) extension(0x0) version(0) current(1) section(0) last_section(0) tsid: 0 programCount: 1 program number 1 has PID 0x 100 data 0x 0 0x 1 0xe1 0x 0 2009-01-18 17:59:03.739 CreatePMTSingleProgram() 2009-01-18 17:59:03.741 PMT in input stream 2009-01-18 17:59:03.742 Program Map Table ver(0) pid(0x100) pnum(1) len(59) Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS) Unknown(136) Descriptor (0x88) length(4) Stream #0 pid(0x1011) type(video-h264 0x1b) Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS) Stream #1 pid(0x1100) type(audio-ac3 0x81) Registration Descriptor: 'AC-3' ATSC audio stream A/52 Audio Stream Descriptor full_srv(0) sample_rate(48kbps) bit_rate(=192kbps, 10) bsid(8) bs_mode(0) channels(2/2 ) Dolby(Not indicated) mainID(0) 2009-01-18 17:59:03.743 PMT for output stream 2009-01-18 17:59:03.744 Program Map Table ver(0) pid(0x100) pnum(1) len(59) Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS) Unknown(136) Descriptor (0x88) length(4) Stream #0 pid(0x1011) type(video-h264 0x1b) Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS) Stream #1 pid(0x1100) type(audio-ac3 0x81) Registration Descriptor: 'AC-3' ATSC audio stream A/52 Audio Stream Descriptor full_srv(0) sample_rate(48kbps) bit_rate(=192kbps, 10) bsid(8) bs_mode(0) channels(2/2 ) Dolby(Not indicated) mainID(0) 2009-01-18 17:59:04.179 PID 0x1011 Found Payload Start 2009-01-18 17:59:04.188 TVRec(6): StopLiveTV(void) curRec: 0x7f09580633d0 pseudoRec: 0x0 2009-01-18 17:59:04.192 PID 0x1100 Found Payload Start 2009-01-18 17:59:04.193 TVRec(6): Changing from WatchingLiveTV to None 2009-01-18 17:59:04.194 TVRec(6): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady, 2009-01-18 17:59:04.196 TVRec(6): Request: Program(no) channel() input() flags(KillRec,KillRingBuffer,) 2009-01-18 17:59:04.229 MPEGRec(/dev/video1): StartRecording finishing up 2009-01-18 17:59:04.254 MPEGRec(/dev/video1): StopEncoding 2009-01-18 17:59:06.920 MPEGRec(/dev/video1): Encoding stopped 2009-01-18 17:59:06.950 MPEGRec(/dev/video1): SetStreamData(0x0) -- begin 2009-01-18 17:59:06.951 MPEGRec(/dev/video1): SetStreamData(0x0) -- end 1 2009-01-18 17:59:06.953 TVRec(6): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, 2009-01-18 17:59:06.957 TVRec(6): FinishedRecording(Up Close & Personal) in recgroup: LiveTV 2009-01-18 17:59:06.958 Chain: Updated endtime for '1071_20090118175857' to 20090118175906 2009-01-18 17:59:06.961 Finished recording Up Close & Personal: channel 1071 2009-01-18 17:59:06.964 scheduler: Finished recording: Up Close & Personal: channel 1071 2009-01-18 17:59:06.967 TVRec(6): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, 2009-01-18 17:59:06.968 TVRec(6): Tearing down RingBuffer 2009-01-18 17:59:06.969 TVRec(6): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, 2009-01-18 17:59:30.745 TVRec(5): RecordPending on inputid 5 2009-01-18 17:59:30.830 TVRec(5): ASK_RECORDING 5 30 0 0 2009-01-18 18:00:02.770 TVRec(5): StartRecording(Dirty Jobs) 2009-01-18 18:00:02.823 TVRec(5): StartedRecording(0xb5edc0) fn(/video1/3219_20090118180000.mpg) 2009-01-18 18:00:02.827 TVRec(5): ClearFlags(CancelNextRecording,) -> RunMainLoop, 2009-01-18 18:00:02.828 TVRec(5): Changing from None to RecordingOnly 2009-01-18 18:00:02.830 TVRec(5): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, 2009-01-18 18:00:02.831 TVRec(5): Request: Program(yes) channel() input() flags(Recording,) 2009-01-18 18:00:02.834 TVRec(5): HW Tuner: 5->5 2009-01-18 18:00:02.835 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop, 2009-01-18 18:00:02.836 TVRec(5): No recorder yet, calling TuningFrequency 2009-01-18 18:00:02.838 Channel(/dev/video0): Device name 'Haupauge HD PVR' driver 'hdpvr'. 2009-01-18 18:00:02.957 ChannelBase(5): Input #5: 'Component' schan(231) sourceid(1) ccid(5) 2009-01-18 18:00:02.958 ChannelBase(5): Current Input #5: 'Component' 2009-01-18 18:00:02.959 Global TVFormat Setting 'NTSC' 2009-01-18 18:00:02.960 Channel(/dev/video0): Input #5: 'Component' schan(231) tun() v4l1(NTSC) v4l2(NTSC) 2009-01-18 18:00:02.961 Channel(/dev/video0): SetFormat(Default) fmt(NTSC) input(5) 2009-01-18 18:00:02.962 Channel(/dev/video0)::SwitchToInput(in 5, '') 2009-01-18 18:00:02.965 Channel(/dev/video0): SetInputAndFormat(5, NTSC) (v4l v2) 2009-01-18 18:00:03.004 Channel(/dev/video0): SetChannelByString(278) 2009-01-18 18:00:03.007 Channel(/dev/video0): SetFormat(Default) fmt(NTSC) input(5) 2009-01-18 18:00:03.008 External channel change: /usr/local/share/mythtv/changechannel /dev/ttyD0 278 2009-01-18 18:00:03.011 Waiting for External Tuning program to exit 2009-01-18 18:00:04.013 ret_pid(0) child(3072) status(0x0) 2009-01-18 18:00:04.863 ret_pid(3072) child(3072) status(0x0) 2009-01-18 18:00:04.865 External Tuning program no longer running 2009-01-18 18:00:04.866 External Tuning program exited with no error 2009-01-18 18:00:04.869 Channel(/dev/video0): InitPictureAttribute(brightness): 134 [ 0, 255] dflt( 134, 0.53, 1670) 2009-01-18 18:00:04.889 Channel(/dev/video0): InitPictureAttribute( contrast): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 18:00:04.909 Channel(/dev/video0): InitPictureAttribute( colour): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 18:00:04.929 Channel(/dev/video0): InitPictureAttribute( hue): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 18:00:04.948 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop, 2009-01-18 18:00:04.949 TVRec(5): SetFlags(NeedToStartRecorder,) -> RunMainLoop,NeedToStartRecorder, 2009-01-18 18:00:04.950 TVRec(5): Starting Recorder 2009-01-18 18:00:04.954 New DB connection, total: 4 2009-01-18 18:00:04.957 Connected to database 'mythconverg' at host: localhost 2009-01-18 18:00:04.996 Using profile 'Default' to record 2009-01-18 18:00:04.999 RecBase(5:/dev/video0): SetRingBuffer(0x7f0958d10960) '/video1/3219_20090118180000.mpg' 2009-01-18 18:00:05.000 RecBase(5:/dev/video0): SetRecording(0xb5edc0) title(Dirty Jobs) 2009-01-18 18:00:05.004 Channel(/dev/video0): InitPictureAttribute(brightness): 134 [ 0, 255] dflt( 134, 0.53, 1670) 2009-01-18 18:00:05.025 Channel(/dev/video0): InitPictureAttribute( contrast): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 18:00:05.046 Channel(/dev/video0): InitPictureAttribute( colour): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 18:00:05.066 Channel(/dev/video0): InitPictureAttribute( hue): 128 [ 0, 255] dflt( 128, 0.50, 128) 2009-01-18 18:00:05.084 MPEGRec(/dev/video0): usingv4l2(1) has_v4l2_vbi(0) has_buggy_vbi(1) 2009-01-18 18:00:05.094 AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 15 min 2009-01-18 18:00:05.097 Started recording: Dirty Jobs "Vellum Maker": channel 3219 on cardid 5, sourceid 1 2009-01-18 18:00:05.101 scheduler: Started recording: Dirty Jobs "Vellum Maker": channel 3219 on cardid 5, sourceid 1 2009-01-18 18:00:05.121 DevRdB(/dev/video0): buffer size 9400 KB 2009-01-18 18:00:05.122 MPEGRec(/dev/video0): DRB ready 2009-01-18 18:00:05.124 MPEGRec(/dev/video0): SetStreamData(0x801500) -- begin 2009-01-18 18:00:05.125 MPEGRec(/dev/video0): SetStreamData(0x801500) -- end 1 2009-01-18 18:00:05.126 MPEGRec(/dev/video0): HandleSingleProgramPAT(NULL) 2009-01-18 18:00:05.127 MPEGRec(/dev/video0): Initial startup of recorder 2009-01-18 18:00:05.128 MPEGRec(/dev/video0): Checking Resolution 2009-01-18 18:00:05.130 TVRec(5): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,NeedToStartRecorder,RecorderRunning,RingBufferReady, 2009-01-18 18:00:05.132 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,RecorderRunning,RingBufferReady, 2009-01-18 18:00:12.733 MPEGRec(/dev/video0): Got Resolution 1280x720 2009-01-18 18:00:12.735 MPEGRec(/dev/video0): Old bitrate 13500/20200 VBR 2009-01-18 18:00:12.737 MPEGRec(/dev/video0): New bitrate 9000/11000 kbps VBR 2009-01-18 18:00:12.747 MPEGRec(/dev/video0): StartEncoding 2009-01-18 18:00:13.072 MPEGRec(/dev/video0): Encoding started 2009-01-18 18:00:13.084 CreatePATSingleProgram() 2009-01-18 18:00:13.085 PAT in input stream 2009-01-18 18:00:13.086 Program Association Table PSIP tableID(0x0) length(17) extension(0x0) version(0) current(1) section(0) last_section(0) tsid: 0 programCount: 2 program number 0 has PID 0x 1f data 0x 0 0x 0 0xe0 0x1f program number 1 has PID 0x 100 data 0x 0 0x 1 0xe1 0x 0 2009-01-18 18:00:13.088 desired_program(1) pid(0x100) 2009-01-18 18:00:13.089 pmt_pid(0x100) 2009-01-18 18:00:13.090 PAT for output stream 2009-01-18 18:00:13.091 Program Association Table PSIP tableID(0x0) length(13) extension(0x0) version(0) current(1) section(0) last_section(0) tsid: 0 programCount: 1 program number 1 has PID 0x 100 data 0x 0 0x 1 0xe1 0x 0 2009-01-18 18:00:13.092 CreatePMTSingleProgram() 2009-01-18 18:00:13.093 PMT in input stream 2009-01-18 18:00:13.095 Program Map Table ver(0) pid(0x100) pnum(1) len(59) Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS) Unknown(136) Descriptor (0x88) length(4) Stream #0 pid(0x1011) type(video-h264 0x1b) Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS) Stream #1 pid(0x1100) type(audio-ac3 0x81) Registration Descriptor: 'AC-3' ATSC audio stream A/52 Audio Stream Descriptor full_srv(0) sample_rate(48kbps) bit_rate(=192kbps, 10) bsid(8) bs_mode(0) channels(2/2 ) Dolby(Not indicated) mainID(0) 2009-01-18 18:00:13.096 PMT for output stream 2009-01-18 18:00:13.097 Program Map Table ver(0) pid(0x100) pnum(1) len(59) Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS) Unknown(136) Descriptor (0x88) length(4) Stream #0 pid(0x1011) type(video-h264 0x1b) Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS) Stream #1 pid(0x1100) type(audio-ac3 0x81) Registration Descriptor: 'AC-3' ATSC audio stream A/52 Audio Stream Descriptor full_srv(0) sample_rate(48kbps) bit_rate(=192kbps, 10) bsid(8) bs_mode(0) channels(2/2 ) Dolby(Not indicated) mainID(0)