rendered paste body
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)