mythdvb:~# mythbackend -v record,channel 2007-07-30 21:09:45.436 Using runtime prefix = /usr 2007-07-30 21:09:45.471 New DB connection, total: 1 2007-07-30 21:09:45.474 Connected to database 'mythconverg' at host: 2.1.0.110 2007-07-30 21:09:45.478 Current Schema Version: 1194 2007-07-30 21:09:45.479 Newest Schema Version : 1192 2007-07-30 21:09:45.480 New DB connection, total: 2 2007-07-30 21:09:45.481 Connected to database 'mythconverg' at host: 2.1.0.110 2007-07-30 21:09:45.481 Setting Lock for Database Schema upgrade. If you see a long pause here it means the Schema is already locked and is being upgraded by another Myth process. 2007-07-30 21:09:45.482 New DB connection, total: 3 2007-07-30 21:09:45.483 Connected to database 'mythconverg' at host: 2.1.0.110 2007-07-30 21:09:45.484 Database Schema upgrade complete, unlocking. Running as a slave backend. 2007-07-30 21:09:45.490 TVRec(1): Start channel: 184. 2007-07-30 21:09:45.491 DVBChan(1:1): DVBChannel(0x821fd38) Master(0x821fd38) 2007-07-30 21:09:45.491 DVBChan(1:1): DVBChannel(0x821fd38) Master(0) 2007-07-30 21:09:46.347 DVBChan(1:1): Opening DVB channel fd(-1) 2007-07-30 21:09:46.347 DVBChan(1:1): Using DVB card 1, with frontend 'Genpix 8psk-USB DVB-S'. 2007-07-30 21:09:46.348 DVBChan(1:1): Opening DiSEqC fd(5) card(1) type(32) 2007-07-30 21:09:46.349 New DB connection, total: 4 2007-07-30 21:09:46.350 Connected to database 'mythconverg' at host: 2.1.0.110 2007-07-30 21:09:46.352 New DB connection, total: 5 2007-07-30 21:09:46.353 Connected to database 'mythconverg' at host: 2.1.0.110 2007-07-30 21:09:46.354 New DB connection, total: 6 2007-07-30 21:09:46.355 Connected to database 'mythconverg' at host: 2.1.0.110 2007-07-30 21:09:46.358 DiSEqCDevTree: Changing LNB voltage to 18V 2007-07-30 21:09:46.864 DiSEqCDevTree: Resetting DiSEqC Bus 2007-07-30 21:09:46.864 DiSEqCDevTree: Sending DiSEqC Command: e0 0 0 2007-07-30 21:09:47.120 ChannelBase(1): Input #1: 'DVBInput #1' schan(184) sourceid(2) ccid(1) 2007-07-30 21:09:47.121 ChannelBase(1): Input #5: 'DVBInput #2' schan() sourceid(4) ccid(1) 2007-07-30 21:09:47.121 ChannelBase(1): Input #9: 'DVBInput #3' schan(192) sourceid(5) ccid(1) 2007-07-30 21:09:47.121 ChannelBase(1): Input #13: 'DVBInput #4' schan(257) sourceid(3) ccid(1)2007-07-30 21:09:47.121 ChannelBase(1): Current Input #1: 'DVBInput #1' 2007-07-30 21:09:47.121 DVBChan(1:1): SetChannelByString(184): 2007-07-30 21:09:47.126 DVBChan(1:1): 12238580 qpsk a auto auto a a auto a l 2007-07-30 21:09:47.148 DiSEqCDevTree: Changing to DiSEqC switch port 1/4 2007-07-30 21:09:47.148 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f2 2007-07-30 21:09:47.324 DVBChan(1:1): Old Params: 0 auto a auto auto a a auto a v DVBChan(1:1): New Params: 12238580 qpsk a auto auto a a auto a l 2007-07-30 21:09:47.324 DVBChan(1:1): Tune(): Tuning to 988580kHz 2007-07-30 21:09:48.028 dvbchannel.cpp:wait_for_backend: Status: Signal,Carrier,FEC Stable,Sync,Lock, 2007-07-30 21:09:48.028 DVBChan(1:1): Tune(): Frequency tuning successful. 2007-07-30 21:09:48.028 DVBChan(1:1): SetChannelByString(184): Tuned to frequency. 2007-07-30 21:09:48.028 DVBChan(1:1): Closing DVB channel 2007-07-30 21:09:48.035 TVRec(1): SetFlags(RunMainLoop,) -> RunMainLoop, 2007-07-30 21:09:48.035 TVRec(1): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2007-07-30 21:09:48.037 EITHelper: localtime offset -4:00:00 2007-07-30 21:09:48.040 TVRec(2): Start channel: 184. 2007-07-30 21:09:48.040 DVBChan(2:1): DVBChannel(0x825e8a0) Master(0x821fd38) 2007-07-30 21:09:48.040 DVBChan(2:1): DVBChannel(0x825e8a0) Master(0x821fd38) 2007-07-30 21:09:48.041 DVBChan(2:1): Opening DVB channel fd(-1) 2007-07-30 21:09:48.041 DVBChan(1:1): Opening DVB channel fd(-1) 2007-07-30 21:09:48.041 DVBChan(1:1): Using DVB card 1, with frontend 'Genpix 8psk-USB DVB-S'. 2007-07-30 21:09:48.041 DVBChan(1:1): Opening DiSEqC fd(5) card(1) type(32) 2007-07-30 21:09:48.041 DiSEqCDevTree: Changing LNB voltage to 18V 2007-07-30 21:09:48.548 DiSEqCDevTree: Resetting DiSEqC Bus 2007-07-30 21:09:48.548 DiSEqCDevTree: Sending DiSEqC Command: e0 0 0 2007-07-30 21:09:48.802 ChannelBase(1): Input #1: 'DVBInput #1' schan(184) sourceid(2) ccid(1) 2007-07-30 21:09:48.803 ChannelBase(1): Input #5: 'DVBInput #2' schan() sourceid(4) ccid(1) 2007-07-30 21:09:48.803 ChannelBase(1): Input #9: 'DVBInput #3' schan(192) sourceid(5) ccid(1) 2007-07-30 21:09:48.803 ChannelBase(1): Input #13: 'DVBInput #4' schan(257) sourceid(3) ccid(1)2007-07-30 21:09:48.803 ChannelBase(1): Current Input #1: 'DVBInput #1' 2007-07-30 21:09:48.879 ChannelBase(2): Input #2: 'DVBInput #1' schan(184) sourceid(2) ccid(2) 2007-07-30 21:09:48.879 ChannelBase(2): Input #6: 'DVBInput #2' schan() sourceid(4) ccid(2) 2007-07-30 21:09:48.879 ChannelBase(2): Input #10: 'DVBInput #3' schan(192) sourceid(5) ccid(2)2007-07-30 21:09:48.879 ChannelBase(2): Input #14: 'DVBInput #4' schan(257) sourceid(3) ccid(2)2007-07-30 21:09:48.879 ChannelBase(2): Current Input #2: 'DVBInput #1' 2007-07-30 21:09:48.880 DVBChan(2:1): SetChannelByString(184): 2007-07-30 21:09:48.884 DVBChan(2:1) Warning: Your frequency setting (12238580) is out of range. (min/max:950000/2150000) 2007-07-30 21:09:48.884 DVBChan(2:1): 12238580 qpsk a auto auto a a auto a l 2007-07-30 21:09:48.884 DVBChan(2:1): SetChannelByString(184): Primary is not open. Tuning to mplexid(1) 2007-07-30 21:09:48.904 DiSEqCDevTree: Changing to DiSEqC switch port 1/4 2007-07-30 21:09:48.904 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f2 2007-07-30 21:09:49.080 DVBChan(1:1): Old Params: 12238580 qpsk a auto auto a a auto a l DVBChan(1:1): New Params: 12238580 qpsk a auto auto a a auto a l 2007-07-30 21:09:49.080 DVBChan(1:1): Tune(): Tuning to 988580kHz 2007-07-30 21:09:49.784 dvbchannel.cpp:wait_for_backend: Status: Signal,Carrier,FEC Stable,Sync,Lock, 2007-07-30 21:09:49.784 DVBChan(1:1): Tune(): Frequency tuning successful. 2007-07-30 21:09:49.784 DVBChan(2:1): SetChannelByString(184): Tuned to frequency. 2007-07-30 21:09:49.784 DVBChan(2:1): Closing DVB channel 2007-07-30 21:09:49.784 DVBChan(1:1): Closing DVB channel 2007-07-30 21:09:49.785 TVRec(2): SetFlags(RunMainLoop,) -> RunMainLoop, 2007-07-30 21:09:49.785 TVRec(2): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2007-07-30 21:09:49.787 TVRec(3): Start channel: 184. 2007-07-30 21:09:49.787 DVBChan(3:1): DVBChannel(0x8286478) Master(0x821fd38) 2007-07-30 21:09:49.787 DVBChan(3:1): DVBChannel(0x8286478) Master(0x821fd38) 2007-07-30 21:09:49.787 DVBChan(3:1): Opening DVB channel fd(-1) 2007-07-30 21:09:49.787 DVBChan(1:1): Opening DVB channel fd(-1) 2007-07-30 21:09:49.788 DVBChan(1:1): Using DVB card 1, with frontend 'Genpix 8psk-USB DVB-S'. 2007-07-30 21:09:49.788 DVBChan(1:1): Opening DiSEqC fd(5) card(1) type(32) 2007-07-30 21:09:49.788 DiSEqCDevTree: Changing LNB voltage to 18V 2007-07-30 21:09:50.292 DiSEqCDevTree: Resetting DiSEqC Bus 2007-07-30 21:09:50.292 DiSEqCDevTree: Sending DiSEqC Command: e0 0 0 2007-07-30 21:09:50.547 ChannelBase(1): Input #1: 'DVBInput #1' schan(184) sourceid(2) ccid(1) 2007-07-30 21:09:50.547 ChannelBase(1): Input #5: 'DVBInput #2' schan() sourceid(4) ccid(1) 2007-07-30 21:09:50.547 ChannelBase(1): Input #9: 'DVBInput #3' schan(192) sourceid(5) ccid(1) 2007-07-30 21:09:50.547 ChannelBase(1): Input #13: 'DVBInput #4' schan(257) sourceid(3) ccid(1)2007-07-30 21:09:50.547 ChannelBase(1): Current Input #1: 'DVBInput #1' 2007-07-30 21:09:50.623 ChannelBase(3): Input #3: 'DVBInput #1' schan(184) sourceid(2) ccid(3) 2007-07-30 21:09:50.623 ChannelBase(3): Input #7: 'DVBInput #2' schan() sourceid(4) ccid(3) 2007-07-30 21:09:50.623 ChannelBase(3): Input #11: 'DVBInput #3' schan(192) sourceid(5) ccid(3)2007-07-30 21:09:50.623 ChannelBase(3): Input #15: 'DVBInput #4' schan(257) sourceid(3) ccid(3)2007-07-30 21:09:50.623 ChannelBase(3): Current Input #3: 'DVBInput #1' 2007-07-30 21:09:50.624 DVBChan(3:1): SetChannelByString(184): 2007-07-30 21:09:50.628 DVBChan(3:1) Warning: Your frequency setting (12238580) is out of range. (min/max:950000/2150000) 2007-07-30 21:09:50.628 DVBChan(3:1): 12238580 qpsk a auto auto a a auto a l 2007-07-30 21:09:50.629 DVBChan(3:1): SetChannelByString(184): Primary is not open. Tuning to mplexid(1) 2007-07-30 21:09:50.648 DiSEqCDevTree: Changing to DiSEqC switch port 1/4 2007-07-30 21:09:50.648 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f2 2007-07-30 21:09:50.824 DVBChan(1:1): Old Params: 12238580 qpsk a auto auto a a auto a l DVBChan(1:1): New Params: 12238580 qpsk a auto auto a a auto a l 2007-07-30 21:09:50.824 DVBChan(1:1): Tune(): Tuning to 988580kHz 2007-07-30 21:09:51.528 dvbchannel.cpp:wait_for_backend: Status: Signal,Carrier,FEC Stable,Sync,Lock, 2007-07-30 21:09:51.528 DVBChan(1:1): Tune(): Frequency tuning successful. 2007-07-30 21:09:51.528 DVBChan(3:1): SetChannelByString(184): Tuned to frequency. 2007-07-30 21:09:51.528 DVBChan(3:1): Closing DVB channel 2007-07-30 21:09:51.528 DVBChan(1:1): Closing DVB channel 2007-07-30 21:09:51.529 TVRec(3): SetFlags(RunMainLoop,) -> RunMainLoop, 2007-07-30 21:09:51.529 TVRec(3): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2007-07-30 21:09:51.531 TVRec(4): Start channel: 184. 2007-07-30 21:09:51.531 DVBChan(4:1): DVBChannel(0x82dc500) Master(0x821fd38) 2007-07-30 21:09:51.531 DVBChan(4:1): DVBChannel(0x82dc500) Master(0x821fd38) 2007-07-30 21:09:51.531 DVBChan(4:1): Opening DVB channel fd(-1) 2007-07-30 21:09:51.531 DVBChan(1:1): Opening DVB channel fd(-1) 2007-07-30 21:09:51.532 DVBChan(1:1): Using DVB card 1, with frontend 'Genpix 8psk-USB DVB-S'. 2007-07-30 21:09:51.532 DVBChan(1:1): Opening DiSEqC fd(5) card(1) type(32) 2007-07-30 21:09:51.532 DiSEqCDevTree: Changing LNB voltage to 18V 2007-07-30 21:09:52.036 DiSEqCDevTree: Resetting DiSEqC Bus 2007-07-30 21:09:52.036 DiSEqCDevTree: Sending DiSEqC Command: e0 0 0 2007-07-30 21:09:52.290 ChannelBase(1): Input #1: 'DVBInput #1' schan(184) sourceid(2) ccid(1) 2007-07-30 21:09:52.291 ChannelBase(1): Input #5: 'DVBInput #2' schan() sourceid(4) ccid(1) 2007-07-30 21:09:52.291 ChannelBase(1): Input #9: 'DVBInput #3' schan(192) sourceid(5) ccid(1) 2007-07-30 21:09:52.291 ChannelBase(1): Input #13: 'DVBInput #4' schan(257) sourceid(3) ccid(1)2007-07-30 21:09:52.291 ChannelBase(1): Current Input #1: 'DVBInput #1' 2007-07-30 21:09:52.367 ChannelBase(4): Input #4: 'DVBInput #1' schan(184) sourceid(2) ccid(4) 2007-07-30 21:09:52.367 ChannelBase(4): Input #8: 'DVBInput #2' schan() sourceid(4) ccid(4) 2007-07-30 21:09:52.367 ChannelBase(4): Input #12: 'DVBInput #3' schan(192) sourceid(5) ccid(4)2007-07-30 21:09:52.367 ChannelBase(4): Input #16: 'DVBInput #4' schan(257) sourceid(3) ccid(4)2007-07-30 21:09:52.368 ChannelBase(4): Current Input #4: 'DVBInput #1' 2007-07-30 21:09:52.368 DVBChan(4:1): SetChannelByString(184): 2007-07-30 21:09:52.372 DVBChan(4:1) Warning: Your frequency setting (12238580) is out of range. (min/max:950000/2150000) 2007-07-30 21:09:52.372 DVBChan(4:1): 12238580 qpsk a auto auto a a auto a l 2007-07-30 21:09:52.373 DVBChan(4:1): SetChannelByString(184): Primary is not open. Tuning to mplexid(1) 2007-07-30 21:09:52.392 DiSEqCDevTree: Changing to DiSEqC switch port 1/4 2007-07-30 21:09:52.392 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f2 2007-07-30 21:09:52.568 DVBChan(1:1): Old Params: 12238580 qpsk a auto auto a a auto a l DVBChan(1:1): New Params: 12238580 qpsk a auto auto a a auto a l 2007-07-30 21:09:52.568 DVBChan(1:1): Tune(): Tuning to 988580kHz 2007-07-30 21:09:53.272 dvbchannel.cpp:wait_for_backend: Status: Signal,Carrier,FEC Stable,Sync,Lock, 2007-07-30 21:09:53.272 DVBChan(1:1): Tune(): Frequency tuning successful. 2007-07-30 21:09:53.272 DVBChan(4:1): SetChannelByString(184): Tuned to frequency. 2007-07-30 21:09:53.272 DVBChan(4:1): Closing DVB channel 2007-07-30 21:09:53.273 DVBChan(1:1): Closing DVB channel 2007-07-30 21:09:53.273 TVRec(4): SetFlags(RunMainLoop,) -> RunMainLoop, 2007-07-30 21:09:53.273 TVRec(4): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2007-07-30 21:09:53.856 Main::Registering HttpStatus Extension 2007-07-30 21:09:53.856 mythbackend version: 0.20.20070327-1 www.mythtv.org 2007-07-30 21:09:53.857 Enabled verbose msgs: important general record channel 2007-07-30 21:09:54.861 Connecting to master server: 2.1.0.110:6543 2007-07-30 21:09:54.861 Connected successfully 2007-07-30 21:09:54.862 TVRec(1): ClearFlags(CancelNextRecording,) -> RunMainLoop, 2007-07-30 21:09:54.862 TVRec(2): ClearFlags(CancelNextRecording,) -> RunMainLoop, 2007-07-30 21:09:54.862 TVRec(3): ClearFlags(CancelNextRecording,) -> RunMainLoop, 2007-07-30 21:09:54.863 TVRec(4): ClearFlags(CancelNextRecording,) -> RunMainLoop, 2007-07-30 21:09:56.815 TVRec(1): RecordPending on inputid 13 2007-07-30 21:09:56.815 TVRec(1): SetFlags(AskAllowRecording,) -> RunMainLoop,AskAllowRecording, 2007-07-30 21:09:56.816 TVRec(1): Group ID 4 2007-07-30 21:09:56.817 TVRec(1): Card ID 2 2007-07-30 21:09:56.817 TVRec(1): Card ID 3 2007-07-30 21:09:56.817 TVRec(1): Card ID 4 2007-07-30 21:09:56.817 TVRec(1): ClearFlags(AskAllowRecording,) -> RunMainLoop, 2007-07-30 21:09:56.818 TVRec(2): RecordPending on inputid 13 2007-07-30 21:09:56.818 TVRec(2): SetFlags(AskAllowRecording,) -> RunMainLoop,AskAllowRecording, 2007-07-30 21:09:56.818 TVRec(2): ClearFlags(AskAllowRecording,) -> RunMainLoop, 2007-07-30 21:09:56.818 TVRec(3): RecordPending on inputid 13 2007-07-30 21:09:56.818 TVRec(3): SetFlags(AskAllowRecording,) -> RunMainLoop,AskAllowRecording, 2007-07-30 21:09:56.818 TVRec(3): ClearFlags(AskAllowRecording,) -> RunMainLoop, 2007-07-30 21:09:56.819 TVRec(4): RecordPending on inputid 13 2007-07-30 21:09:56.819 TVRec(4): SetFlags(AskAllowRecording,) -> RunMainLoop,AskAllowRecording, 2007-07-30 21:09:56.819 TVRec(4): ClearFlags(AskAllowRecording,) -> RunMainLoop, 2007-07-30 21:09:56.820 TVRec(1): StartRecording(9478 (ULTRA)) 2007-07-30 21:09:56.820 TVRec(1): ClearFlags(AskAllowRecording,) -> RunMainLoop, 2007-07-30 21:09:56.820 TVRec(1): StartRecording(9478 (ULTRA)) Pending(Y) pendCardIds(3) 2007-07-30 21:09:56.820 TVRec(1): Checking input group recorders kFlagCancelNext(0) - begin 2007-07-30 21:09:56.820 TVRec(2): ClearFlags(CancelNextRecording,) -> RunMainLoop, 2007-07-30 21:09:56.821 TVRec(2): Pending recording revoked 2007-07-30 21:09:56.821 TVRec(3): ClearFlags(CancelNextRecording,) -> RunMainLoop, 2007-07-30 21:09:56.821 TVRec(3): Pending recording revoked 2007-07-30 21:09:56.821 TVRec(4): ClearFlags(CancelNextRecording,) -> RunMainLoop, 2007-07-30 21:09:56.821 TVRec(4): Pending recording revoked 2007-07-30 21:09:56.821 TVRec(1): Checking input group recorders - done 2007-07-30 21:09:56.862 TVRec(1): StartedRecording(0x8333fc8) fn(/stash/video/12478_20070730211000.mpg) 2007-07-30 21:09:56.863 TVRec(1): ClearFlags(CancelNextRecording,) -> RunMainLoop, 2007-07-30 21:09:56.863 TVRec(1): Changing from None to RecordingOnly 2007-07-30 21:09:56.863 TVRec(1): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, 2007-07-30 21:09:56.863 TVRec(1): SetFlags(AskAllowRecording,) -> RunMainLoop,AskAllowRecording, 2007-07-30 21:09:56.863 TVRec(1): Request: Program(yes) channel() input() flags(Recording,) 2007-07-30 21:09:56.865 TVRec(1): HW Tuner: 1->1 2007-07-30 21:09:56.865 TVRec(1): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,AskAllowRecording,2007-07-30 21:09:56.865 TVRec(1): HandleTuning() request dequeued 2007-07-30 21:09:56.865 TVRec(1): TuningFrequency() 2007-07-30 21:09:56.866 DVBChan(1:1): Opening DVB channel fd(-1) 2007-07-30 21:09:56.866 DVBChan(1:1): Using DVB card 1, with frontend 'Genpix 8psk-USB DVB-S'. 2007-07-30 21:09:56.866 DVBChan(1:1): Opening DiSEqC fd(18) card(1) type(32) 2007-07-30 21:09:56.867 DiSEqCDevTree: Changing LNB voltage to 18V 2007-07-30 21:09:57.372 DiSEqCDevTree: Resetting DiSEqC Bus 2007-07-30 21:09:57.372 DiSEqCDevTree: Sending DiSEqC Command: e0 0 0 2007-07-30 21:09:57.632 ChannelBase(1): Input #1: 'DVBInput #1' schan(184) sourceid(2) ccid(1) 2007-07-30 21:09:57.632 ChannelBase(1): Input #5: 'DVBInput #2' schan() sourceid(4) ccid(1) 2007-07-30 21:09:57.633 ChannelBase(1): Input #9: 'DVBInput #3' schan(192) sourceid(5) ccid(1) 2007-07-30 21:09:57.633 ChannelBase(1): Input #13: 'DVBInput #4' schan(257) sourceid(3) ccid(1)2007-07-30 21:09:57.633 ChannelBase(1): Current Input #1: 'DVBInput #1' 2007-07-30 21:09:57.633 DVBChan(1:1): SetChannelByString(9478): 2007-07-30 21:09:57.634 ChannelBase(1): Failed to find channel(9478) on current input (DVBInput #1) of card (1). 2007-07-30 21:09:57.635 ChannelBase(1): Found channel(9478) on another input (DVBInput #4) of card (1). 2007-07-30 21:09:57.642 DVBChan(1:1): 12296900 8psk a auto auto a a auto a l 2007-07-30 21:09:57.664 DiSEqCDevTree: Changing to DiSEqC switch port 4/4 2007-07-30 21:09:57.664 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 fe 2007-07-30 21:09:57.840 DVBChan(1:1): Old Params: 12238580 qpsk a auto auto a a auto a l DVBChan(1:1): New Params: 12296900 8psk a auto auto a a auto a l 2007-07-30 21:09:57.840 DVBChan(1:1): Tune(): Tuning to 1046900kHz 2007-07-30 21:09:58.544 dvbchannel.cpp:wait_for_backend: Status: Signal,Carrier,FEC Stable,Sync,Lock, 2007-07-30 21:09:58.544 DVBChan(1:1): Tune(): Frequency tuning successful. 2007-07-30 21:09:58.544 DVBChan(1:1): SetChannelByString(9478): Tuned to frequency. 2007-07-30 21:09:58.544 TVRec(1): Starting Signal Monitor 2007-07-30 21:09:58.545 TVRec(1): SetupSignalMonitor(1, 0) 2007-07-30 21:09:58.545 DVBChan(1:1): Opening DVB channel fd(18) 2007-07-30 21:09:58.558 DVBSM(1): Can measure Signal Strength 2007-07-30 21:09:58.558 DVBSM(1): Can measure S/N 2007-07-30 21:09:58.558 DVBSM(1): Can measure Bit Error Rate 2007-07-30 21:09:58.558 DVBSM(1): Can count Uncorrected Blocks 2007-07-30 21:09:58.558 SM(1)::RemoveFlags: Seen() Match() Wait() 2007-07-30 21:09:58.558 DVBSM(1): DVBSignalMonitor::ctor initial flags Seen() Match() Wait(Sig,SNR,BER,UB,) 2007-07-30 21:09:58.864 TVRec(1): Signal monitor successfully created 2007-07-30 21:09:58.864 TVRec(1): Setting up dvb table monitoring. 2007-07-30 21:09:58.870 Using profile 'Live TV' to record 2007-07-30 21:09:58.870 TVRec(1): DVB service_id 9478 on net_id 4104 tsid 406 2007-07-30 21:09:58.870 TVRec(1): stream_data(0x837e9f8) 2007-07-30 21:09:58.870 DTVSM(1)::SetDVBService(transport_id: 406, network_id: 4104, service_id: 9478): 2007-07-30 21:09:58.870 SM(1)::RemoveFlags: Seen(PMT,NIT,SDT,Crypt,) Match(PMT,NIT,SDT,Crypt,) Wait() 2007-07-30 21:09:58.870 SM(1)::AddFlags: Seen() Match() Wait(PMT,NIT,) 2007-07-30 21:09:58.870 SM(1)::AddFlags: Seen() Match() Wait(PMT,NIT,Pos,) 2007-07-30 21:09:58.870 TVRec(1): Successfully set up DVB table monitoring. 2007-07-30 21:09:58.871 SM(1)::Start: begin 2007-07-30 21:09:58.871 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2007-07-30 21:09:58.876 SM(1)::Start: end 2007-07-30 21:09:58.876 TVRec(1): SetFlags(SignalMonitorRunning,) -> RunMainLoop,AskAllowRecording,SignalMonitorRunning, 2007-07-30 21:09:58.876 TVRec(1): ClearFlags(WaitingForSignal,) -> RunMainLoop,AskAllowRecording,SignalMonitorRunning, 2007-07-30 21:09:58.876 TVRec(1): SetFlags(WaitingForSignal,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,SignalMonitorRunning, 2007-07-30 21:09:58.876 TVRec(1): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,SignalMonitorRunning, 2007-07-30 21:09:58.876 TVRec(1): SetFlags(NeedToStartRecorder,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning, 2007-07-30 21:09:58.884 DVBSM(1): UpdateValues -- Signal Locked 2007-07-30 21:09:58.884 DVBSH(1): AddListener(0x837ea7c) -- begin 2007-07-30 21:09:58.884 DVBSH(1): AddListener(0x837ea7c) -- locked 2007-07-30 21:09:58.884 DVBSH(1): AddListener(0x837ea7c) -- end 2007-07-30 21:09:58.885 DVBSH(1): AddPIDFilter(0x0) priority 2 2007-07-30 21:09:58.885 PIDInfo(1): Opening filter for pid 0x0 2007-07-30 21:09:58.885 DVBSH(1): RemovePIDFilter(0x0) 2007-07-30 21:09:58.885 PIDInfo(1): Closing filter for pid 0x0 2007-07-30 21:09:58.891 DVBSH(1): RunTS(): begin 2007-07-30 21:09:58.892 DVBSH(1): AddPIDFilter(0x0) priority 2 2007-07-30 21:09:58.892 PIDInfo(1): Opening filter for pid 0x0 2007-07-30 21:09:58.892 DVBSH(1): AddPIDFilter(0x10) priority 2 2007-07-30 21:09:58.892 PIDInfo(1): Opening filter for pid 0x10 2007-07-30 21:09:58.893 DVBSH(1): AddPIDFilter(0x11) priority 2 2007-07-30 21:09:58.893 PIDInfo(1): Opening filter for pid 0x11 2007-07-30 21:09:58.893 DVBSH(1): AddPIDFilter(0x14) priority 2 2007-07-30 21:09:58.893 PIDInfo(1): Opening filter for pid 0x14 2007-07-30 21:09:58.949 DTVSM(1): Time Offset: -0.949817 2007-07-30 21:09:58.989 SM(1)::AddFlags: Seen(PAT,) Match() Wait() 2007-07-30 21:09:58.989 SM(1)::AddFlags: Seen() Match(PAT,) Wait() 2007-07-30 21:09:58.989 CreatePATSingleProgram() 2007-07-30 21:09:58.990 PAT in input stream 2007-07-30 21:09:58.990 Program Association Table PSIP tableID(0x0) length(25) extension(0x196) version(3) current(1) section(0) last_section(0) tsid: 406 programCount: 4 program number 0 has PID 0x 10 data 0x 0 0x 0 0xe0 0x10 program number 9472 has PID 0x 21 data 0x25 0x 0 0xe0 0x21 program number 9476 has PID 0x 21 data 0x25 0x 4 0xe0 0x21 program number 9478 has PID 0x 21 data 0x25 0x 6 0xe0 0x21 2007-07-30 21:09:58.990 desired_program(9478) pid(0x21) 2007-07-30 21:09:58.990 pmt_pid(0x21) 2007-07-30 21:09:58.990 PAT for output stream 2007-07-30 21:09:58.990 Program Association Table PSIP tableID(0x0) length(13) extension(0x196) version(3) current(1) section(0) last_section(0) tsid: 406 programCount: 1 program number 1 has PID 0x 21 data 0x 0 0x 1 0xe0 0x21 2007-07-30 21:09:58.991 DVBSH(1): AddPIDFilter(0x21) priority 2 2007-07-30 21:09:58.991 PIDInfo(1): Opening filter for pid 0x21 2007-07-30 21:09:58.995 SM(1)::AddFlags: Seen(PMT,) Match() Wait() 2007-07-30 21:09:58.996 DTVSM(1) Error: Wrong PMT; pmt->pn(9472) desired(9478) 2007-07-30 21:09:58.996 SM(1)::AddFlags: Seen(PMT,) Match() Wait() 2007-07-30 21:09:58.996 DTVSM(1) Error: Wrong PMT; pmt->pn(9476) desired(9478) 2007-07-30 21:09:58.996 SM(1)::AddFlags: Seen(PMT,) Match() Wait() 2007-07-30 21:09:58.996 SM(1)::AddFlags: Seen() Match(PMT,) Wait() 2007-07-30 21:09:58.996 CreatePMTSingleProgram() 2007-07-30 21:09:58.997 PMT in input stream 2007-07-30 21:09:58.997 Program Map Table ver(0) pid(0x21) pnum(9478) len(41) Stream #0 pid(0x1222) type(video-mpeg2 0x2) Conditional Access: sid(0x1810) pid(0x122) data_size(0) Conditional Access: sid(0x1801) pid(0x122) data_size(0) Stream #1 pid(0x1223) type(audio-ac3 0x81) ISO-639 Language: code(eng) canonical(eng) eng(English) 2007-07-30 21:09:58.997 PMT for output stream 2007-07-30 21:09:58.997 Program Map Table ver(0) pid(0x21) pnum(1) len(29) Stream #0 pid(0x1222) type(video-mpeg2 0x2) Stream #1 pid(0x1223) type(audio-ac3 0x81) ISO-639 Language: code(eng) canonical(eng) eng(English) 2007-07-30 21:09:58.997 DVBSH(1): AddPIDFilter(0x1222) priority 3 2007-07-30 21:09:58.998 PIDInfo(1): Opening filter for pid 0x1222 2007-07-30 21:09:58.998 DVBSH(1): AddPIDFilter(0x1223) priority 3 2007-07-30 21:09:58.998 PIDInfo(1): Opening filter for pid 0x1223 2007-07-30 21:09:59.433 PID 0x1222 status: Decrypted 2007-07-30 21:09:59.433 Program 9478 status: Decrypted 2007-07-30 21:09:59.433 SM(1)::AddFlags: Seen(Crypt,) Match() Wait() 2007-07-30 21:09:59.433 SM(1)::AddFlags: Seen() Match(Crypt,) Wait() 2007-07-30 21:10:01.109 DTVSM(1)::SetNIT(): net_id = 4104 2007-07-30 21:10:01.109 SM(1)::AddFlags: Seen(NIT,) Match() Wait() 2007-07-30 21:10:01.109 SM(1)::AddFlags: Seen() Match(NIT,) Wait() 2007-07-30 21:10:01.123 DTVSM(1)::SetNIT(): net_id = 4104 2007-07-30 21:10:01.123 SM(1)::AddFlags: Seen(NIT,) Match() Wait() 2007-07-30 21:10:01.123 SM(1)::AddFlags: Seen() Match(NIT,) Wait() 2007-07-30 21:10:01.316 TVRec(1): Got good signal 2007-07-30 21:10:01.316 TVRec(1): TeardownSignalMonitor() -- begin 2007-07-30 21:10:01.316 DVBSM(1): Stop() -- begin 2007-07-30 21:10:01.316 SM(1)::Stop: begin 2007-07-30 21:10:01.620 SM(1)::Stop: end 2007-07-30 21:10:01.620 DVBSH(1): RemoveListener(0x837ea7c) -- begin 2007-07-30 21:10:01.620 DVBSH(1): RemoveListener(0x837ea7c) -- locked 2007-07-30 21:10:01.623 DVBSH(1): RunTS(): shutdown 2007-07-30 21:10:01.623 DVBSH(1): RemovePIDFilter(0x0) 2007-07-30 21:10:01.623 PIDInfo(1): Closing filter for pid 0x0 2007-07-30 21:10:01.623 DVBSH(1): RemovePIDFilter(0x10) 2007-07-30 21:10:01.623 PIDInfo(1): Closing filter for pid 0x10 2007-07-30 21:10:01.623 DVBSH(1): RemovePIDFilter(0x11) 2007-07-30 21:10:01.623 PIDInfo(1): Closing filter for pid 0x11 2007-07-30 21:10:01.623 DVBSH(1): RemovePIDFilter(0x14) 2007-07-30 21:10:01.624 PIDInfo(1): Closing filter for pid 0x14 2007-07-30 21:10:01.624 DVBSH(1): RemovePIDFilter(0x21) 2007-07-30 21:10:01.624 PIDInfo(1): Closing filter for pid 0x21 2007-07-30 21:10:01.624 DVBSH(1): RemovePIDFilter(0x1222) 2007-07-30 21:10:01.624 PIDInfo(1): Closing filter for pid 0x1222 2007-07-30 21:10:01.624 DVBSH(1): RemovePIDFilter(0x1223) 2007-07-30 21:10:01.624 PIDInfo(1): Closing filter for pid 0x1223 2007-07-30 21:10:01.625 DVBSH(1): RunTS(): end 2007-07-30 21:10:01.625 DVBSH(1): RemoveListener(0x837ea7c) -- end 2007-07-30 21:10:01.625 DVBSM(1): Stop() -- end 2007-07-30 21:10:01.625 TVRec(1): TeardownSignalMonitor() -- end 2007-07-30 21:10:01.625 TVRec(1): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,NeedToStartRecorder, 2007-07-30 21:10:01.626 TVRec(1): ClearFlags(WaitingForSignal,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder, 2007-07-30 21:10:01.627 TVRec(1): Starting Recorder 2007-07-30 21:10:01.633 Using profile 'Default' to record 2007-07-30 21:10:01.640 TVRec(1): SetRingBuffer(0x8372ab8) old(0) 2007-07-30 21:10:01.650 RecBase(1:1): SetRingBuffer(0x8372ab8) old(0) '/stash/video/12478_20070730211000.mpg' 2007-07-30 21:10:01.651 DVBRec(1:1): SetPAT(9478 on 0x21) ringBuffer(0x8372ab8) 2007-07-30 21:10:01.651 CreatePATSingleProgram() 2007-07-30 21:10:01.651 PAT in input stream 2007-07-30 21:10:01.651 Program Association Table PSIP tableID(0x0) length(25) extension(0x196) version(3) current(1) section(0) last_section(0) tsid: 406 programCount: 4 program number 0 has PID 0x 10 data 0x 0 0x 0 0xe0 0x10 program number 9472 has PID 0x 21 data 0x25 0x 0 0xe0 0x21 program number 9476 has PID 0x 21 data 0x25 0x 4 0xe0 0x21 program number 9478 has PID 0x 21 data 0x25 0x 6 0xe0 0x21 2007-07-30 21:10:01.651 desired_program(9478) pid(0x21) 2007-07-30 21:10:01.651 pmt_pid(0x21) 2007-07-30 21:10:01.652 PAT for output stream 2007-07-30 21:10:01.652 Program Association Table PSIP tableID(0x0) length(13) extension(0x196) version(3) current(1) section(0) last_section(0) tsid: 406 programCount: 1 program number 1 has PID 0x 21 data 0x 0 0x 1 0xe0 0x21 2007-07-30 21:10:01.652 DVBRec(1:1): SetPMT(9478) ringBuffer(0x8372ab8) 2007-07-30 21:10:01.652 CreatePMTSingleProgram() 2007-07-30 21:10:01.652 PMT in input stream 2007-07-30 21:10:01.652 Program Map Table ver(0) pid(0x21) pnum(9478) len(41) Stream #0 pid(0x1222) type(video-mpeg2 0x2) Conditional Access: sid(0x1810) pid(0x122) data_size(0) Conditional Access: sid(0x1801) pid(0x122) data_size(0) Stream #1 pid(0x1223) type(audio-ac3 0x81) ISO-639 Language: code(eng) canonical(eng) eng(English) 2007-07-30 21:10:01.653 PMT for output stream 2007-07-30 21:10:01.653 Program Map Table ver(0) pid(0x21) pnum(1) len(29) Stream #0 pid(0x1222) type(video-mpeg2 0x2) Stream #1 pid(0x1223) type(audio-ac3 0x81) ISO-639 Language: code(eng) canonical(eng) eng(English) 2007-07-30 21:10:01.653 RecBase(1:1): SetRecording(0x8333fc8) title(9478 (ULTRA)) 2007-07-30 21:10:01.654 DVBRec(1:1): Card opened successfully fd(-1) 2007-07-30 21:10:01.655 DVBSH(1): AddListener(0x837ea7c) -- begin 2007-07-30 21:10:01.655 DVBSH(1): AddListener(0x837ea7c) -- locked 2007-07-30 21:10:01.656 DVBSH(1): AddListener(0x837ea7c) -- end 2007-07-30 21:10:01.664 TVRec(1): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, 2007-07-30 21:10:01.664 TVRec(1): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,AskAllowRecording,RecorderRunning,RingBufferReady, 2007-07-30 21:10:01.679 DevRdB(1): buffer size 9400 KB 2007-07-30 21:10:01.680 DVBSH(1): RunTS(): begin 2007-07-30 21:10:01.680 DVBSH(1): AddPIDFilter(0x0) priority 2 2007-07-30 21:10:01.680 PIDInfo(1): Opening filter for pid 0x0 2007-07-30 21:10:01.680 DVBSH(1): AddPIDFilter(0x10) priority 2 2007-07-30 21:10:01.681 PIDInfo(1): Opening filter for pid 0x10 2007-07-30 21:10:01.681 DVBSH(1): AddPIDFilter(0x11) priority 2 2007-07-30 21:10:01.681 PIDInfo(1): Opening filter for pid 0x11 2007-07-30 21:10:01.681 DVBSH(1): AddPIDFilter(0x14) priority 2 2007-07-30 21:10:01.681 PIDInfo(1): Opening filter for pid 0x14 2007-07-30 21:10:01.682 DVBSH(1): AddPIDFilter(0x21) priority 2 2007-07-30 21:10:01.682 PIDInfo(1): Opening filter for pid 0x21 2007-07-30 21:10:01.682 DVBSH(1): AddPIDFilter(0x1222) priority 3 2007-07-30 21:10:01.682 PIDInfo(1): Opening filter for pid 0x1222 2007-07-30 21:10:01.682 DVBSH(1): AddPIDFilter(0x1223) priority 3 2007-07-30 21:10:01.682 PIDInfo(1): Opening filter for pid 0x1223 2007-07-30 21:10:01.700 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 11 (expected 8). 2007-07-30 21:10:01.716 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 8 (expected 7). 2007-07-30 21:10:02.582 PID 0x1222 Found Payload Start 2007-07-30 21:10:02.632 PID 0x1223 Found Payload Start 2007-07-30 21:10:21.684 DevRdB(1): fill avg( 0%) fill max( 1%) samples(1972) 2007-07-30 21:10:41.696 DevRdB(1): fill avg( 0%) fill max( 1%) samples(1513) 2007-07-30 21:11:01.700 DevRdB(1): fill avg( 0%) fill max( 1%) samples(1262) 2007-07-30 21:11:21.716 DevRdB(1): fill avg( 0%) fill max( 1%) samples(1553) 2007-07-30 21:11:41.725 DevRdB(1): fill avg( 0%) fill max( 1%) samples(1324) 2007-07-30 21:12:01.729 DevRdB(1): fill avg( 0%) fill max( 1%) samples(1491) 2007-07-30 21:12:21.741 DevRdB(1): fill avg( 0%) fill max( 1%) samples(1665) 2007-07-30 21:12:41.757 DevRdB(1): fill avg( 0%) fill max( 1%) samples(1544) 2007-07-30 21:13:01.761 DevRdB(1): fill avg( 0%) fill max( 1%) samples(1614) 2007-07-30 21:13:21.786 DevRdB(1): fill avg( 0%) fill max( 1%) samples(1714) 2007-07-30 21:13:41.789 DevRdB(1): fill avg( 0%) fill max( 1%) samples(1394)