rendered paste body2006-10-02 21:26:52.301 Using runtime prefix = /usr/local
2006-10-02 21:26:52.309 New DB connection, total: 1
2006-10-02 21:26:52.310 Connected to database 'mythconverg' at host: localhost
2006-10-02 21:26:52.312 Current Schema Version: 1160
2006-10-02 21:26:52.319 New DB connection, total: 2
2006-10-02 21:26:52.319 Connected to database 'mythconverg' at host: localhost
2006-10-02 21:26:52.320 EITHelper: localtime offset -7:00:00
2006-10-02 21:26:53.190 DVBChan(0): Opening DVB channel
2006-10-02 21:26:53.191 New DB connection, total: 3
2006-10-02 21:26:53.191 Connected to database 'mythconverg' at host: localhost
2006-10-02 21:26:53.192 New DB connection, total: 4
2006-10-02 21:26:53.192 Connected to database 'mythconverg' at host: localhost
2006-10-02 21:26:53.193 New DB connection, total: 5
2006-10-02 21:26:53.193 Connected to database 'mythconverg' at host: localhost
2006-10-02 21:26:53.194 New DB connection, total: 6
2006-10-02 21:26:53.194 Connected to database 'mythconverg' at host: localhost
2006-10-02 21:26:53.195 DiSEqCDevTree: Changing LNB voltage to 18V
2006-10-02 21:26:53.695 DiSEqCDevTree: Resetting DiSEqC Bus
2006-10-02 21:26:53.695 DiSEqCDevTree: Sending DiSEqC Command: e0 0 0
2006-10-02 21:26:53.972 ChannelBase(1): Input #1: 'DVBInput #1' schan(504) sourceid(1) ccid(1)
2006-10-02 21:26:53.972 ChannelBase(1): Input #2: 'DVBInput #2' schan(509) sourceid(2) ccid(1)
2006-10-02 21:26:53.972 ChannelBase(1): Input #3: 'DVBInput #3' schan(7861) sourceid(3) ccid(1)
2006-10-02 21:26:53.972 ChannelBase(1): Current Input #1: 'DVBInput #1'
2006-10-02 21:26:53.972 DVBChan(0): SetChannelByString(504):
2006-10-02 21:26:53.973 DVBChan(0): Frequency: 12296900 Symbol Rate: 20000000 Pol: l Inv: Auto
2006-10-02 21:26:53.974 invalidating DeCSA for card 0
2006-10-02 21:26:53.990 DiSEqCDevTree: Changing to DiSEqC switch port 1/3
2006-10-02 21:26:53.990 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f3
2006-10-02 21:26:54.160 DVBChan(0): Old Params: Frequency: 0 Symbol Rate: 0 Pol: v Inv: Off
DVBChan(0): New Params: Frequency: 12296900 Symbol Rate: 20000000 Pol: l Inv: Auto
2006-10-02 21:26:54.160 DVBChan(0): Tune(): Tuning to 2053100kHz
2006-10-02 21:26:54.209 dvbchannel.cpp:wait_for_backend: Status:
2006-10-02 21:26:54.209 DVBChan(0): Tune(): Frequency tuning successful.
2006-10-02 21:26:54.209 DVBChan(0): SetChannelByString(504): Tuned to frequency.
2006-10-02 21:26:54.213 EITHelper: localtime offset -7:00:00
2006-10-02 21:26:55.082 DVBChan(1): Opening DVB channel
2006-10-02 21:26:55.083 DiSEqCDevTree: Changing LNB voltage to 18V
2006-10-02 21:26:55.584 DiSEqCDevTree: Resetting DiSEqC Bus
2006-10-02 21:26:55.584 DiSEqCDevTree: Sending DiSEqC Command: e0 0 0
2006-10-02 21:26:55.860 ChannelBase(2): Input #4: 'DVBInput #1' schan(504) sourceid(1) ccid(2)
2006-10-02 21:26:55.860 ChannelBase(2): Input #5: 'DVBInput #2' schan(509) sourceid(2) ccid(2)
2006-10-02 21:26:55.860 ChannelBase(2): Input #6: 'DVBInput #3' schan(9901) sourceid(3) ccid(2)
2006-10-02 21:26:55.860 ChannelBase(2): Current Input #4: 'DVBInput #1'
2006-10-02 21:26:55.860 DVBChan(1): SetChannelByString(504):
2006-10-02 21:26:55.861 DVBChan(1): Frequency: 12296900 Symbol Rate: 20000000 Pol: l Inv: Auto
2006-10-02 21:26:55.861 invalidating DeCSA for card 1
2006-10-02 21:26:55.877 DiSEqCDevTree: Changing to DiSEqC switch port 1/3
2006-10-02 21:26:55.877 DiSEqCDevTree: Sending DiSEqC Command: e0 10 39 f0
2006-10-02 21:26:56.047 DVBChan(1): Old Params: Frequency: 0 Symbol Rate: 0 Pol: v Inv: Off
DVBChan(1): New Params: Frequency: 12296900 Symbol Rate: 20000000 Pol: l Inv: Auto
2006-10-02 21:26:56.047 DVBChan(1): Tune(): Tuning to 2053100kHz
2006-10-02 21:26:56.047 dvbchannel.cpp:wait_for_backend: Status:
2006-10-02 21:26:56.047 DVBChan(1): Tune(): Frequency tuning successful.
2006-10-02 21:26:56.047 DVBChan(1): SetChannelByString(504): Tuned to frequency.
2006-10-02 21:26:56.048 New DB scheduler connection
2006-10-02 21:26:56.048 Connected to database 'mythconverg' at host: localhost
2006-10-02 21:26:56.049 Main::Starting HttpServer
2006-10-02 21:26:56.052 Main::Registering HttpStatus Extension
2006-10-02 21:26:56.054 mythbackend version: 0.20.20060915-1 www.mythtv.org
2006-10-02 21:26:56.054 Enabled verbose msgs: important general channel
2006-10-02 21:26:56.054 AutoExpire: Found 2 recorders w/max rate of 277 MiB/min
2006-10-02 21:26:56.055 AutoExpire: Required Free Space: 3.2 GB w/freq: 5 min
2006-10-02 21:26:58.058 Reschedule requested for id -1.
2006-10-02 21:26:58.275 Scheduled 1 items in 0.2 = 0.21 match + 0.01 place
2006-10-02 21:26:58.276 Recording starts soon, AUTO-Startup assumed
2006-10-02 21:27:08.985 MainServer::HandleAnnounce Monitor
2006-10-02 21:27:08.985 adding: teevee as a client (events: 0)
2006-10-02 21:27:08.985 MainServer::HandleAnnounce Monitor
2006-10-02 21:27:08.985 adding: teevee as a client (events: 1)
2006-10-02 21:27:08.988 MainServer::HandleAnnounce Playback
2006-10-02 21:27:08.988 adding: teevee as a client (events: 0)
2006-10-02 21:27:08.989 TVRec(1): Changing from None to WatchingLiveTV
2006-10-02 21:27:08.990 TVRec(1): HW Tuner: 1->1
2006-10-02 21:27:08.990 DVBChan(0): Opening DVB channel
2006-10-02 21:27:08.990 DVBChan(0): SetChannelByString(504):
2006-10-02 21:27:08.990 DVBChan(0): SetChannelByString(504): Already on channel
2006-10-02 21:27:09.012 DVBChan(0): Opening DVB channel
2006-10-02 21:27:09.025 SM(0)::AddFlags: Seen() Match() Wait(Sig,SNR,BER,UB,)
2006-10-02 21:27:09.025 DVBSM(0)::constructor(): initial flags 0x7400000
2006-10-02 21:27:09.328 DTVSM(0)::SetDVBService(transport_id: 206, network_id: 4102, service_id: 504):
2006-10-02 21:27:09.328 SM(0)::RemoveFlags: Seen(PMT,SDT,) Match(PMT,SDT,) Wait()
2006-10-02 21:27:09.328 SM(0)::AddFlags: Seen() Match() Wait(PMT,NIT,)
2006-10-02 21:27:09.328 SM(0)::AddFlags: Seen() Match() Wait(PMT,NIT,)
2006-10-02 21:27:09.328 SM(0)::AddFlags: Seen() Match() Wait()
2006-10-02 21:27:09.328 SM(0)::Start: begin
2006-10-02 21:27:09.329 SM(0)::Start: end
2006-10-02 21:27:09.345 DVBSM(0)::UpdateValues: Signal Locked
2006-10-02 21:27:09.345 DVBSM(0)::UpdateValues: Waiting for table monitor to start
2006-10-02 21:27:09.388 DVBSM(0)::AddPIDFilter(0x0):
2006-10-02 21:27:09.389 DVBSM(0)::UpdateValues: Table monitor started
2006-10-02 21:27:09.389 DTVSM(0)::GetStatusList: WaitForPMT seen(0) matching(0)
2006-10-02 21:27:09.450 DVBSM(0)::RemovePIDFilter(0x0):
2006-10-02 21:27:09.648 DVBSM(0): RunTableMonitorTS(): begin (# of pids 3)
2006-10-02 21:27:09.648 DVBSM(0)::AddPIDFilter(0x0):
2006-10-02 21:27:09.648 DVBSM(0)::AddPIDFilter(0x10):
2006-10-02 21:27:09.648 DVBSM(0)::AddPIDFilter(0x11):
2006-10-02 21:27:09.712 SM(0)::AddFlags: Seen(PAT,) Match() Wait()
2006-10-02 21:27:09.712 SM(0)::AddFlags: Seen() Match(PAT,) Wait()
2006-10-02 21:27:09.712 DVBSM(0)::AddPIDFilter(0x21):
2006-10-02 21:27:09.723 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.723 DTVSM(0) Error: Wrong PMT; pmt->pn(539) desired(504)
2006-10-02 21:27:09.744 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.744 DTVSM(0) Error: Wrong PMT; pmt->pn(839) desired(504)
2006-10-02 21:27:09.744 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.744 DTVSM(0) Error: Wrong PMT; pmt->pn(841) desired(504)
2006-10-02 21:27:09.744 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.744 DTVSM(0) Error: Wrong PMT; pmt->pn(842) desired(504)
2006-10-02 21:27:09.763 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.764 DTVSM(0) Error: Wrong PMT; pmt->pn(5817) desired(504)
2006-10-02 21:27:09.764 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.764 DTVSM(0) Error: Wrong PMT; pmt->pn(5819) desired(504)
2006-10-02 21:27:09.764 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.764 DTVSM(0) Error: Wrong PMT; pmt->pn(5825) desired(504)
2006-10-02 21:27:09.785 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.785 DTVSM(0) Error: Wrong PMT; pmt->pn(456) desired(504)
2006-10-02 21:27:09.785 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.785 DTVSM(0) Error: Wrong PMT; pmt->pn(861) desired(504)
2006-10-02 21:27:09.805 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.805 DTVSM(0) Error: Wrong PMT; pmt->pn(9926) desired(504)
2006-10-02 21:27:09.805 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.805 DTVSM(0) Error: Wrong PMT; pmt->pn(9928) desired(504)
2006-10-02 21:27:09.825 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.825 DTVSM(0) Error: Wrong PMT; pmt->pn(9934) desired(504)
2006-10-02 21:27:09.825 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.825 DTVSM(0) Error: Wrong PMT; pmt->pn(19055) desired(504)
2006-10-02 21:27:09.845 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.845 DTVSM(0) Error: Wrong PMT; pmt->pn(19002) desired(504)
2006-10-02 21:27:09.845 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.845 DTVSM(0) Error: Wrong PMT; pmt->pn(19047) desired(504)
2006-10-02 21:27:09.866 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.866 DTVSM(0) Error: Wrong PMT; pmt->pn(19048) desired(504)
2006-10-02 21:27:09.866 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.866 DTVSM(0) Error: Wrong PMT; pmt->pn(19049) desired(504)
2006-10-02 21:27:09.866 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.866 DTVSM(0) Error: Wrong PMT; pmt->pn(5775) desired(504)
2006-10-02 21:27:09.906 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.907 DTVSM(0) Error: Wrong PMT; pmt->pn(19090) desired(504)
2006-10-02 21:27:09.907 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.907 DTVSM(0) Error: Wrong PMT; pmt->pn(5770) desired(504)
2006-10-02 21:27:09.926 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.926 DTVSM(0) Error: Wrong PMT; pmt->pn(538) desired(504)
2006-10-02 21:27:09.926 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.926 SM(0)::AddFlags: Seen() Match(PMT,) Wait()
2006-10-02 21:27:09.947 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.947 DTVSM(0) Error: Wrong PMT; pmt->pn(467) desired(504)
2006-10-02 21:27:09.947 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.947 DTVSM(0) Error: Wrong PMT; pmt->pn(508) desired(504)
2006-10-02 21:27:09.947 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.947 DTVSM(0) Error: Wrong PMT; pmt->pn(518) desired(504)
2006-10-02 21:27:09.966 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:09.966 DTVSM(0) Error: Wrong PMT; pmt->pn(522) desired(504)
2006-10-02 21:27:09.991 DTVSM(0)::GetStatusList: WaitForPMT seen(1) matching(1)
2006-10-02 21:27:10.284 DTVSM(0)::SetNIT(): net_id = 4102
2006-10-02 21:27:10.284 SM(0)::AddFlags: Seen(NIT,) Match() Wait()
2006-10-02 21:27:10.284 DTVSM(0)::setNIT(): nid = 4102
2006-10-02 21:27:10.284 SM(0)::AddFlags: Seen() Match(NIT,) Wait()
2006-10-02 21:27:10.292 DVBSM(0)::Stop: begin
2006-10-02 21:27:10.292 SM(0)::Stop: begin
2006-10-02 21:27:10.382 DTVSM(0)::SetNIT(): net_id = 4102
2006-10-02 21:27:10.382 SM(0)::AddFlags: Seen(NIT,) Match() Wait()
2006-10-02 21:27:10.382 DTVSM(0)::setNIT(): nid = 4102
2006-10-02 21:27:10.382 SM(0)::AddFlags: Seen() Match(NIT,) Wait()
2006-10-02 21:27:10.487 DTVSM(0)::SetNIT(): net_id = 4102
2006-10-02 21:27:10.487 SM(0)::AddFlags: Seen(NIT,) Match() Wait()
2006-10-02 21:27:10.487 DTVSM(0)::setNIT(): nid = 4102
2006-10-02 21:27:10.487 SM(0)::AddFlags: Seen() Match(NIT,) Wait()
2006-10-02 21:27:10.594 SM(0)::Stop: end
2006-10-02 21:27:10.594 DVBSM(0): RunTableMonitorTS(): shutdown
2006-10-02 21:27:10.595 DVBSM(0)::RemovePIDFilter(0x0):
2006-10-02 21:27:10.595 DVBSM(0)::RemovePIDFilter(0x10):
2006-10-02 21:27:10.595 DVBSM(0)::RemovePIDFilter(0x11):
2006-10-02 21:27:10.595 DVBSM(0)::RemovePIDFilter(0x21):
2006-10-02 21:27:10.597 DVBSM(0): RunTableMonitorTS(): end
2006-10-02 21:27:10.597 DVBSM(0)::Stop: end
2006-10-02 21:27:10.602 Finished recording Poseidon (All Day) "All Day (6:00am - 6:00am Eastern)": channel 1504
2006-10-02 21:27:10.624 Finished recording Poseidon (All Day) "All Day (6:00am - 6:00am Eastern)": channel 1504
2006-10-02 21:27:10.646 DVB#0 CA: SetPMT() program num #504
2006-10-02 21:27:10.646 SID: 504 TID: 1 (4386 0) (4387 1) (4388 1)
2006-10-02 21:27:10.646 Found CA Descriptors: (36) 9 4 18 10 1 23 9 4 18 1 1 23 9 4 18 10 1 23 9 4 18 1 1 23 9 4 18 10 1 23 9 4 18 1 1 23
2006-10-02 21:27:10.663 Preview Error: Previewer file '/mythtv/1504_20061002212708.mpg' is not valid.
opening keyfile: /root/.mythtv/externcam_0/keyfifo -> 3
HW Decoder: No
sasc (0): initializing plugin: SoftCam (0.5.10): A software emulated CAM
sasc (0): starting plugin:
scplugin: version 0.5.10 starting
** Plugin config:
** Key updates (AU) are enabled (active CAIDs)
** ScCaps are 1 2 0 0 0 0 0 0 0 0
** Concurrent limits FF=3 Soft=4
INFO: loading keys from /root/.mythtv/externcam_0/SoftCam.Key
plainkeys: loaded 8 keys from /root/.mythtv/externcam_0/SoftCam.Key
** registered systems:
** Cardclient (pri -15)
** Conax (pri -10)
** ConstCW (pri -20)
** Irdeto (pri -10)
** Nagra2 (pri -10)
** Nagra (pri -10)
** SC-Conax (pri -5)
** SC-Cryptoworks (pri -5)
** SC-Irdeto (pri -5)
** SC-Seca (pri -5)
** SC-Viaccess (pri -5)
** Seca (pri -10)
** @SHL (pri -10)
** Viaccess (pri -10)
ERROR: Failed to open file '/root/.mythtv/externcam_0/cardclient.conf': No such file or directory
cc-link: created 0 client(s)
ERROR: Failed to open file '/root/.mythtv/externcam_0/Ird-Beta.KID': No such file or directory
cardinfo: loaded 0 Irdeto cards from /root/.mythtv/externcam_0/Ird-Beta.KID
ERROR: Failed to open file '/root/.mythtv/externcam_0/Seca.KID': No such file or directory
cardinfo: loaded 0 Seca cards from /root/.mythtv/externcam_0/Seca.KID
ERROR: Failed to open file '/root/.mythtv/externcam_0/Viaccess.KID': No such file or directory
cardinfo: loaded 0 Viaccess cards from /root/.mythtv/externcam_0/Viaccess.KID
ERROR: Failed to open file '/root/.mythtv/externcam_0/smartcard.conf': No such file or directory
INFO: loading ca cache from /root/.mythtv/externcam_0/ca.cache
WARNING: no smartcard interface defined!
sasc (0): got PMT (len = 36)09 04 18 10 01 23 09 04 18 01 01 23 09 04 18 10 01 23 09 04 18 01 01 23 09 04 18 10 01 23 09 04 18 01 01 23
sasc (0): corrected to (len = 36)
sasc (0): Changing channels: 36 0 36
cam 0: try to open ca device myself
cam 0: no ca device, assuming budget card & SoftCSA
INFO: Using software decryption on card 0
ecmhandler 0.1: setting new SID 504
ecmhandler 0.1: is no longer idle
softcam: now 1 SoftCSA stream(s) registered
ecmhandler 0.1: triggered SID <none>/504 idx -1/1 mode -1/0 -
ecmcache: from cache: system Nagra2 (1810) id 0000 with ecm 123/80
ecmhandler 0.1: got CaDescriptors (len=36)
ecmhandler 0.1: descriptor 18 10 01 23
ecmhandler 0.1: found 1810 (Nagra2) id 0000 with ecm 123 (already present)
ecmhandler 0.1: descriptor 18 01 01 23
ecmhandler 0.1: found 1801 (Nagra2) id 0000 with ecm 123 (dup) (new)
ecmhandler 0.1: descriptor 18 10 01 23
ecmhandler 0.1: found 1810 (Nagra2) id 0000 with ecm 123 (already present)
ecmhandler 0.1: descriptor 18 01 01 23
ecmhandler 0.1: found 1801 (Nagra2) id 0000 with ecm 123 (dup) (already present)
ecmhandler 0.1: descriptor 18 10 01 23
ecmhandler 0.1: found 1810 (Nagra2) id 0000 with ecm 123 (already present)
ecmhandler 0.1: descriptor 18 01 01 23
ecmhandler 0.1: found 1801 (Nagra2) id 0000 with ecm 123 (dup) (already present)
ecmhandler 0.1: try system Nagra2 (1810) id 0000 with ecm 123 (cached) (pri=-10)
LOADING /lib/firmware/image.bin
LOADED /lib/firmware/image.bin
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
system: using key N 0101 00 24D2...
logger 0: chain caid 1800 -> Nagra(-10) [0020-82/ff/00]
logger 0: chain caid 1801 -> Nagra2(-10) [0120-82/fe/00]
logger 0: chain caid 1810 -> Nagra2(-10) [0120-82/ff/00]
logger 0: chai2006-10-02 21:27:20.375 ChannelBase(1): Failed to find channel() on current input (DVBInput #1) of card (1).
2006-10-02 21:27:20.375 ChannelBase(1): Failed to find channel() on any input of card (1).
2006-10-02 21:27:20.379 ChannelBase(1): Failed to find channel(509) on current input (DVBInput #1) of card (1).
2006-10-02 21:27:20.380 ChannelBase(1): Found channel(509) on another input (DVBInput #2) of card (1).
2006-10-02 21:27:20.439 ChannelBase(1): Failed to find channel(509) on current input (DVBInput #1) of card (1).
2006-10-02 21:27:20.439 ChannelBase(1): Found channel(509) on another input (DVBInput #2) of card (1).
2006-10-02 21:27:20.439 TVRec(1): HW Tuner: 1->1
2006-10-02 21:27:20.445 DVBChan(0): Opening DVB channel
2006-10-02 21:27:20.445 DVBChan(0): SetChannelByString(509):
2006-10-02 21:27:20.445 ChannelBase(1): Failed to find channel(509) on current input (DVBInput #1) of card (1).
2006-10-02 21:27:20.446 ChannelBase(1): Found channel(509) on another input (DVBInput #2) of card (1).
2006-10-02 21:27:20.448 DVBChan(0): Frequency: 12457280 Symbol Rate: 20000000 Pol: r Inv: Auto
2006-10-02 21:27:20.448 invalidating DeCSA for card 0
2006-10-02 21:27:20.464 DiSEqCDevTree: Changing to DiSEqC switch port 2/3
2006-10-02 21:27:20.464 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f4
2006-10-02 21:27:20.634 DVBChan(0): Old Params: Frequency: 12296900 Symbol Rate: 20000000 Pol: l Inv: Auto
DVBChan(0): New Params: Frequency: 12457280 Symbol Rate: 20000000 Pol: r Inv: Auto
2006-10-02 21:27:20.634 DVBChan(0): Tune(): Tuning to 1207280kHz
2006-10-02 21:27:20.634 dvbchannel.cpp:wait_for_backend: Status:
2006-10-02 21:27:20.634 DVBChan(0): Tune(): Frequency tuning successful.
2006-10-02 21:27:20.634 DVBChan(0): SetChannelByString(509): Tuned to frequency.
2006-10-02 21:27:20.654 Finished recording Poseidon (All Day) "All Day (6:00am - 6:00am Eastern)": channel 1504
0: start_time: 2487.555 duration: 0.643
1: start_time: 2487.524 duration: 0.635
2: start_time: 2487.519 duration: 0.635
stream: start_time: 27639.097 duration: 7.546 bitrate=2255 kb/s
2006-10-02 21:27:20.669 AFD: Opened codec 0x82ce640, id(MPEG2VIDEO) type(Video)
2006-10-02 21:27:20.684 AFD: Opened codec 0x82cea80, id(MP3) type(Audio)
2006-10-02 21:27:20.684 AFD: Opened codec 0x82cf030, id(MP3) type(Audio)
2006-10-02 21:27:20.710 DVBChan(0): Opening DVB channel
2006-10-02 21:27:20.711 SM(0)::AddFlags: Seen() Match() Wait(Sig,SNR,BER,UB,)
2006-10-02 21:27:20.711 DVBSM(0)::constructor(): initial flags 0x7400000
2006-10-02 21:27:21.013 DTVSM(0)::SetDVBService(transport_id: 17, network_id: 4100, service_id: 509):
2006-10-02 21:27:21.013 SM(0)::RemoveFlags: Seen(PMT,SDT,) Match(PMT,SDT,) Wait()
2006-10-02 21:27:21.013 SM(0)::AddFlags: Seen() Match() Wait(PMT,NIT,)
2006-10-02 21:27:21.013 SM(0)::AddFlags: Seen() Match() Wait(PMT,NIT,)
2006-10-02 21:27:21.013 SM(0)::AddFlags: Seen() Match() Wait()
2006-10-02 21:27:21.014 SM(0)::Start: begin
2006-10-02 21:27:21.014 SM(0)::Start: end
2006-10-02 21:27:21.026 DVBSM(0)::UpdateValues: Signal Locked
2006-10-02 21:27:21.026 DVBSM(0)::UpdateValues: Waiting for table monitor to start
2006-10-02 21:27:21.027 DVBSM(0)::UpdateValues: Table monitor started
2006-10-02 21:27:21.027 DTVSM(0)::GetStatusList: WaitForPMT seen(0) matching(0)
2006-10-02 21:27:21.032 DVBSM(0): RunTableMonitorTS(): begin (# of pids 3)
2006-10-02 21:27:21.032 DVBSM(0)::AddPIDFilter(0x0):
2006-10-02 21:27:21.032 DVBSM(0)::AddPIDFilter(0x10):
2006-10-02 21:27:21.032 DVBSM(0)::AddPIDFilter(0x11):
2006-10-02 21:27:21.081 SM(0)::AddFlags: Seen(PAT,) Match() Wait()
2006-10-02 21:27:21.082 SM(0)::AddFlags: Seen() Match(PAT,) Wait()
2006-10-02 21:27:21.082 DVBSM(0)::AddPIDFilter(0x21):
2006-10-02 21:27:21.086 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.086 DTVSM(0) Error: Wrong PMT; pmt->pn(19010) desired(509)
2006-10-02 21:27:21.107 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.107 DTVSM(0) Error: Wrong PMT; pmt->pn(310) desired(509)
2006-10-02 21:27:21.107 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.107 DTVSM(0) Error: Wrong PMT; pmt->pn(312) desired(509)
2006-10-02 21:27:21.126 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.126 DTVSM(0) Error: Wrong PMT; pmt->pn(150) desired(509)
2006-10-02 21:27:21.126 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.126 DTVSM(0) Error: Wrong PMT; pmt->pn(189) desired(509)
2006-10-02 21:27:21.126 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.126 DTVSM(0) Error: Wrong PMT; pmt->pn(101) desired(509)
2006-10-02 21:27:21.148 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.148 DTVSM(0) Error: Wrong PMT; pmt->pn(253) desired(509)
2006-10-02 21:27:21.148 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.148 DTVSM(0) Error: Wrong PMT; pmt->pn(405) desired(509)
2006-10-02 21:27:21.148 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.148 DTVSM(0) Error: Wrong PMT; pmt->pn(414) desired(509)
2006-10-02 21:27:21.148 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.148 DTVSM(0) Error: Wrong PMT; pmt->pn(416) desired(509)
2006-10-02 21:27:21.169 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.169 DTVSM(0) Error: Wrong PMT; pmt->pn(429) desired(509)
2006-10-02 21:27:21.169 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.169 DTVSM(0) Error: Wrong PMT; pmt->pn(480) desired(509)
2006-10-02 21:27:21.169 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.169 DTVSM(0) Error: Wrong PMT; pmt->pn(490) desired(509)
2006-10-02 21:27:21.188 DVB#0 CA: SetPMT() program num #509
2006-10-02 21:27:21.188 SID: 509 TID: 1 (6434 0) (6435 1) (6436 1)
2006-10-02 21:27:21.188 Found CA Descriptors: (36) 9 4 18 10 1 2a 9 4 18 1 1 2a 9 4 18 10 1 2a 9 4 18 1 1 2a 9 4 18 10 1 2a 9 4 18 1 1 2a
2006-10-02 21:27:21.188 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.188 SM(0)::AddFlags: Seen() Match(PMT,) Wait()
2006-10-02 21:27:21.188 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.188 DTVSM(0) Error: Wrong PMT; pmt->pn(632) desired(509)
2006-10-02 21:27:21.188 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.188 DTVSM(0) Error: Wrong PMT; pmt->pn(885) desired(509)
2006-10-02 21:27:21.209 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.210 DTVSM(0) Error: Wrong PMT; pmt->pn(891) desired(509)
2006-10-02 21:27:21.210 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.210 DTVSM(0) Error: Wrong PMT; pmt->pn(6024) desired(509)
2006-10-02 21:27:21.210 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.210 DTVSM(0) Error: Wrong PMT; pmt->pn(6062) desired(509)
2006-10-02 21:27:21.210 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.210 DTVSM(0) Error: Wrong PMT; pmt->pn(6030) desired(509)
2006-10-02 21:27:21.229 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.229 DTVSM(0) Error: Wrong PMT; pmt->pn(6050) desired(509)
2006-10-02 21:27:21.229 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.229 DTVSM(0) Error: Wrong PMT; pmt->pn(6051) desired(509)
2006-10-02 21:27:21.229 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.229 DTVSM(0) Error: Wrong PMT; pmt->pn(6053) desired(509)
2006-10-02 21:27:21.229 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.229 DTVSM(0) Error: Wrong PMT; pmt->pn(6070) desired(509)
2006-10-02 21:27:21.250 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.250 DTVSM(0) Error: Wrong PMT; pmt->pn(6072) desired(509)
2006-10-02 21:27:21.250 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.250 DTVSM(0) Error: Wrong PMT; pmt->pn(7598) desired(509)
2006-10-02 21:27:21.250 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.250 DTVSM(0) Error: Wrong PMT; pmt->pn(8339) desired(509)
2006-10-02 21:27:21.271 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.271 DTVSM(0) Error: Wrong PMT; pmt->pn(8668) desired(509)
2006-10-02 21:27:21.271 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.272 DTVSM(0) Error: Wrong PMT; pmt->pn(9393) desired(509)
2006-10-02 21:27:21.272 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.272 DTVSM(0) Error: Wrong PMT; pmt->pn(19040) desired(509)
2006-10-02 21:27:21.291 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:21.291 DTVSM(0) Error: Wrong PMT; pmt->pn(19052) desired(509)
2006-10-02 21:27:21.328 DTVSM(0)::GetStatusList: WaitForPMT seen(1) matching(1)
n caid 1820 -> Nagra2(-10) [0350-82/ff/00]
logger 0: chain caid 1821 -> Nagra2(-10) [0351-82/ff/00]
logger 0: chain caid 1822 -> Nagra2(-10) [0352-82/ff/00]
logger 0: chain caid 1823 -> Nagra2(-10) [0353-82/ff/00]
logger 0: chain caid 1824 -> Nagra2(-10) [0354-82/ff/00]
logger 0: chain caid 1825 -> Nagra2(-10) [0355-82/ff/00]
logger 0: chain caid 1826 -> Nagra2(-10) [0356-82/ff/00]
logger 0: chain caid 1827 -> Nagra2(-10) [0357-82/ff/00]
logger 0: chain caid 1828 -> Nagra2(-10) [0358-82/ff/00]
logger 0: chain caid 1829 -> Nagra2(-10) [0359-82/ff/00]
logger 0: chain caid 182a -> Nagra2(-10) [035a-82/ff/00]
logger 0: chain caid 182b -> Nagra2(-10) [035b-82/ff/00]
logger 0: chain caid 182c -> Nagra2(-10) [035c-82/ff/00]
logger 0: chain caid 182d -> Nagra2(-10) [035d-82/ff/00]
logger 0: starting chain 1810
ecmhandler 0.1: correct key found
logger 0: stopping chain 1810
logger 0: chain caid 1800 -> Nagra(-10) [0020-82/ff/00]
logger 0: chain caid 1801 -> Nagra2(-10) [0120-82/fe/00]
logger 0: chain caid 1810 -> Nagra2(-10) [0120-82/ff/00]
logger 0: chain caid 1820 -> Nagra2(-10) [0350-82/ff/00]
logger 0: chain caid 1821 -> Nagra2(-10) [0351-82/ff/00]
logger 0: chain caid 1822 -> Nagra2(-10) [0352-82/ff/00]
logger 0: chain caid 1823 -> Nagra2(-10) [0353-82/ff/00]
logger 0: chain caid 1824 -> Nagra2(-10) [0354-82/ff/00]
logger 0: chain caid 1825 -> Nagra2(-10) [0355-82/ff/00]
logger 0: chain caid 1826 -> Nagra2(-10) [0356-82/ff/00]
logger 0: chain caid 1827 -> Nagra2(-10) [0357-82/ff/00]
logger 0: chain caid 1828 -> Nagra2(-10) [0358-82/ff/00]
logger 0: chain caid 1829 -> Nagra2(-10) [0359-82/ff/00]
logger 0: chain caid 182a -> Nagra2(-10) [035a-82/ff/00]
logger 0: chain caid 182b -> Nagra2(-10) [035b-82/ff/00]
logger 0: chain caid 182c -> Nagra2(-10) [035c-82/ff/00]
logger 0: chain caid 182d -> Nagra2(-10) [035d-82/ff/00]
logger 0: starting chain 1810
sasc (0): got PMT (len = 36)09 04 18 10 01 2a 09 04 18 01 01 2a 09 04 18 10 01 2a 09 04 18 01 01 2a 09 04 18 10 01 2a 09 04 18 01 01 2a
sasc (0): corrected to (len = 36)
sasc (0): Changing channels: 36 36 36
cam 0: killing leftover pid 1122
cam 0: killing leftover pid 1124
ecmhandler 0.1: is idle now
softcam: no SoftCSA streams registered
logger 0: stopping chain 1810
ecmhandler 0.1: setting new SID 509
ecmhandler 0.1: is no longer idle
softcam: now 1 SoftCSA stream(s) registered
ecmhandler 0.1: triggered SID 504/509 idx 1/1 mode 3/0 sync
ecmcache: from cache: system Nagra2 (1810) id 0000 with ecm 12a/80
ecmhandler 0.1: got CaDescriptors (len=36)
ecmhandler 0.1: descriptor 18 10 01 2a
ecmhandler 0.1: found 1810 (Nagra2) id 0000 with ecm 12a (already present)
ecmhandler 0.1: descriptor 18 01 01 2a
ecmhandler 0.1: found 1801 (Nagra2) id 0000 with ecm 12a (dup) (new)
ecmhandler 0.1: descriptor 18 10 01 2a
ecmhandler 0.1: found 1810 (Nagra2) id 0000 with ecm 12a (already present)
ecmhandler 0.1: descriptor 18 01 01 2a
ecmhandler 0.1: found 1801 (Nagra2) id 0000 with ecm 12a (dup) (already present)
ecmhandler 0.1: descriptor 18 10 01 2a
ecmhandler 0.1: found 1810 (Nagra2) id 0000 with ecm 12a (already present)
ecmhandler 0.1: descriptor 18 01 01 2a
ecmhandler 0.1: found 1801 (Nagra2) id 0000 with ecm 12a (dup) (already present)
ecmhandler 0.1: try system Nagra2 (1810) id 0000 with ecm 12a (cached) (pri=-10)
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
system: using key N 0101 00 24D2...
logger 0: chain caid 1800 -> Nagra(-10) [0020-82/ff/00]
logger 0: chain caid 1801 -> Nagra2(-10) 2006-10-02 21:27:21.384 DTVSM(0)::SetNIT(): net_id = 4100
2006-10-02 21:27:21.384 SM(0)::AddFlags: Seen(NIT,) Match() Wait()
2006-10-02 21:27:21.385 DTVSM(0)::setNIT(): nid = 4100
2006-10-02 21:27:21.385 SM(0)::AddFlags: Seen() Match(NIT,) Wait()
2006-10-02 21:27:21.389 DVBSM(0)::Stop: begin
2006-10-02 21:27:21.389 SM(0)::Stop: begin
2006-10-02 21:27:21.480 DTVSM(0)::SetNIT(): net_id = 4100
2006-10-02 21:27:21.481 SM(0)::AddFlags: Seen(NIT,) Match() Wait()
2006-10-02 21:27:21.481 DTVSM(0)::setNIT(): nid = 4100
2006-10-02 21:27:21.481 SM(0)::AddFlags: Seen() Match(NIT,) Wait()
2006-10-02 21:27:21.581 DTVSM(0)::SetNIT(): net_id = 4100
2006-10-02 21:27:21.581 SM(0)::AddFlags: Seen(NIT,) Match() Wait()
2006-10-02 21:27:21.581 DTVSM(0)::setNIT(): nid = 4100
2006-10-02 21:27:21.581 SM(0)::AddFlags: Seen() Match(NIT,) Wait()
2006-10-02 21:27:21.602 DTVSM(0)::SetNIT(): net_id = 4100
2006-10-02 21:27:21.602 SM(0)::AddFlags: Seen(NIT,) Match() Wait()
2006-10-02 21:27:21.602 DTVSM(0)::setNIT(): nid = 4100
2006-10-02 21:27:21.602 SM(0)::AddFlags: Seen() Match(NIT,) Wait()
2006-10-02 21:27:21.629 SM(0)::Stop: end
2006-10-02 21:27:21.640 DVBSM(0): RunTableMonitorTS(): shutdown
2006-10-02 21:27:21.641 DVBSM(0)::RemovePIDFilter(0x0):
2006-10-02 21:27:21.641 DVBSM(0)::RemovePIDFilter(0x10):
2006-10-02 21:27:21.641 DVBSM(0)::RemovePIDFilter(0x11):
2006-10-02 21:27:21.641 DVBSM(0)::RemovePIDFilter(0x21):
2006-10-02 21:27:21.641 DVBSM(0): RunTableMonitorTS(): end
2006-10-02 21:27:21.641 DVBSM(0)::Stop: end
2006-10-02 21:27:21.643 Finished recording RV (All Day) "All Day (6:30am - 6:30am Eastern)": channel 2509
2006-10-02 21:27:21.666 Finished recording RV (All Day) "All Day (6:30am - 6:30am Eastern)": channel 2509
2006-10-02 21:27:21.673 Preview Error: Previewer file '/mythtv/2509_20061002212720.mpg' is not valid.
2006-10-02 21:27:29.691 DVBChan(0): Opening DVB channel
2006-10-02 21:27:29.691 DVBChan(0): SetChannelByString(200):
2006-10-02 21:27:29.692 DVBChan(0): Frequency: 12486440 Symbol Rate: 20000000 Pol: r Inv: Auto
2006-10-02 21:27:29.692 invalidating DeCSA for card 0
2006-10-02 21:27:29.692 DVBChan(0): Old Params: Frequency: 12457280 Symbol Rate: 20000000 Pol: r Inv: Auto
DVBChan(0): New Params: Frequency: 12486440 Symbol Rate: 20000000 Pol: r Inv: Auto
2006-10-02 21:27:29.692 DVBChan(0): Tune(): Tuning to 1236440kHz
2006-10-02 21:27:29.741 dvbchannel.cpp:wait_for_backend: Status:
2006-10-02 21:27:29.741 DVBChan(0): Tune(): Frequency tuning successful.
2006-10-02 21:27:29.742 DVBChan(0): SetChannelByString(200): Tuned to frequency.
2006-10-02 21:27:29.760 Finished recording RV (All Day) "All Day (6:30am - 6:30am Eastern)": channel 2509
0: start_time: 1787.044 duration: 0.514
1: start_time: 1786.987 duration: 0.557
2: start_time: 1786.993 duration: 0.557
stream: start_time: 19855.416 duration: 6.337 bitrate=3388 kb/s
2006-10-02 21:27:29.774 AFD: Opened codec 0x822dd10, id(MPEG2VIDEO) type(Video)
2006-10-02 21:27:29.775 AFD: Opened codec 0x82dbdc0, id(MP3) type(Audio)
2006-10-02 21:27:29.775 AFD: Opened codec 0x82dc400, id(MP3) type(Audio)
2006-10-02 21:27:29.781 DVBChan(0): Opening DVB channel
2006-10-02 21:27:29.781 SM(0)::AddFlags: Seen() Match() Wait(Sig,SNR,BER,UB,)
2006-10-02 21:27:29.781 DVBSM(0)::constructor(): initial flags 0x7400000
2006-10-02 21:27:30.084 DTVSM(0)::SetDVBService(transport_id: 19, network_id: 4100, service_id: 200):
2006-10-02 21:27:30.084 SM(0)::RemoveFlags: Seen(PMT,SDT,) Match(PMT,SDT,) Wait()
2006-10-02 21:27:30.084 SM(0)::AddFlags: Seen() Match() Wait(PMT,NIT,)
2006-10-02 21:27:30.084 SM(0)::AddFlags: Seen() Match() Wait(PMT,NIT,)
2006-10-02 21:27:30.084 SM(0)::AddFlags: Seen() Match() Wait()
2006-10-02 21:27:30.084 SM(0)::Start: begin
2006-10-02 21:27:30.085 SM(0)::Start: end
2006-10-02 21:27:30.097 DVBSM(0)::UpdateValues: Signal Locked
2006-10-02 21:27:30.097 DVBSM(0)::UpdateValues: Waiting for table monitor to start
2006-10-02 21:27:30.098 DVBSM(0)::UpdateValues: Table monitor started
2006-10-02 21:27:30.098 DTVSM(0)::GetStatusList: WaitForPMT seen(0) matching(0)
2006-10-02 21:27:30.101 DVBSM(0): RunTableMonitorTS(): begin (# of pids 3)
2006-10-02 21:27:30.102 DVBSM(0)::AddPIDFilter(0x0):
2006-10-02 21:27:30.102 DVBSM(0)::AddPIDFilter(0x10):
2006-10-02 21:27:30.102 DVBSM(0)::AddPIDFilter(0x11):
2006-10-02 21:27:30.110 SM(0)::AddFlags: Seen(PAT,) Match() Wait()
2006-10-02 21:27:30.110 SM(0)::AddFlags: Seen() Match(PAT,) Wait()
2006-10-02 21:27:30.110 DVBSM(0)::AddPIDFilter(0x21):
2006-10-02 21:27:30.116 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.116 DTVSM(0) Error: Wrong PMT; pmt->pn(36857) desired(200)
2006-10-02 21:27:30.116 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.116 DTVSM(0) Error: Wrong PMT; pmt->pn(36858) desired(200)
2006-10-02 21:27:30.134 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.134 DTVSM(0) Error: Wrong PMT; pmt->pn(36859) desired(200)
2006-10-02 21:27:30.134 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.134 DTVSM(0) Error: Wrong PMT; pmt->pn(36860) desired(200)
2006-10-02 21:27:30.134 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.134 DTVSM(0) Error: Wrong PMT; pmt->pn(36863) desired(200)
2006-10-02 21:27:30.155 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.155 DTVSM(0) Error: Wrong PMT; pmt->pn(320) desired(200)
2006-10-02 21:27:30.155 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.155 DTVSM(0) Error: Wrong PMT; pmt->pn(980) desired(200)
2006-10-02 21:27:30.155 DVB#0 CA: SetPMT() program num #200
2006-10-02 21:27:30.155 SID: 200 TID: 1 (5154 0) (5155 1)
2006-10-02 21:27:30.155 Found CA Descriptors: (24) 9 4 18 10 1 22 9 4 18 1 1 22 9 4 18 10 1 22 9 4 18 1 1 22
2006-10-02 21:27:30.156 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.156 SM(0)::AddFlags: Seen() Match(PMT,) Wait()
2006-10-02 21:27:30.177 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.177 DTVSM(0) Error: Wrong PMT; pmt->pn(202) desired(200)
2006-10-02 21:27:30.177 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.177 DTVSM(0) Error: Wrong PMT; pmt->pn(303) desired(200)
2006-10-02 21:27:30.198 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.198 DTVSM(0) Error: Wrong PMT; pmt->pn(333) desired(200)
2006-10-02 21:27:30.198 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.198 DTVSM(0) Error: Wrong PMT; pmt->pn(820) desired(200)
2006-10-02 21:27:30.198 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.198 DTVSM(0) Error: Wrong PMT; pmt->pn(821) desired(200)
2006-10-02 21:27:30.198 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.198 DTVSM(0) Error: Wrong PMT; pmt->pn(955) desired(200)
2006-10-02 21:27:30.198 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.198 DTVSM(0) Error: Wrong PMT; pmt->pn(964) desired(200)
2006-10-02 21:27:30.198 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.198 DTVSM(0) Error: Wrong PMT; pmt->pn(965) desired(200)
2006-10-02 21:27:30.219 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.219 DTVSM(0) Error: Wrong PMT; pmt->pn(978) desired(200)
2006-10-02 21:27:30.219 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.219 DTVSM(0) Error: Wrong PMT; pmt->pn(979) desired(200)
2006-10-02 21:27:30.219 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.219 DTVSM(0) Error: Wrong PMT; pmt->pn(977) desired(200)
2006-10-02 21:27:30.219 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.219 DTVSM(0) Error: Wrong PMT; pmt->pn(5500) desired(200)
2006-10-02 21:27:30.219 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.219 DTVSM(0) Error: Wrong PMT; pmt->pn(6500) desired(200)
2006-10-02 21:27:30.241 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.241 DTVSM(0) Error: Wrong PMT; pmt->pn(19030) desired(200)
2006-10-02 21:27:30.241 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.241 DTVSM(0) Error: Wrong PMT; pmt->pn(19031) desired(200)
2006-10-02 21:27:30.241 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.241 DTVSM(0) Error: Wrong PMT; pmt->pn(19032) desired(200)
2006-10-02 21:27:30.260 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.260 DTVSM(0) Error: Wrong PMT; pmt->pn(32767) desired(200)
2006-10-02 21:27:30.260 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.260 DTVSM(0) Error: Wrong PMT; pmt->pn(36850) desired(200)
2006-10-02 21:27:30.260 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.260 DTVSM(0) Error: Wrong PMT; pmt->pn(36851) desired(200)
2006-10-02 21:27:30.260 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.260 DTVSM(0) Error: Wrong PMT; pmt->pn(36854) desired(200)
2006-10-02 21:27:30.260 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.260 DTVSM(0) Error: Wrong PMT; pmt->pn(36855) desired(200)
2006-10-02 21:27:30.264 DTVSM(0)::SetNIT(): net_id = 4100
2006-10-02 21:27:30.264 SM(0)::AddFlags: Seen(NIT,) Match() Wait()
2006-10-02 21:27:30.264 DTVSM(0)::setNIT(): nid = 4100
2006-10-02 21:27:30.264 SM(0)::AddFlags: Seen() Match(NIT,) Wait()
2006-10-02 21:27:30.265 DVBSM(0)::Stop: begin
2006-10-02 21:27:30.265 SM(0)::Stop: begin
[0120-82/fe/00]
logger 0: chain caid 1810 -> Nagra2(-10) [0120-82/ff/00]
logger 0: chain caid 1820 -> Nagra2(-10) [0350-82/ff/00]
logger 0: chain caid 1821 -> Nagra2(-10) [0351-82/ff/00]
logger 0: chain caid 1822 -> Nagra2(-10) [0352-82/ff/00]
logger 0: chain caid 1823 -> Nagra2(-10) [0353-82/ff/00]
logger 0: chain caid 1824 -> Nagra2(-10) [0354-82/ff/00]
logger 0: chain caid 1825 -> Nagra2(-10) [0355-82/ff/00]
logger 0: chain caid 1826 -> Nagra2(-10) [0356-82/ff/00]
logger 0: chain caid 1827 -> Nagra2(-10) [0357-82/ff/00]
logger 0: chain caid 1828 -> Nagra2(-10) [0358-82/ff/00]
logger 0: chain caid 1829 -> Nagra2(-10) [0359-82/ff/00]
logger 0: chain caid 182a -> Nagra2(-10) [035a-82/ff/00]
logger 0: chain caid 182b -> Nagra2(-10) [035b-82/ff/00]
logger 0: chain caid 182c -> Nagra2(-10) [035c-82/ff/00]
logger 0: chain caid 182d -> Nagra2(-10) [035d-82/ff/00]
logger 0: starting chain 1810
ecmhandler 0.1: correct key found
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
logger 0: stopping chain 1810
logger 0: chain caid 1800 -> Nagra(-10) [0020-82/ff/00]
logger 0: chain caid 1801 -> Nagra2(-10) [0120-82/fe/00]
logger 0: chain caid 1810 -> Nagra2(-10) [0120-82/ff/00]
logger 0: chain caid 1820 -> Nagra2(-10) [0350-82/ff/00]
logger 0: chain caid 1821 -> Nagra2(-10) [0351-82/ff/00]
logger 0: chain caid 1822 -> Nagra2(-10) [0352-82/ff/00]
logger 0: chain caid 1823 -> Nagra2(-10) [0353-82/ff/00]
logger 0: chain caid 1824 -> Nagra2(-10) [0354-82/ff/00]
logger 0: chain caid 1825 -> Nagra2(-10) [0355-82/ff/00]
logger 0: chain caid 1826 -> Nagra2(-10) [0356-82/ff/00]
logger 0: chain caid 1827 -> Nagra2(-10) [0357-82/ff/00]
logger 0: chain caid 1828 -> Nagra2(-10) [0358-82/ff/00]
logger 0: chain caid 1829 -> Nagra2(-10) [0359-82/ff/00]
logger 0: chain caid 182a -> Nagra2(-10) [035a-82/ff/00]
logger 0: chain caid 182b -> Nagra2(-10) [035b-82/ff/00]
logger 0: chain caid 182c -> Nagra2(-10) [035c-82/ff/00]
logger 0: chain caid 182d -> Nagra2(-10) [035d-82/ff/00]
logger 0: starting chain 1810
sasc (0): got PMT (len = 24)09 04 18 10 01 22 09 04 18 01 01 22 09 04 18 10 01 22 09 04 18 01 01 22
sasc (0): corrected to (len = 24)
sasc (0): Changing channels: 24 36 24
cam 0: killing leftover pid 1922
cam 0: killing leftover pid 1924
ecmhandler 0.1: is idle now
softcam: no SoftCSA streams registered
logger 0: stopping chain 1810
ecmhandler 0.1: setting new SID 200
ecmhandler 0.1: is no longer idle
softcam: now 1 SoftCSA stream(s) registered
ecmhandler 0.1: triggered SID 509/200 idx 1/1 mode 3/0 sync
ecmcache: from cache: system Nagra2 (1810) id 0000 with ecm 122/80
ecmhandler 0.1: got CaDescriptors (len=24)
ecmhandler 0.1: descriptor 18 10 01 22
ecmhandler 0.1: found 1810 (Nagra2) id 0000 with ecm 122 (already present)
ecmhandler 0.1: descriptor 18 01 01 22
ecmhandler 0.1: found 1801 (Nagra2) id 0000 with ecm 122 (dup) (new)
ecmhandler 0.1: descriptor 18 10 01 22
ecmhandler 0.1: found 1810 (Nagra2) id 0000 with ecm 122 (already present)
ecmhandler 0.1: descriptor 18 01 01 22
ecmhandler 0.1: found 1801 (Nagra2) id 0000 with ecm 122 (dup) (already present)
ecmhandler 0.1: try system Nagra2 (1810) id 0000 with ecm 122 (cached) (pri=-10)
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt 2006-10-02 21:27:30.281 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:30.281 DTVSM(0) Error: Wrong PMT; pmt->pn(36856) desired(200)
2006-10-02 21:27:30.364 DTVSM(0)::SetNIT(): net_id = 4100
2006-10-02 21:27:30.364 SM(0)::AddFlags: Seen(NIT,) Match() Wait()
2006-10-02 21:27:30.364 DTVSM(0)::setNIT(): nid = 4100
2006-10-02 21:27:30.364 SM(0)::AddFlags: Seen() Match(NIT,) Wait()
2006-10-02 21:27:30.399 DTVSM(0)::GetStatusList: WaitForPMT seen(1) matching(1)
2006-10-02 21:27:30.399 SM(0)::Stop: end
2006-10-02 21:27:30.404 DVBSM(0): RunTableMonitorTS(): shutdown
2006-10-02 21:27:30.404 DVBSM(0)::RemovePIDFilter(0x0):
2006-10-02 21:27:30.404 DVBSM(0)::RemovePIDFilter(0x10):
2006-10-02 21:27:30.404 DVBSM(0)::RemovePIDFilter(0x11):
2006-10-02 21:27:30.404 DVBSM(0)::RemovePIDFilter(0x21):
2006-10-02 21:27:30.404 DVBSM(0): RunTableMonitorTS(): end
2006-10-02 21:27:30.405 DVBSM(0)::Stop: end
2006-10-02 21:27:30.407 Finished recording Larry King Live: channel 2200
2006-10-02 21:27:30.432 Finished recording Larry King Live: channel 2200
2006-10-02 21:27:30.439 Preview Error: Previewer file '/mythtv/2200_20061002212729.mpg' is not valid.
2006-10-02 21:27:39.408 DVBChan(0): Opening DVB channel
2006-10-02 21:27:39.408 DVBChan(0): SetChannelByString(239):
2006-10-02 21:27:39.409 DVBChan(0): Frequency: 12471860 Symbol Rate: 20000000 Pol: l Inv: Auto
2006-10-02 21:27:39.409 invalidating DeCSA for card 0
2006-10-02 21:27:39.427 DiSEqCDevTree: Changing to DiSEqC switch port 2/3
2006-10-02 21:27:39.427 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f7
2006-10-02 21:27:39.596 DVBChan(0): Old Params: Frequency: 12486440 Symbol Rate: 20000000 Pol: r Inv: Auto
DVBChan(0): New Params: Frequency: 12471860 Symbol Rate: 20000000 Pol: l Inv: Auto
2006-10-02 21:27:39.596 DVBChan(0): Tune(): Tuning to 1878140kHz
2006-10-02 21:27:39.646 dvbchannel.cpp:wait_for_backend: Status:
2006-10-02 21:27:39.646 DVBChan(0): Tune(): Frequency tuning successful.
2006-10-02 21:27:39.646 DVBChan(0): SetChannelByString(239): Tuned to frequency.
2006-10-02 21:27:39.666 Finished recording Larry King Live: channel 2200
0: start_time: 6491.313 duration: 0.640
1: start_time: 6491.280 duration: 0.648
stream: start_time: 72125.339 duration: 7.466 bitrate=3732 kb/s
2006-10-02 21:27:39.677 AFD: Opened codec 0x82a49c0, id(MPEG2VIDEO) type(Video)
2006-10-02 21:27:39.677 AFD: Opened codec 0x82a4e40, id(MP3) type(Audio)
2006-10-02 21:27:39.704 DVBChan(0): Opening DVB channel
2006-10-02 21:27:39.706 SM(0)::AddFlags: Seen() Match() Wait(Sig,SNR,BER,UB,)
2006-10-02 21:27:39.706 DVBSM(0)::constructor(): initial flags 0x7400000
2006-10-02 21:27:40.008 DTVSM(0)::SetDVBService(transport_id: 18, network_id: 4100, service_id: 239):
2006-10-02 21:27:40.008 SM(0)::RemoveFlags: Seen(PMT,SDT,) Match(PMT,SDT,) Wait()
2006-10-02 21:27:40.009 SM(0)::AddFlags: Seen() Match() Wait(PMT,NIT,)
2006-10-02 21:27:40.009 SM(0)::AddFlags: Seen() Match() Wait(PMT,NIT,)
2006-10-02 21:27:40.009 SM(0)::AddFlags: Seen() Match() Wait()
2006-10-02 21:27:40.009 SM(0)::Start: begin
2006-10-02 21:27:40.009 SM(0)::Start: end
2006-10-02 21:27:40.023 DVBSM(0)::UpdateValues: Signal Locked
2006-10-02 21:27:40.024 DVBSM(0)::UpdateValues: Waiting for table monitor to start
2006-10-02 21:27:40.024 DVBSM(0)::UpdateValues: Table monitor started
2006-10-02 21:27:40.024 DTVSM(0)::GetStatusList: WaitForPMT seen(0) matching(0)
2006-10-02 21:27:40.031 DVBSM(0): RunTableMonitorTS(): begin (# of pids 3)
2006-10-02 21:27:40.031 DVBSM(0)::AddPIDFilter(0x0):
2006-10-02 21:27:40.031 DVBSM(0)::AddPIDFilter(0x10):
2006-10-02 21:27:40.031 DVBSM(0)::AddPIDFilter(0x11):
2006-10-02 21:27:40.040 SM(0)::AddFlags: Seen(PAT,) Match() Wait()
2006-10-02 21:27:40.040 SM(0)::AddFlags: Seen() Match(PAT,) Wait()
2006-10-02 21:27:40.041 DVBSM(0)::AddPIDFilter(0x21):
2006-10-02 21:27:40.053 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.053 DTVSM(0) Error: Wrong PMT; pmt->pn(6086) desired(239)
2006-10-02 21:27:40.053 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.053 DTVSM(0) Error: Wrong PMT; pmt->pn(6080) desired(239)
2006-10-02 21:27:40.054 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.054 DTVSM(0) Error: Wrong PMT; pmt->pn(6001) desired(239)
2006-10-02 21:27:40.073 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.073 DTVSM(0) Error: Wrong PMT; pmt->pn(454) desired(239)
2006-10-02 21:27:40.073 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.073 DTVSM(0) Error: Wrong PMT; pmt->pn(546) desired(239)
2006-10-02 21:27:40.073 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.073 DTVSM(0) Error: Wrong PMT; pmt->pn(5802) desired(239)
2006-10-02 21:27:40.094 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.094 DTVSM(0) Error: Wrong PMT; pmt->pn(5814) desired(239)
2006-10-02 21:27:40.094 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.094 DTVSM(0) Error: Wrong PMT; pmt->pn(36862) desired(239)
2006-10-02 21:27:40.113 DVB#0 CA: SetPMT() program num #239
2006-10-02 21:27:40.113 SID: 239 TID: 1 (6178 0) (6179 1)
2006-10-02 21:27:40.113 Found CA Descriptors: (24) 9 4 18 10 1 2d 9 4 18 1 1 2d 9 4 18 10 1 2d 9 4 18 1 1 2d
2006-10-02 21:27:40.114 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.114 SM(0)::AddFlags: Seen() Match(PMT,) Wait()
2006-10-02 21:27:40.114 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.114 DTVSM(0) Error: Wrong PMT; pmt->pn(145) desired(239)
2006-10-02 21:27:40.114 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.114 DTVSM(0) Error: Wrong PMT; pmt->pn(418) desired(239)
2006-10-02 21:27:40.124 DTVSM(0)::SetNIT(): net_id = 4100
2006-10-02 21:27:40.124 SM(0)::AddFlags: Seen(NIT,) Match() Wait()
2006-10-02 21:27:40.124 DTVSM(0)::setNIT(): nid = 4100
2006-10-02 21:27:40.124 SM(0)::AddFlags: Seen() Match(NIT,) Wait()
2006-10-02 21:27:40.125 DVBSM(0)::Stop: begin
2006-10-02 21:27:40.125 SM(0)::Stop: begin
2006-10-02 21:27:40.134 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.134 DTVSM(0) Error: Wrong PMT; pmt->pn(424) desired(239)
2006-10-02 21:27:40.134 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.134 DTVSM(0) Error: Wrong PMT; pmt->pn(427) desired(239)
2006-10-02 21:27:40.134 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.134 DTVSM(0) Error: Wrong PMT; pmt->pn(436) desired(239)
2006-10-02 21:27:40.154 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.154 DTVSM(0) Error: Wrong PMT; pmt->pn(438) desired(239)
2006-10-02 21:27:40.154 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.154 DTVSM(0) Error: Wrong PMT; pmt->pn(446) desired(239)
2006-10-02 21:27:40.154 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.154 DTVSM(0) Error: Wrong PMT; pmt->pn(550) desired(239)
2006-10-02 21:27:40.175 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.175 DTVSM(0) Error: Wrong PMT; pmt->pn(627) desired(239)
2006-10-02 21:27:40.175 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.175 DTVSM(0) Error: Wrong PMT; pmt->pn(636) desired(239)
2006-10-02 21:27:40.175 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.175 DTVSM(0) Error: Wrong PMT; pmt->pn(881) desired(239)
2006-10-02 21:27:40.175 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.175 DTVSM(0) Error: Wrong PMT; pmt->pn(946) desired(239)
2006-10-02 21:27:40.196 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.196 DTVSM(0) Error: Wrong PMT; pmt->pn(6008) desired(239)
2006-10-02 21:27:40.196 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.196 DTVSM(0) Error: Wrong PMT; pmt->pn(6014) desired(239)
2006-10-02 21:27:40.196 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.196 DTVSM(0) Error: Wrong PMT; pmt->pn(6021) desired(239)
2006-10-02 21:27:40.196 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.197 DTVSM(0) Error: Wrong PMT; pmt->pn(6026) desired(239)
2006-10-02 21:27:40.216 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.216 DTVSM(0) Error: Wrong PMT; pmt->pn(6027) desired(239)
2006-10-02 21:27:40.216 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.216 DTVSM(0) Error: Wrong PMT; pmt->pn(6028) desired(239)
2006-10-02 21:27:40.216 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.216 DTVSM(0) Error: Wrong PMT; pmt->pn(6074) desired(239)
2006-10-02 21:27:40.216 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.216 DTVSM(0) Error: Wrong PMT; pmt->pn(6060) desired(239)
2006-10-02 21:27:40.237 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.237 DTVSM(0) Error: Wrong PMT; pmt->pn(6040) desired(239)
2006-10-02 21:27:40.237 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.237 DTVSM(0) Error: Wrong PMT; pmt->pn(6035) desired(239)
2006-10-02 21:27:40.237 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.237 DTVSM(0) Error: Wrong PMT; pmt->pn(6071) desired(239)
2006-10-02 21:27:40.237 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.237 DTVSM(0) Error: Wrong PMT; pmt->pn(6073) desired(239)
related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
system: using key N 0101 00 24D2...
logger 0: chain caid 1800 -> Nagra(-10) [0020-82/ff/00]
logger 0: chain caid 1801 -> Nagra2(-10) [0120-82/fe/00]
logger 0: chain caid 1810 -> Nagra2(-10) [0120-82/ff/00]
logger 0: chain caid 1820 -> Nagra2(-10) [0350-82/ff/00]
logger 0: chain caid 1821 -> Nagra2(-10) [0351-82/ff/00]
logger 0: chain caid 1822 -> Nagra2(-10) [0352-82/ff/00]
logger 0: chain caid 1823 -> Nagra2(-10) [0353-82/ff/00]
logger 0: chain caid 1824 -> Nagra2(-10) [0354-82/ff/00]
logger 0: chain caid 1825 -> Nagra2(-10) [0355-82/ff/00]
logger 0: chain caid 1826 -> Nagra2(-10) [0356-82/ff/00]
logger 0: chain caid 1827 -> Nagra2(-10) [0357-82/ff/00]
logger 0: chain caid 1828 -> Nagra2(-10) [0358-82/ff/00]
logger 0: chain caid 1829 -> Nagra2(-10) [0359-82/ff/00]
logger 0: chain caid 182a -> Nagra2(-10) [035a-82/ff/00]
logger 0: chain caid 182b -> Nagra2(-10) [035b-82/ff/00]
logger 0: chain caid 182c -> Nagra2(-10) [035c-82/ff/00]
logger 0: chain caid 182d -> Nagra2(-10) [035d-82/ff/00]
logger 0: starting chain 1810
ecmhandler 0.1: correct key found
logger 0: stopping chain 1810
logger 0: chain caid 1800 -> Nagra(-10) [0020-82/ff/00]
logger 0: chain caid 1801 -> Nagra2(-10) [0120-82/fe/00]
logger 0: chain caid 1810 -> Nagra2(-10) [0120-82/ff/00]
logger 0: chain caid 1820 -> Nagra2(-10) [0350-82/ff/00]
logger 0: chain caid 1821 -> Nagra2(-10) [0351-82/ff/00]
logger 0: chain caid 1822 -> Nagra2(-10) [0352-82/ff/00]
logger 0: chain caid 1823 -> Nagra2(-10) [0353-82/ff/00]
logger 0: chain caid 1824 -> Nagra2(-10) [0354-82/ff/00]
logger 0: chain caid 1825 -> Nagra2(-10) [0355-82/ff/00]
logger 0: chain caid 1826 -> Nagra2(-10) [0356-82/ff/00]
logger 0: chain caid 1827 -> Nagra2(-10) [0357-82/ff/00]
logger 0: chain caid 1828 -> Nagra2(-10) [0358-82/ff/00]
logger 0: chain caid 1829 -> Nagra2(-10) [0359-82/ff/00]
logger 0: chain caid 182a -> Nagra2(-10) [035a-82/ff/00]
logger 0: chain caid 182b -> Nagra2(-10) [035b-82/ff/00]
logger 0: chain caid 182c -> Nagra2(-10) [035c-82/ff/00]
logger 0: chain caid 182d -> Nagra2(-10) [035d-82/ff/00]
logger 0: starting chain 1810
sasc (0): got PMT (len = 24)09 04 18 10 01 2d 09 04 18 01 01 2d 09 04 18 10 01 2d 09 04 18 01 01 2d
sasc (0): corrected to (len = 24)
sasc (0): Changing channels: 24 24 24
cam 0: killing leftover pid 1422
cam 0: killing leftover pid 1423
ecmhandler 0.1: is idle now
softcam: no SoftCSA streams registered
logger 0: stopping chain 1810
ecmhandler 0.1: setting new SID 239
ecmhandler 0.1: is no longer idle
softcam: now 1 SoftCSA stream(s) registered
ecmhandler 0.1: triggered SID 200/239 idx 1/1 mode 3/0 sync
ecmcache: from cache: system Nagra2 (1810) id 0000 with ecm 12d/80
ecmhandler 0.1: got CaDescriptors (len=24)
ecmhandler 0.1: descriptor 18 10 01 2d
ecmhandler 0.1: found 1810 (Nagra2) id 0000 with ecm 12d (already present)
ecmhandler 0.1: descriptor 18 01 01 2d
ecmhandler 0.1: found 1801 (Nagra2) id 0000 with ecm 12d (dup) (new)
ecmhandler 0.1: descriptor 18 10 01 2d
ecmhandler 0.1: found 1810 (Nagra2) id 0000 with ecm 12d (already present)
ecmhandler 0.1: descriptor 18 01 01 2d
ecmhandler 0.1: found 1801 (Nagra2) id 0000 with ecm 12d (dup) (already present)
ecmhandler 0.1: try system Nagra2 (1810) id 0000 with ecm 12d (cached) (pri=-10)
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skipping interrupt related stuff
Skip2006-10-02 21:27:40.266 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-02 21:27:40.266 DTVSM(0) Error: Wrong PMT; pmt->pn(6077) desired(239)
2006-10-02 21:27:40.325 DTVSM(0)::GetStatusList: WaitForPMT seen(1) matching(1)
2006-10-02 21:27:40.326 SM(0)::Stop: end
2006-10-02 21:27:40.337 DVBSM(0): RunTableMonitorTS(): shutdown
2006-10-02 21:27:40.337 DVBSM(0)::RemovePIDFilter(0x0):
2006-10-02 21:27:40.337 DVBSM(0)::RemovePIDFilter(0x10):
2006-10-02 21:27:40.337 DVBSM(0)::RemovePIDFilter(0x11):
2006-10-02 21:27:40.337 DVBSM(0)::RemovePIDFilter(0x21):
2006-10-02 21:27:40.337 DVBSM(0): RunTableMonitorTS(): end
2006-10-02 21:27:40.338 DVBSM(0)::Stop: end
2006-10-02 21:27:40.340 Finished recording Scrubs "My Lucky Night": channel 2239
2006-10-02 21:27:40.366 Finished recording Scrubs "My Lucky Night": channel 2239
2006-10-02 21:27:40.373 Preview Error: Previewer file '/mythtv/2239_20061002212739.mpg' is not valid.
2006-10-02 21:27:56.332 TVRec(1): Changing from WatchingLiveTV to None
2006-10-02 21:27:56.442 Finished recording Scrubs "My Lucky Night": channel 2239