2006-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 /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