rendered paste body2006-10-04 22:57:49.135 Using runtime prefix = /usr/local
2006-10-04 22:57:49.152 New DB connection, total: 1
2006-10-04 22:57:49.153 Connected to database 'mythconverg' at host: localhost
2006-10-04 22:57:49.155 Current Schema Version: 1160
2006-10-04 22:57:49.162 New DB connection, total: 2
2006-10-04 22:57:49.162 Connected to database 'mythconverg' at host: localhost
2006-10-04 22:57:49.163 EITHelper: localtime offset -7:00:00
2006-10-04 22:57:50.032 DVBChan(0): Opening DVB channel
2006-10-04 22:57:50.032 DVBChan(0): Using DVB card 0, with frontend 'Genpix 8psk-USB DVB-S'.
2006-10-04 22:57:50.033 New DB connection, total: 3
2006-10-04 22:57:50.033 Connected to database 'mythconverg' at host: localhost
2006-10-04 22:57:50.034 New DB connection, total: 4
2006-10-04 22:57:50.034 Connected to database 'mythconverg' at host: localhost
2006-10-04 22:57:50.035 New DB connection, total: 5
2006-10-04 22:57:50.035 Connected to database 'mythconverg' at host: localhost
2006-10-04 22:57:50.036 New DB connection, total: 6
2006-10-04 22:57:50.036 Connected to database 'mythconverg' at host: localhost
2006-10-04 22:57:50.037 DiSEqCDevTree: Changing LNB voltage to 18V
2006-10-04 22:57:50.538 DiSEqCDevTree: Resetting DiSEqC Bus
2006-10-04 22:57:50.538 DiSEqCDevTree: Sending DiSEqC Command: e0 0 0
2006-10-04 22:57:50.822 ChannelBase(1): Input #1: 'DVBInput #1' schan(520) sourceid(1) ccid(1)
2006-10-04 22:57:50.822 ChannelBase(1): Input #2: 'DVBInput #2' schan(200) sourceid(2) ccid(1)
2006-10-04 22:57:50.822 ChannelBase(1): Input #3: 'DVBInput #3' schan(7861) sourceid(3) ccid(1)
2006-10-04 22:57:50.822 ChannelBase(1): Current Input #1: 'DVBInput #1'
2006-10-04 22:57:50.822 DVBChan(0): SetChannelByString(520):
2006-10-04 22:57:50.823 DVBChan(0): Frequency: 12413540 Symbol Rate: 20000000 Pol: l Inv: Auto
2006-10-04 22:57:50.823 invalidating DeCSA for card 0
2006-10-04 22:57:50.824 Got Key type(73) idx: 0, pid: 0
2006-10-04 22:57:50.840 DiSEqCDevTree: Changing to DiSEqC switch port 1/3
2006-10-04 22:57:50.840 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f3
2006-10-04 22:57:51.010 DVBChan(0): Old Params: Frequency: 0 Symbol Rate: 0 Pol: v Inv: Off
DVBChan(0): New Params: Frequency: 12413540 Symbol Rate: 20000000 Pol: l Inv: Auto
2006-10-04 22:57:51.010 DVBChan(0): Tune(): Tuning to 1936460kHz
2006-10-04 22:57:51.059 dvbchannel.cpp:wait_for_backend: Status:
2006-10-04 22:57:51.059 DVBChan(0): Tune(): Frequency tuning successful.
2006-10-04 22:57:51.059 DVBChan(0): SetChannelByString(520): Tuned to frequency.
2006-10-04 22:57:51.062 TVRec(1): SetFlags(RunMainLoop,) -> RunMainLoop,
2006-10-04 22:57:51.062 TVRec(1): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop,
2006-10-04 22:57:51.062 EITHelper: localtime offset -7:00:00
2006-10-04 22:57:51.930 DVBChan(1): Opening DVB channel
2006-10-04 22:57:51.930 DVBChan(1): Using DVB card 1, with frontend 'Genpix 8psk-USB DVB-S'.
2006-10-04 22:57:51.932 DiSEqCDevTree: Changing LNB voltage to 18V
2006-10-04 22:57:52.432 DiSEqCDevTree: Resetting DiSEqC Bus
2006-10-04 22:57:52.432 DiSEqCDevTree: Sending DiSEqC Command: e0 0 0
2006-10-04 22:57:52.714 ChannelBase(2): Input #4: 'DVBInput #1' schan(526) sourceid(1) ccid(2)
2006-10-04 22:57:52.714 ChannelBase(2): Input #5: 'DVBInput #2' schan(200) sourceid(2) ccid(2)
2006-10-04 22:57:52.715 ChannelBase(2): Input #6: 'DVBInput #3' schan(9901) sourceid(3) ccid(2)
2006-10-04 22:57:52.715 ChannelBase(2): Current Input #4: 'DVBInput #1'
2006-10-04 22:57:52.715 DVBChan(1): SetChannelByString(526):
2006-10-04 22:57:52.716 DVBChan(1): Frequency: 12413540 Symbol Rate: 20000000 Pol: l Inv: Auto
2006-10-04 22:57:52.716 invalidating DeCSA for card 1
2006-10-04 22:57:52.716 Got Key type(73) idx: 0, pid: 0
2006-10-04 22:57:52.731 DiSEqCDevTree: Changing to DiSEqC switch port 1/3
2006-10-04 22:57:52.731 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f3
2006-10-04 22:57:52.901 DVBChan(1): Old Params: Frequency: 0 Symbol Rate: 0 Pol: v Inv: Off
DVBChan(1): New Params: Frequency: 12413540 Symbol Rate: 20000000 Pol: l Inv: Auto
2006-10-04 22:57:52.901 DVBChan(1): Tune(): Tuning to 1936460kHz
2006-10-04 22:57:52.901 dvbchannel.cpp:wait_for_backend: Status:
2006-10-04 22:57:52.901 DVBChan(1): Tune(): Frequency tuning successful.
2006-10-04 22:57:52.901 DVBChan(1): SetChannelByString(526): Tuned to frequency.
2006-10-04 22:57:52.902 TVRec(2): SetFlags(RunMainLoop,) -> RunMainLoop,
2006-10-04 22:57:52.902 TVRec(2): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop,
2006-10-04 22:57:52.902 New DB scheduler connection
2006-10-04 22:57:52.902 Connected to database 'mythconverg' at host: localhost
2006-10-04 22:57:52.904 Main::Starting HttpServer
2006-10-04 22:57:52.905 JobQueue: RecoverQueue: Checking for unfinished jobs to recover.
2006-10-04 22:57:52.906 JobQueue: GetJobsInQueue: findJobs search bitmask 4, found 3 total jobs
2006-10-04 22:57:52.906 JobQueue: GetJobsInQueue: Ignore 'Flag Commercials' Job for 1512 @ 20061003030000 in Finished state.
2006-10-04 22:57:52.906 JobQueue: GetJobsInQueue: Ignore 'Flag Commercials' Job for 1525 @ 20061004003000 in Finished state.
2006-10-04 22:57:52.906 JobQueue: GetJobsInQueue: Ignore 'Flag Commercials' Job for 1526 @ 20061004010000 in Finished state.
2006-10-04 22:57:52.906 Main::Registering HttpStatus Extension
2006-10-04 22:57:52.907 UPnp::UPnp:Begin
2006-10-04 22:57:52.907 UPnp::UPnp:Starting TaskQueue
2006-10-04 22:57:52.907 UPnp::UPnp:Loading UPnp Description
2006-10-04 22:57:52.909 UPnp::UPnp:Starting SSDP Thread
2006-10-04 22:57:52.909 UPnp::UPnp:Adding Context Listener
2006-10-04 22:57:52.909 UPnp::UPnp:End
2006-10-04 22:57:52.909 Main::Registering UPnpCDSTv Extension
2006-10-04 22:57:52.909 Main::Registering UPnpCDSMusic Extension
2006-10-04 22:57:52.909 mythbackend version: 0.20.20060915-1 www.mythtv.org
2006-10-04 22:57:52.909 Enabled verbose msgs: all nodatabase
2006-10-04 22:57:52.909 AutoExpire: Found 2 recorders w/max rate of 277 MiB/min
2006-10-04 22:57:52.911 AutoExpire: Required Free Space: 3.2 GB w/freq: 5 min
2006-10-04 22:57:54.914 Reschedule requested for id -1.
Query 0: /record.search = :NRST AND (record.recordid = :NRRECORDID OR :NRRECORDID = -1) AND program.manualid = 0 AND program.title = record.title
2006-10-04 22:57:55.116 |-- Start DB Query 0...
2006-10-04 22:57:55.121 |-- -1 results in 0.004647 sec.
2006-10-04 22:57:55.121 +-- Done.
2006-10-04 22:57:55.121 Encoder card 1 free space 387858432
2006-10-04 22:57:55.121 Encoder card 2 free space 387858432
2006-10-04 22:57:55.122 PruneOldRecords...
2006-10-04 22:57:55.122 AddNewRecords...
2006-10-04 22:57:55.124 |-- Start DB Query...
2006-10-04 22:57:55.128 |-- 22 results in 0.003535 sec. Processing...
2006-10-04 22:57:55.131 +-- Cleanup...
2006-10-04 22:57:55.131 AddNotListed...
2006-10-04 22:57:55.132 |-- Start DB Query...
2006-10-04 22:57:55.132 |-- 0 results in 0.000499 sec. Processing...
2006-10-04 22:57:55.132 Sort by time...
2006-10-04 22:57:55.132 PruneOverlaps...
2006-10-04 22:57:55.132 Sort by priority...
2006-10-04 22:57:55.132 BuildListMaps...
2006-10-04 22:57:55.132 SchedNewRecords...
2006-10-04 22:57:55.132 Scheduling:
2006-10-04 22:57:55.132 ClearListMaps...
2006-10-04 22:57:55.132 Sort by time...
2006-10-04 22:57:55.132 PruneRedundants...
2006-10-04 22:57:55.133 Sort by time...
2006-10-04 22:57:55.133 UpdateNextRecord...
--- print list start ---
Title - Subtitle Chan ChID Day Start End C I T N Pri
--- print list end ---
2006-10-04 22:57:55.133 Scheduled 0 items in 0.2 = 0.21 match + 0.01 place
2006-10-04 22:57:55.134 Seem to be woken up by USER
2006-10-04 22:57:55.134 MythEvent: SCHEDULE_CHANGE
2006-10-04 22:58:02.907 JobQueue: ProcessQueue() started
2006-10-04 22:58:02.908 JobQueue: Currently set at 1 job(s) max and to run new jobs from 00:00 to 23:59
2006-10-04 22:58:02.908 JobQueue: GetJobsInQueue: findJobs search bitmask 4, found 3 total jobs
2006-10-04 22:58:02.908 JobQueue: GetJobsInQueue: Ignore 'Flag Commercials' Job for 1512 @ 20061003030000 in Finished state.
2006-10-04 22:58:02.909 JobQueue: GetJobsInQueue: Ignore 'Flag Commercials' Job for 1525 @ 20061004003000 in Finished state.
2006-10-04 22:58:02.909 JobQueue: GetJobsInQueue: Ignore 'Flag Commercials' Job for 1526 @ 20061004010000 in Finished state.
2006-10-04 22:58:12.905 AutoExpire: ExpireLiveTV(10000)
2006-10-04 22:58:12.906 AutoExpire: SendDeleteMessages. Nothing to expire.
2006-10-04 22:58:13.895 MythSocket(823ce28:22): new socket
2006-10-04 22:58:13.895 MythSocket(823ce28:22): setSocket: 21
2006-10-04 22:58:13.895 MythSocket(823ce28:21): state change Idle -> Connected
2006-10-04 22:58:13.895 MythSocket(823ce28:21): UpRef: 1
2006-10-04 22:58:13.895 MythSocket: readyread thread start
2006-10-04 22:58:13.895 MythSocket(823ce28:21): socket is readable
2006-10-04 22:58:13.895 MythSocket(823ce28:21): cb->readyRead()
2006-10-04 22:58:13.895 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:13.895 read <- 21 21 MYTH_PROTO_VERSION 31
2006-10-04 22:58:13.895 write -> 21 13 ACCEPT[]:[]31
2006-10-04 22:58:13.896 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:13.898 MythSocket(823ce28:21): socket is readable
2006-10-04 22:58:13.898 MythSocket(823ce28:21): cb->readyRead()
2006-10-04 22:58:13.898 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:13.898 read <- 21 22 ANN Monitor inductor 0
2006-10-04 22:58:13.898 MainServer::HandleAnnounce Monitor
2006-10-04 22:58:13.898 adding: inductor as a client (events: 0)
2006-10-04 22:58:13.898 write -> 21 2 OK
2006-10-04 22:58:13.898 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:13.900 MythSocket(823cec8:25): new socket
2006-10-04 22:58:13.900 MythSocket(823cec8:25): setSocket: 24
2006-10-04 22:58:13.900 MythSocket(823cec8:24): state change Idle -> Connected
2006-10-04 22:58:13.901 MythSocket(823cec8:24): UpRef: 1
2006-10-04 22:58:13.901 MythSocket(823cec8:24): socket is readable
2006-10-04 22:58:13.901 MythSocket(823cec8:24): cb->readyRead()
2006-10-04 22:58:13.901 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:13.901 read <- 24 22 ANN Monitor inductor 1
2006-10-04 22:58:13.901 MainServer::HandleAnnounce Monitor
2006-10-04 22:58:13.901 adding: inductor as a client (events: 1)
2006-10-04 22:58:13.901 write -> 24 2 OK
2006-10-04 22:58:13.901 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:13.903 MythSocket(823ce28:21): socket is readable
2006-10-04 22:58:13.903 MythSocket(823ce28:21): cb->readyRead()
2006-10-04 22:58:13.903 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:13.903 read <- 21 29 GET_NEXT_FREE_RECORDER[]:[]-1
2006-10-04 22:58:13.903 Getting next free recorder after : -1
2006-10-04 22:58:13.903 Checking card 1. Best card so far 1
2006-10-04 22:58:13.904 Checking card 2. Best card so far 1
2006-10-04 22:58:13.904 write -> 21 27 1[]:[]192.168.0.54[]:[]6543
2006-10-04 22:58:13.904 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:13.909 MythSocket(8256918:26): new socket
2006-10-04 22:58:13.909 MythSocket(8256918:26): setSocket: 25
2006-10-04 22:58:13.909 MythSocket(8256918:25): state change Idle -> Connected
2006-10-04 22:58:13.909 MythSocket(8256918:25): UpRef: 1
2006-10-04 22:58:13.909 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:13.909 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:13.909 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:13.909 read <- 25 21 MYTH_PROTO_VERSION 31
2006-10-04 22:58:13.909 write -> 25 13 ACCEPT[]:[]31
2006-10-04 22:58:13.910 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:13.913 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:13.913 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:13.913 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:13.913 read <- 25 23 ANN Playback inductor 0
2006-10-04 22:58:13.913 MainServer::HandleAnnounce Playback
2006-10-04 22:58:13.913 adding: inductor as a client (events: 0)
2006-10-04 22:58:13.913 write -> 25 2 OK
2006-10-04 22:58:13.913 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:13.915 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:13.915 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:13.915 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:13.915 read <- 25 77 QUERY_RECORDER 1[]:[]SPAWN_LIVETV[]:[]live-inductor-2006-10-04T22...
2006-10-04 22:58:13.916 TVRec(1): Changing from None to WatchingLiveTV
2006-10-04 22:58:13.916 TVRec(1): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,
2006-10-04 22:58:13.916 TVRec(1): SetFlags(AskAllowRecording,) -> RunMainLoop,AskAllowRecording,
2006-10-04 22:58:13.916 TVRec(1): Request: Program(no) channel() input() flags(LiveTV,)
2006-10-04 22:58:13.916 TVRec(1): HW Tuner: 1->1
2006-10-04 22:58:13.916 TVRec(1): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,AskAllowRecording,
2006-10-04 22:58:13.916 DVBChan(0): Opening DVB channel
2006-10-04 22:58:13.916 DVBChan(0): SetChannelByString(520):
2006-10-04 22:58:13.916 DVBChan(0): SetChannelByString(520): Already on channel
2006-10-04 22:58:13.916 TVRec(1): CreateLiveTVRingBuffer()
2006-10-04 22:58:13.916 TVRec(1): GetProgramRingBufferForLiveTV()
2006-10-04 22:58:13.942 TVRec(1): StartedRecording(0x823d910) fn(/mythtv/1520_20061004225813.mpg)
2006-10-04 22:58:13.942 MythEvent: RECORDING_LIST_CHANGE
2006-10-04 22:58:13.946 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:13.946 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:13.946 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:13.946 write -> 24 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2006-10-04 22:58:13.946 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:13.946 MythSocket(8256918:25): UpRef: 3
2006-10-04 22:58:13.946 MythSocket(8256918:25): DownRef: 2
2006-10-04 22:58:13.947 Chain: Appended@0 '1520_20061004225813'
2006-10-04 22:58:13.947 MythEvent: LIVETV_CHAIN UPDATE live-inductor-2006-10-04T22:51:21
2006-10-04 22:58:13.947 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:13.947 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:13.947 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:13.947 write -> 24 83 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-inductor-2006-10-04T...
2006-10-04 22:58:13.947 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:13.947 MythSocket(8256918:25): UpRef: 3
2006-10-04 22:58:13.947 MythSocket(8256918:25): DownRef: 2
2006-10-04 22:58:13.948 TVRec(1): Starting Signal Monitor
2006-10-04 22:58:13.948 TVRec(1): SetupSignalMonitor(1, 1)
2006-10-04 22:58:13.948 DVBChan(0): Opening DVB channel
2006-10-04 22:58:13.960 SM(0)::AddFlags: Seen() Match() Wait(Sig,SNR,BER,UB,)
2006-10-04 22:58:13.960 DVBSM(0)::constructor(): initial flags 0x7400000
2006-10-04 22:58:14.261 TVRec(1): Signal monitor successfully created
2006-10-04 22:58:14.261 TVRec(1): Setting up table monitoring.
2006-10-04 22:58:14.263 Using profile 'Live TV' to record
2006-10-04 22:58:14.263 TVRec(1): DVB service_id 520 on net_id 4102 tsid 214
2006-10-04 22:58:14.263 DTVSM(0)::SetDVBService(transport_id: 214, network_id: 4102, service_id: 520):
2006-10-04 22:58:14.263 SM(0)::RemoveFlags: Seen(PMT,SDT,) Match(PMT,SDT,) Wait()
2006-10-04 22:58:14.263 SM(0)::AddFlags: Seen() Match() Wait(PMT,NIT,)
2006-10-04 22:58:14.263 SM(0)::AddFlags: Seen() Match() Wait(PMT,NIT,)
2006-10-04 22:58:14.263 SM(0)::AddFlags: Seen() Match() Wait()
2006-10-04 22:58:14.264 TVRec(1): Successfully set up DVB table monitoring.
2006-10-04 22:58:14.264 SM(0)::Start: begin
2006-10-04 22:58:14.264 SM(0)::Start: end
2006-10-04 22:58:14.264 TVRec(1): SetFlags(SignalMonitorRunning,) -> RunMainLoop,AskAllowRecording,SignalMonitorRunning,
2006-10-04 22:58:14.264 TVRec(1): ClearFlags(WaitingForSignal,) -> RunMainLoop,AskAllowRecording,SignalMonitorRunning,
2006-10-04 22:58:14.264 TVRec(1): SetFlags(WaitingForSignal,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,SignalMonitorRunning,
2006-10-04 22:58:14.264 TVRec(1): SetFlags(DummyRecorderRunning,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,
2006-10-04 22:58:14.264 DummyDTVRecorder -- started
2006-10-04 22:58:14.264 TVRec(1): SetFlags(RingBufferReady,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
2006-10-04 22:58:14.264 TVRec(1): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
2006-10-04 22:58:14.264 TVRec(1): SetFlags(NeedToStartRecorder,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
2006-10-04 22:58:14.264 TVRec(1): SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
2006-10-04 22:58:14.264 write -> 25 2 ok
2006-10-04 22:58:14.264 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:14.277 DVBSM(0)::UpdateValues: Signal Locked
2006-10-04 22:58:14.277 DVBSM(0)::UpdateValues: Waiting for table monitor to start
2006-10-04 22:58:14.277 DVBSM(0)::AddPIDFilter(0x0):
2006-10-04 22:58:14.277 DVBSM(0)::RemovePIDFilter(0x0):
2006-10-04 22:58:14.278 DVBSM(0)::UpdateValues: Table monitor started
2006-10-04 22:58:14.278 DTVSM(0)::GetStatusList: WaitForPMT seen(0) matching(0)
2006-10-04 22:58:14.278 MythEvent: SIGNAL 1
2006-10-04 22:58:14.278 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:14.278 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:14.278 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:14.279 write -> 24 493 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
2006-10-04 22:58:14.279 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:14.279 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:14.279 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:14.283 DVBSM(0): RunTableMonitorTS(): begin (# of pids 3)
2006-10-04 22:58:14.284 DVBSM(0)::AddPIDFilter(0x0):
2006-10-04 22:58:14.284 DVBSM(0)::AddPIDFilter(0x10):
2006-10-04 22:58:14.284 DVBSM(0)::AddPIDFilter(0x11):
2006-10-04 22:58:14.284 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:14.284 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:14.284 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:14.284 read <- 25 33 QUERY_RECORDER 1[]:[]IS_RECORDING
2006-10-04 22:58:14.285 write -> 25 1 1
2006-10-04 22:58:14.285 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:14.286 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:14.286 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:14.286 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:14.286 read <- 25 33 QUERY_RECORDER 1[]:[]IS_RECORDING
2006-10-04 22:58:14.286 write -> 25 1 1
2006-10-04 22:58:14.286 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:14.292 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:14.292 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:14.292 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:14.292 read <- 25 34 QUERY_RECORDER 1[]:[]GET_FRAMERATE
2006-10-04 22:58:14.292 write -> 25 2 -1
2006-10-04 22:58:14.292 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:14.306 MythSocket(823ce28:21): socket is readable
2006-10-04 22:58:14.306 MythSocket(823ce28:21): cb->readyRead()
2006-10-04 22:58:14.306 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:14.306 read <- 21 33 MESSAGE[]:[]RECORDING_LIST_CHANGE
2006-10-04 22:58:14.306 MythEvent: RECORDING_LIST_CHANGE
2006-10-04 22:58:14.306 write -> 21 2 OK
2006-10-04 22:58:14.306 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:14.306 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:14.306 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:14.306 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:14.306 write -> 24 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2006-10-04 22:58:14.307 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:14.307 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:14.307 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:14.316 SM(0)::AddFlags: Seen(PAT,) Match() Wait()
2006-10-04 22:58:14.316 SM(0)::AddFlags: Seen() Match(PAT,) Wait()
2006-10-04 22:58:14.316 CreatePATSingleProgram()
2006-10-04 22:58:14.316 PAT in input stream
2006-10-04 22:58:14.316 Program Association Table
PSIP tableID(0x0) length(93) extension(0xd6)
version(15) current(1) section(0) last_section(0)
tsid: 214
programCount: 21
program number 0 has PID 0x 10 data 0x0 0x0 0x224 0x16
program number 217 has PID 0x 21 data 0x0 0x217 0x224 0x33
program number 458 has PID 0x 21 data 0x1 0x202 0x224 0x33
program number 520 has PID 0x 21 data 0x2 0x8 0x224 0x33
program number 525 has PID 0x 21 data 0x2 0x13 0x224 0x33
program number 526 has PID 0x 21 data 0x2 0x14 0x224 0x33
program number 527 has PID 0x 21 data 0x2 0x15 0x224 0x33
program number 528 has PID 0x 21 data 0x2 0x16 0x224 0x33
program number 529 has PID 0x 21 data 0x2 0x17 0x224 0x33
program number 530 has PID 0x 21 data 0x2 0x18 0x224 0x33
program number 532 has PID 0x 21 data 0x2 0x20 0x224 0x33
program number 5773 has PID 0x 21 data 0x22 0x141 0x224 0x33
program number 5830 has PID 0x 21 data 0x22 0x198 0x224 0x33
program number 5831 has PID 0x 21 data 0x22 0x199 0x224 0x33
program number 9612 has PID 0x 21 data 0x37 0x140 0x224 0x33
program number 9940 has PID 0x 21 data 0x38 0x212 0x224 0x33
program number 9941 has PID 0x 21 data 0x38 0x213 0x224 0x33
program number 9947 has PID 0x 21 data 0x38 0x219 0x224 0x33
program number 19007 has PID 0x 21 data 0x74 0x63 0x224 0x33
program number 19026 has PID 0x 21 data 0x74 0x82 0x224 0x33
program number 19053 has PID 0x 21 data 0x74 0x109 0x224 0x33
2006-10-04 22:58:14.316 desired_program(520) pid(0x21)
2006-10-04 22:58:14.316 pmt_pid(0x21)
2006-10-04 22:58:14.316 PAT for output stream
2006-10-04 22:58:14.316 Program Association Table
PSIP tableID(0x0) length(13) extension(0xd6)
version(15) current(1) section(0) last_section(0)
tsid: 214
programCount: 1
program number 1 has PID 0x 21 data 0x0 0x1 0x224 0x33
2006-10-04 22:58:14.316 DVBSM(0)::AddPIDFilter(0x21):
2006-10-04 22:58:14.326 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.326 DTVSM(0) Error: Wrong PMT; pmt->pn(5773) desired(520)
2006-10-04 22:58:14.326 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.326 DTVSM(0) Error: Wrong PMT; pmt->pn(19007) desired(520)
2006-10-04 22:58:14.345 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.345 DTVSM(0) Error: Wrong PMT; pmt->pn(217) desired(520)
2006-10-04 22:58:14.346 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.346 DTVSM(0) Error: Wrong PMT; pmt->pn(458) desired(520)
2006-10-04 22:58:14.367 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.367 SM(0)::AddFlags: Seen() Match(PMT,) Wait()
2006-10-04 22:58:14.367 CreatePMTSingleProgram()
2006-10-04 22:58:14.367 PMT in input stream
2006-10-04 22:58:14.367 Program Map Table ver(0) pid(0x21) pnum(520) len(76)
Stream #0 pid(0x1a22) type(video-mpeg2 0x2)
Conditional Access: sid(0x1810) pid(0x131) data_size(0)
Conditional Access: sid(0x1801) pid(0x131) data_size(0)
Stream #1 pid(0x1a23) type(audio-mp2-layer[1,2,3] 0x4)
Conditional Access: sid(0x1810) pid(0x131) data_size(0)
Conditional Access: sid(0x1801) pid(0x131) data_size(0)
ISO-639 Language: code(eng) canonical(eng) eng(English)
Stream #2 pid(0x1a24) type(audio-mp2-layer[1,2,3] 0x4)
Conditional Access: sid(0x1810) pid(0x131) data_size(0)
Conditional Access: sid(0x1801) pid(0x131) data_size(0)
ISO-639 Language: code(esl) canonical(esl) eng(Unknown)
2006-10-04 22:58:14.367 PMT for output stream
2006-10-04 22:58:14.367 Program Map Table ver(0) pid(0x21) pnum(1) len(28)
Stream #0 pid(0x1a22) type(video-mpeg2 0x2)
Stream #1 pid(0x1a23) type(audio-mp2-layer[1,2,3] 0x4)
Stream #2 pid(0x1a24) type(audio-mp2-layer[1,2,3] 0x4)
2006-10-04 22:58:14.367 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.367 DTVSM(0) Error: Wrong PMT; pmt->pn(525) desired(520)
2006-10-04 22:58:14.386 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.386 DTVSM(0) Error: Wrong PMT; pmt->pn(528) desired(520)
2006-10-04 22:58:14.386 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.386 DTVSM(0) Error: Wrong PMT; pmt->pn(529) desired(520)
2006-10-04 22:58:14.407 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.413 DTVSM(0) Error: Wrong PMT; pmt->pn(530) desired(520)
2006-10-04 22:58:14.413 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.413 DTVSM(0) Error: Wrong PMT; pmt->pn(532) desired(520)
2006-10-04 22:58:14.413 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.413 DTVSM(0) Error: Wrong PMT; pmt->pn(5830) desired(520)
2006-10-04 22:58:14.427 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.427 DTVSM(0) Error: Wrong PMT; pmt->pn(5831) desired(520)
2006-10-04 22:58:14.427 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.427 DTVSM(0) Error: Wrong PMT; pmt->pn(526) desired(520)
2006-10-04 22:58:14.427 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.427 DTVSM(0) Error: Wrong PMT; pmt->pn(527) desired(520)
2006-10-04 22:58:14.448 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.448 DTVSM(0) Error: Wrong PMT; pmt->pn(9612) desired(520)
2006-10-04 22:58:14.448 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.448 DTVSM(0) Error: Wrong PMT; pmt->pn(9947) desired(520)
2006-10-04 22:58:14.448 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.448 DTVSM(0) Error: Wrong PMT; pmt->pn(9940) desired(520)
2006-10-04 22:58:14.469 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.469 DTVSM(0) Error: Wrong PMT; pmt->pn(9941) desired(520)
2006-10-04 22:58:14.469 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.469 DTVSM(0) Error: Wrong PMT; pmt->pn(19026) desired(520)
2006-10-04 22:58:14.469 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
2006-10-04 22:58:14.469 DTVSM(0) Error: Wrong PMT; pmt->pn(19053) desired(520)
2006-10-04 22:58:14.579 DTVSM(0)::GetStatusList: WaitForPMT seen(1) matching(1)
2006-10-04 22:58:14.579 MythEvent: SIGNAL 1
2006-10-04 22:58:14.579 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:14.579 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:14.579 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:14.579 write -> 24 493 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
2006-10-04 22:58:14.579 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:14.579 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:14.579 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:14.757 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:14.757 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:14.778 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:14.779 Discarding broken PES packet
2006-10-04 22:58:14.798 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:14.798 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:14.817 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:14.817 Discarding broken PES packet
2006-10-04 22:58:14.838 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:14.838 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:14.857 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:14.857 Discarding broken PES packet
2006-10-04 22:58:14.879 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:14.879 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:14.880 MythEvent: SIGNAL 1
2006-10-04 22:58:14.880 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:14.880 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:14.880 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:14.880 write -> 24 493 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
2006-10-04 22:58:14.880 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:14.880 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:14.880 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:14.900 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:14.900 Discarding broken PES packet
2006-10-04 22:58:14.919 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:14.919 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:14.941 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:14.941 Discarding broken PES packet
2006-10-04 22:58:15.044 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:15.045 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:15.045 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:15.045 read <- 25 35 QUERY_RECORDER 1[]:[]FRONTEND_READY
2006-10-04 22:58:15.045 TVRec(1): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
2006-10-04 22:58:15.045 write -> 25 2 ok
2006-10-04 22:58:15.045 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:15.063 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:15.063 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:15.063 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:15.063 read <- 25 30 QUERY_RECORDER 1[]:[]GET_INPUT
2006-10-04 22:58:15.063 write -> 25 11 DVBInput #1
2006-10-04 22:58:15.063 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:15.181 MythEvent: SIGNAL 1
2006-10-04 22:58:15.181 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:15.181 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:15.181 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:15.181 write -> 24 493 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
2006-10-04 22:58:15.181 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:15.181 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:15.181 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:15.482 MythEvent: SIGNAL 1
2006-10-04 22:58:15.482 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:15.482 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:15.482 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:15.482 write -> 24 493 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
2006-10-04 22:58:15.482 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:15.482 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:15.482 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:15.525 DTVSM(0)::SetNIT(): net_id = 4102
2006-10-04 22:58:15.525 SM(0)::AddFlags: Seen(NIT,) Match() Wait()
2006-10-04 22:58:15.525 DTVSM(0)::setNIT(): nid = 4102
2006-10-04 22:58:15.525 SM(0)::AddFlags: Seen() Match(NIT,) Wait()
2006-10-04 22:58:15.628 DTVSM(0)::SetNIT(): net_id = 4102
2006-10-04 22:58:15.628 SM(0)::AddFlags: Seen(NIT,) Match() Wait()
2006-10-04 22:58:15.628 DTVSM(0)::setNIT(): nid = 4102
2006-10-04 22:58:15.628 SM(0)::AddFlags: Seen() Match(NIT,) Wait()
2006-10-04 22:58:15.749 DTVSM(0)::SetNIT(): net_id = 4102
2006-10-04 22:58:15.749 SM(0)::AddFlags: Seen(NIT,) Match() Wait()
2006-10-04 22:58:15.749 DTVSM(0)::setNIT(): nid = 4102
2006-10-04 22:58:15.749 SM(0)::AddFlags: Seen() Match(NIT,) Wait()
2006-10-04 22:58:15.783 MythEvent: SIGNAL 1
2006-10-04 22:58:15.783 TVRec(1): Got good signal
2006-10-04 22:58:15.783 TVRec(1): TeardownSignalMonitor() -- begin
2006-10-04 22:58:15.783 DVBSM(0)::Stop: begin
2006-10-04 22:58:15.783 SM(0)::Stop: begin
2006-10-04 22:58:15.783 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:15.783 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:15.783 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:15.783 write -> 24 493 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
2006-10-04 22:58:15.783 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:15.783 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:15.783 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:15.852 DTVSM(0)::SetNIT(): net_id = 4102
2006-10-04 22:58:15.852 SM(0)::AddFlags: Seen(NIT,) Match() Wait()
2006-10-04 22:58:15.852 DTVSM(0)::setNIT(): nid = 4102
2006-10-04 22:58:15.852 SM(0)::AddFlags: Seen() Match(NIT,) Wait()
2006-10-04 22:58:15.971 DTVSM(0)::SetNIT(): net_id = 4102
2006-10-04 22:58:15.971 SM(0)::AddFlags: Seen(NIT,) Match() Wait()
2006-10-04 22:58:15.971 DTVSM(0)::setNIT(): nid = 4102
2006-10-04 22:58:15.971 SM(0)::AddFlags: Seen() Match(NIT,) Wait()
2006-10-04 22:58:16.073 DTVSM(0)::SetNIT(): net_id = 4102
2006-10-04 22:58:16.073 SM(0)::AddFlags: Seen(NIT,) Match() Wait()
2006-10-04 22:58:16.073 DTVSM(0)::setNIT(): nid = 4102
2006-10-04 22:58:16.073 SM(0)::AddFlags: Seen() Match(NIT,) Wait()
2006-10-04 22:58:16.084 MythEvent: SIGNAL 1
2006-10-04 22:58:16.084 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:16.084 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:16.084 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:16.084 write -> 24 493 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
2006-10-04 22:58:16.084 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:16.084 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:16.084 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:16.084 SM(0)::Stop: end
2006-10-04 22:58:16.088 DVBSM(0): RunTableMonitorTS(): shutdown
2006-10-04 22:58:16.088 DVBSM(0)::RemovePIDFilter(0x0):
2006-10-04 22:58:16.088 DVBSM(0)::RemovePIDFilter(0x10):
2006-10-04 22:58:16.088 DVBSM(0)::RemovePIDFilter(0x11):
2006-10-04 22:58:16.089 DVBSM(0)::RemovePIDFilter(0x21):
2006-10-04 22:58:16.090 DVBSM(0): RunTableMonitorTS(): end
2006-10-04 22:58:16.090 DVBSM(0)::Stop: end
2006-10-04 22:58:16.090 TVRec(1): TeardownSignalMonitor() -- end
2006-10-04 22:58:16.090 TVRec(1): ClearFlags(SignalMonitorRunning,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady,
2006-10-04 22:58:16.090 TVRec(1): ClearFlags(WaitingForSignal,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady,
2006-10-04 22:58:16.090 EITScanner: EIT scan ignoring sourceid..
2006-10-04 22:58:16.091 TVRec(1): Starting Recorder
2006-10-04 22:58:16.091 TVRec(1): ClearFlags(DummyRecorderRunning,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,NeedToStartRecorder,RingBufferReady,
2006-10-04 22:58:16.091 TVRec(1): FinishedRecording(Bring It On: All or ... (All Day)) in recgroup: LiveTV
2006-10-04 22:58:16.092 Chain: Updated endtime for '1520_20061004225813' to 20061004225816
2006-10-04 22:58:16.092 MythEvent: LIVETV_CHAIN UPDATE live-inductor-2006-10-04T22:51:21
2006-10-04 22:58:16.092 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:16.092 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:16.092 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:16.092 write -> 24 83 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-inductor-2006-10-04T...
2006-10-04 22:58:16.092 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:16.092 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:16.092 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:16.093 Finished recording Bring It On: All or ... (All Day) "All Day (5:30am - 5:30am Eastern)": channel 1520
2006-10-04 22:58:16.095 Using profile 'Live TV' to record
2006-10-04 22:58:16.095 TVRec(1): SwitchLiveTVRingBuffer(discont 1, set_rec 0)
2006-10-04 22:58:16.095 TVRec(1): GetProgramRingBufferForLiveTV()
2006-10-04 22:58:16.102 TVRec(1): StartedRecording(0x824d638) fn(/mythtv/1520_20061004225816.mpg)
2006-10-04 22:58:16.102 MythEvent: RECORDING_LIST_CHANGE
2006-10-04 22:58:16.103 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:16.103 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:16.103 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:16.103 write -> 24 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2006-10-04 22:58:16.103 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:16.103 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:16.103 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:16.108 TVRec(1): FinishedRecording(Bring It On: All or ... (All Day)) in recgroup: LiveTV
2006-10-04 22:58:16.108 Chain: Updated endtime for '1520_20061004225813' to 20061004225816
2006-10-04 22:58:16.108 MythEvent: LIVETV_CHAIN UPDATE live-inductor-2006-10-04T22:51:21
2006-10-04 22:58:16.108 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:16.108 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:16.108 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:16.108 write -> 24 83 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-inductor-2006-10-04T...
2006-10-04 22:58:16.108 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:16.108 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:16.108 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:16.113 Finished recording Bring It On: All or ... (All Day) "All Day (5:30am - 5:30am Eastern)": channel 1520
2006-10-04 22:58:16.113 Preview: 'myth://192.168.0.54:6543/1520_20061004225813.mpg' is not local,
replacing with '/mythtv/1520_20061004225813.mpg', which is local.
2006-10-04 22:58:16.115 Chain: Appended@1 '1520_20061004225816'
2006-10-04 22:58:16.115 MythEvent: LIVETV_CHAIN UPDATE live-inductor-2006-10-04T22:51:21
2006-10-04 22:58:16.115 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:16.115 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:16.115 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:16.115 write -> 24 83 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-inductor-2006-10-04T...
2006-10-04 22:58:16.115 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:16.115 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:16.115 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:16.126 RecBase(0): SetRingBuffer(0x8293dd8) '/mythtv/1520_20061004225816.mpg'
2006-10-04 22:58:16.126 DVBRec(0): SetPAT(520 on 0x21)
2006-10-04 22:58:16.126 Got Key type(71) idx: 0, pid: 0
2006-10-04 22:58:16.126 CreatePATSingleProgram()
2006-10-04 22:58:16.126 PAT in input stream
2006-10-04 22:58:16.126 Program Association Table
PSIP tableID(0x0) length(93) extension(0xd6)
version(15) current(1) section(0) last_section(0)
tsid: 214
programCount: 21
program number 0 has PID 0x 10 data 0x0 0x0 0x224 0x16
program number 217 has PID 0x 21 data 0x0 0x217 0x224 0x33
program number 458 has PID 0x 21 data 0x1 0x202 0x224 0x33
program number 520 has PID 0x 21 data 0x2 0x8 0x224 0x33
program number 525 has PID 0x 21 data 0x2 0x13 0x224 0x33
program number 526 has PID 0x 21 data 0x2 0x14 0x224 0x33
program number 527 has PID 0x 21 data 0x2 0x15 0x224 0x33
program number 528 has PID 0x 21 data 0x2 0x16 0x224 0x33
program number 529 has PID 0x 21 data 0x2 0x17 0x224 0x33
program number 530 has PID 0x 21 data 0x2 0x18 0x224 0x33
program number 532 has PID 0x 21 data 0x2 0x20 0x224 0x33
program number 5773 has PID 0x 21 data 0x22 0x141 0x224 0x33
program number 5830 has PID 0x 21 data 0x22 0x198 0x224 0x33
program number 5831 has PID 0x 21 data 0x22 0x199 0x224 0x33
program number 9612 has PID 0x 21 data 0x37 0x140 0x224 0x33
program number 9940 has PID 0x 21 data 0x38 0x212 0x224 0x33
program number 9941 has PID 0x 21 data 0x38 0x213 0x224 0x33
program number 9947 has PID 0x 21 data 0x38 0x219 0x224 0x33
program number 19007 has PID 0x 21 data 0x74 0x63 0x224 0x33
program number 19026 has PID 0x 21 data 0x74 0x82 0x224 0x33
program number 19053 has PID 0x 21 data 0x74 0x109 0x224 0x33
2006-10-04 22:58:16.127 desired_program(520) pid(0x21)
2006-10-04 22:58:16.127 pmt_pid(0x21)
2006-10-04 22:58:16.127 PAT for output stream
2006-10-04 22:58:16.127 Program Association Table
PSIP tableID(0x0) length(13) extension(0xd6)
version(15) current(1) section(0) last_section(0)
tsid: 214
programCount: 1
program number 1 has PID 0x 21 data 0x0 0x1 0x224 0x33
2006-10-04 22:58:16.127 DVBRec(0): SetPMT(520)
2006-10-04 22:58:16.127 DVB#0 CA: SetPMT() program num #520
2006-10-04 22:58:16.127 SID: 520 TID: 1 (6690 0) (6691 1) (6692 1)
2006-10-04 22:58:16.127 Found CA Descriptors: (36) 9 4 18 10 1 31 9 4 18 1 1 31 9 4 18 10 1 31 9 4 18 1 1 31 9 4 18 10 1 31 9 4 18 1 1 31
2006-10-04 22:58:16.128 Got Key type(80) idx: 1, pid: 6690
2006-10-04 22:58:16.128 MythSocket(823ce28:21): socket is readable
2006-10-04 22:58:16.128 MythSocket(823ce28:21): cb->readyRead()
2006-10-04 22:58:16.128 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:16.128 read <- 21 33 MESSAGE[]:[]RECORDING_LIST_CHANGE
2006-10-04 22:58:16.128 MythEvent: RECORDING_LIST_CHANGE
2006-10-04 22:58:16.128 write -> 21 2 OK
2006-10-04 22:58:16.128 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:16.128 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:16.128 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:16.128 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:16.128 write -> 24 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2006-10-04 22:58:16.128 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:16.128 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:16.128 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:16.128 Got Key type(80) idx: 1, pid: 6692
2006-10-04 22:58:16.128 CreatePMTSingleProgram()
2006-10-04 22:58:16.128 PMT in input stream
2006-10-04 22:58:16.129 Program Map Table ver(0) pid(0x21) pnum(520) len(76)
Stream #0 pid(0x1a22) type(video-mpeg2 0x2)
Conditional Access: sid(0x1810) pid(0x131) data_size(0)
Conditional Access: sid(0x1801) pid(0x131) data_size(0)
Stream #1 pid(0x1a23) type(audio-mp2-layer[1,2,3] 0x4)
Conditional Access: sid(0x1810) pid(0x131) data_size(0)
Conditional Access: sid(0x1801) pid(0x131) data_size(0)
ISO-639 Language: code(eng) canonical(eng) eng(English)
Stream #2 pid(0x1a24) type(audio-mp2-layer[1,2,3] 0x4)
Conditional Access: sid(0x1810) pid(0x131) data_size(0)
Conditional Access: sid(0x1801) pid(0x131) data_size(0)
ISO-639 Language: code(esl) canonical(esl) eng(Unknown)
2006-10-04 22:58:16.129 PMT for output stream
2006-10-04 22:58:16.129 Program Map Table ver(0) pid(0x21) pnum(1) len(28)
Stream #0 pid(0x1a22) type(video-mpeg2 0x2)
Stream #1 pid(0x1a23) type(audio-mp2-layer[1,2,3] 0x4)
Stream #2 pid(0x1a24) type(audio-mp2-layer[1,2,3] 0x4)
2006-10-04 22:58:16.129 RecBase(0): SetRecording(0x823d910) title(Bring It On: All or ... (All Day))
2006-10-04 22:58:16.129 DVBRec(0): Card opened successfully fd(26)
2006-10-04 22:58:16.134 MythSocket(823ce28:21): socket is readable
2006-10-04 22:58:16.134 MythSocket(823ce28:21): cb->readyRead()
2006-10-04 22:58:16.134 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:16.134 read <- 21 33 MESSAGE[]:[]RECORDING_LIST_CHANGE
2006-10-04 22:58:16.134 MythEvent: RECORDING_LIST_CHANGE
2006-10-04 22:58:16.134 write -> 21 2 OK
2006-10-04 22:58:16.134 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:16.134 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:16.134 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:16.134 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:16.135 write -> 24 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2006-10-04 22:58:16.135 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:16.135 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:16.135 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:16.135 TVRec(1): SetFlags(RecorderRunning,RingBufferReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady,
2006-10-04 22:58:16.135 TVRec(1): ClearFlags(NeedToStartRecorder,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,RecorderRunning,RingBufferReady,
2006-10-04 22:58:16.145 DevRdB(0): buffer size 9400 KB
2006-10-04 22:58:16.145 DVBRec(0): Resetting Demux Filters
2006-10-04 22:58:16.145 DVBRec(0): Adding pid 0x0 size(94208)
2006-10-04 22:58:16.145 DVBRec(0): Adding pid 0x1a22 size(3600384)
2006-10-04 22:58:16.145 DVBRec(0): Adding pid 0x1a23 size(94208)
2006-10-04 22:58:16.145 DVBRec(0): Adding pid 0x1a24 size(94208)
2006-10-04 22:58:16.145 DVBRec(0): Adding pid 0x21 size(94208)
2006-10-04 22:58:16.145 DVBRec(0): SetOutputPAT()
Program Association Table
PSIP tableID(0x0) length(13) extension(0x1)
version(1) current(1) section(0) last_section(0)
tsid: 1
programCount: 1
program number 1 has PID 0x 21 data 0x0 0x1 0x224 0x33
2006-10-04 22:58:16.146 DVBRec(0): CreatePMT(void) INPUT
Program Map Table ver(0) pid(0x21) pnum(520) len(76)
Stream #0 pid(0x1a22) type(video-mpeg2 0x2)
Conditional Access: sid(0x1810) pid(0x131) data_size(0)
Conditional Access: sid(0x1801) pid(0x131) data_size(0)
Stream #1 pid(0x1a23) type(audio-mp2-layer[1,2,3] 0x4)
Conditional Access: sid(0x1810) pid(0x131) data_size(0)
Conditional Access: sid(0x1801) pid(0x131) data_size(0)
ISO-639 Language: code(eng) canonical(eng) eng(English)
Stream #2 pid(0x1a24) type(audio-mp2-layer[1,2,3] 0x4)
Conditional Access: sid(0x1810) pid(0x131) data_size(0)
Conditional Access: sid(0x1801) pid(0x131) data_size(0)
ISO-639 Language: code(esl) canonical(esl) eng(Unknown)
2006-10-04 22:58:16.146 Created PMT
Program Map Table ver(1) pid(0x21) pnum(1) len(40)
Stream #0 pid(0x1a22) type(video-mpeg2 0x2)
Stream #1 pid(0x1a23) type(audio-mp2-layer[1,2,3] 0x4)
ISO-639 Language: code(eng) canonical(eng) eng(English)
Stream #2 pid(0x1a24) type(audio-mp2-layer[1,2,3] 0x4)
ISO-639 Language: code(esl) canonical(esl) eng(Unknown)
2006-10-04 22:58:16.146 DVBRec(0): CreatePMT(void) OUTPUT
Program Map Table ver(1) pid(0x21) pnum(1) len(40)
Stream #0 pid(0x1a22) type(video-mpeg2 0x2)
Stream #1 pid(0x1a23) type(audio-mp2-layer[1,2,3] 0x4)
ISO-639 Language: code(eng) canonical(eng) eng(English)
Stream #2 pid(0x1a24) type(audio-mp2-layer[1,2,3] 0x4)
ISO-639 Language: code(esl) canonical(esl) eng(Unknown)
2006-10-04 22:58:16.146 DVBRec(0): SetOutputPMT()
Program Map Table ver(1) pid(0x21) pnum(1) len(40)
Stream #0 pid(0x1a22) type(video-mpeg2 0x2)
Stream #1 pid(0x1a23) type(audio-mp2-layer[1,2,3] 0x4)
ISO-639 Language: code(eng) canonical(eng) eng(English)
Stream #2 pid(0x1a24) type(audio-mp2-layer[1,2,3] 0x4)
ISO-639 Language: code(esl) canonical(esl) eng(Unknown)
2006-10-04 22:58:16.146 DVBRec(0): Adding pid 0x10 size(94208)
2006-10-04 22:58:16.146 DVBRec(0): 6 PID filters open.
2006-10-04 22:58:16.147 Preview Error: Previewer file '/mythtv/1520_20061004225813.mpg' is not valid.
2006-10-04 22:58:16.158 DVBRec(0): PID 0x1a22 discontinuity detected
2006-10-04 22:58:16.158 DVBRec(0): PID 0x21 discontinuity detected
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 31 09 04 18 01 01 31 09 04 18 10 01 31 09 04 18 01 01 31 09 04 18 10 01 31 09 04 18 01 01 31
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 520
ecmhandler 0.1: is no longer idle
softcam: now 1 SoftCSA stream(s) registered
ecmhandler 0.1: triggered SID <none>/520 idx -1/1 mode -1/0 -
ecmcache: from cache: system Nagra2 (1810) id 0000 with ecm 131/80
ecmhandler 0.1: got CaDescriptors (len=36)
ecmhandler 0.1: descriptor 18 10 01 31
ecmhandler 0.1: found 1810 (Nagra2) id 0000 with ecm 131 (already present)
ecmhandler 0.1: descriptor 18 01 01 31
ecmhandler 0.1: found 1801 (Nagra2) id 0000 with ecm 131 (dup) (new)
ecmhandler 0.1: descriptor 18 10 01 31
ecmhandler 0.1: found 1810 (Nagra2) id 0000 with ecm 131 (already present)
ecmhandler 0.1: descriptor 18 01 01 31
ecmhandler 0.1: found 1801 (Nagra2) id 0000 with ecm 131 (dup) (already present)
ecmhandler 0.1: descriptor 18 10 01 31
ecmhandler 0.1: found 1810 (Nagra2) id 0000 with ecm 131 (already present)
ecmhandler 0.1: descriptor 18 01 01 31
ecmhandler 0.1: found 1801 (Nagra2) id 0000 with ecm 131 (dup) (already present)
ecmhandler 0.1: try system Nagra2 (1810) id 0000 with ecm 131 (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 01 66B9...
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-04 22:58:16.668 Got Key type(69) idx: 1, pid: 0
2006-10-04 22:58:16.668 Got Key type(79) idx: 1, pid: 0
2006-10-04 22:58:16.969 Writing PAT & PMT @0 + 0
2006-10-04 22:58:16.969 PID 0x1a22 Found Payload Start
2006-10-04 22:58:16.969 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 5 (expected 10).
2006-10-04 22:58:16.969 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 1 (expected 6).
2006-10-04 22:58:16.981 PID 0x1a24 Found Payload Start
2006-10-04 22:58:17.065 PID 0x1a23 Found Payload Start
2006-10-04 22:58:17.827 DVBRec(0): 6 PID filters open.
2006-10-04 22:58:18.223 Writing PAT & PMT @347424 + 247408
2006-10-04 22:58:18.655 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:18.655 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:18.667 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:18.667 Discarding broken PES packet
2006-10-04 22:58:18.691 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:18.691 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:18.715 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:18.715 Discarding broken PES packet
2006-10-04 22:58:18.727 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:18.727 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:18.751 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:18.751 Discarding broken PES packet
2006-10-04 22:58:18.775 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:18.775 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:18.787 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:18.787 Discarding broken PES packet
2006-10-04 22:58:18.811 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:18.811 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:18.835 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:18.835 Discarding broken PES packet
2006-10-04 22:58:19.387 DVBRec(0): 6 PID filters open.
2006-10-04 22:58:19.615 Writing PAT & PMT @693908 + 530160
2006-10-04 22:58:20.598 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:20.598 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:20.611 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:20.611 Discarding broken PES packet
2006-10-04 22:58:20.635 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:20.635 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:20.658 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:20.658 Discarding broken PES packet
2006-10-04 22:58:20.663 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:20.663 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:20.663 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:20.663 read <- 25 39 QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN
2006-10-04 22:58:20.663 write -> 25 8 0[]:[]90
2006-10-04 22:58:20.663 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:20.664 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:20.664 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:20.664 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:20.665 read <- 25 51 QUERY_RECORDER 1[]:[]FILL_POSITION_MAP[]:[]1[]:[]90
2006-10-04 22:58:20.665 write -> 25 85 0[]:[]41[]:[]0[]:[]443868[]:[]0[]:[]74[]:[]0[]:[]838856[]:[]0[]:[...
2006-10-04 22:58:20.665 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:20.671 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:20.671 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:20.678 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:20.678 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:20.678 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:20.678 read <- 25 39 QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN
2006-10-04 22:58:20.679 write -> 25 8 0[]:[]91
2006-10-04 22:58:20.679 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:20.688 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:20.688 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:20.688 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:20.688 read <- 25 51 QUERY_RECORDER 1[]:[]FILL_POSITION_MAP[]:[]1[]:[]91
2006-10-04 22:58:20.688 write -> 25 85 0[]:[]41[]:[]0[]:[]443868[]:[]0[]:[]74[]:[]0[]:[]838856[]:[]0[]:[...
2006-10-04 22:58:20.688 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:20.695 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:20.695 Discarding broken PES packet
2006-10-04 22:58:20.719 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:20.719 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:20.731 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:20.731 Discarding broken PES packet
2006-10-04 22:58:20.760 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:20.760 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:20.784 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:20.784 Discarding broken PES packet
2006-10-04 22:58:20.844 Writing PAT & PMT @1047912 + 70688
2006-10-04 22:58:21.096 DVBRec(0): 6 PID filters open.
2006-10-04 22:58:22.236 Writing PAT & PMT @1388944 + 1378416
2006-10-04 22:58:22.542 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:22.542 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:22.554 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:22.554 Discarding broken PES packet
2006-10-04 22:58:22.578 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:22.578 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:22.602 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:22.602 Discarding broken PES packet
2006-10-04 22:58:22.614 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:22.614 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:22.638 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:22.638 Discarding broken PES packet
2006-10-04 22:58:22.662 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:22.662 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:22.674 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:22.674 Discarding broken PES packet
2006-10-04 22:58:22.698 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:22.698 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:22.710 DVBRec(0): 6 PID filters open.
2006-10-04 22:58:22.722 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:22.722 Discarding broken PES packet
2006-10-04 22:58:23.708 Writing PAT & PMT @1739376 + 600848
2006-10-04 22:58:24.352 DVBRec(0): 6 PID filters open.
2006-10-04 22:58:24.484 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:24.484 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:24.496 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:24.496 Discarding broken PES packet
2006-10-04 22:58:24.520 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:24.520 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:24.544 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:24.544 Discarding broken PES packet
2006-10-04 22:58:24.568 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:24.568 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:24.580 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:24.580 Discarding broken PES packet
2006-10-04 22:58:24.604 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:24.604 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:24.628 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:24.628 Discarding broken PES packet
2006-10-04 22:58:24.640 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:24.640 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:24.647 Got Key type(69) idx: 1, pid: 0
2006-10-04 22:58:24.664 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:24.664 Discarding broken PES packet
2006-10-04 22:58:25.240 Writing PAT & PMT @2083228 + 918944
2006-10-04 22:58:25.894 DVBRec(0): 6 PID filters open.
2006-10-04 22:58:26.427 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:26.428 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:26.451 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:26.451 Discarding broken PES packet
2006-10-04 22:58:26.475 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:26.475 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:26.487 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:26.488 Discarding broken PES packet
2006-10-04 22:58:26.511 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:26.512 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:26.535 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:26.535 Discarding broken PES packet
2006-10-04 22:58:26.559 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:26.559 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:26.571 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:26.571 Discarding broken PES packet
2006-10-04 22:58:26.584 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:26.584 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:26.607 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:26.607 Discarding broken PES packet
2006-10-04 22:58:26.643 Writing PAT & PMT @2432908 + 600848
2006-10-04 22:58:27.651 DVBRec(0): 6 PID filters open.
2006-10-04 22:58:28.371 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:28.371 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:28.383 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:28.384 Discarding broken PES packet
2006-10-04 22:58:28.407 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:28.408 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:28.431 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:28.431 Discarding broken PES packet
2006-10-04 22:58:28.443 Writing PAT & PMT @2773376 + 459472
2006-10-04 22:58:28.455 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:28.455 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:28.467 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:28.467 Discarding broken PES packet
2006-10-04 22:58:28.485 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:28.486 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:28.515 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:28.515 Discarding broken PES packet
2006-10-04 22:58:28.527 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:28.527 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:28.551 PESPacket: Failed CRC check 0xffffffff != 0x1291273f for StreamID = 0x72
2006-10-04 22:58:28.551 Discarding broken PES packet
2006-10-04 22:58:29.219 DVBRec(0): 6 PID filters open.
2006-10-04 22:58:29.787 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:29.787 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:29.787 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:29.787 read <- 25 50 QUERY_RECORDER 1[]:[]CHECK_CHANNEL_PREFIX[]:[]9467
2006-10-04 22:58:29.790 write -> 25 19 1[]:[]1[]:[]0[]:[]X
2006-10-04 22:58:29.790 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:29.791 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:29.792 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:29.792 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:29.792 read <- 25 43 QUERY_RECORDER 1[]:[]CHECK_CHANNEL[]:[]9467
2006-10-04 22:58:29.792 write -> 25 1 1
2006-10-04 22:58:29.792 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:29.796 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:29.796 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:29.796 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:29.796 read <- 25 26 QUERY_RECORDER 1[]:[]PAUSE
2006-10-04 22:58:29.796 write -> 25 2 ok
2006-10-04 22:58:29.796 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:29.798 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:29.798 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:29.799 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:29.799 read <- 25 30 QUERY_RECORDER 1[]:[]GET_INPUT
2006-10-04 22:58:29.799 write -> 25 11 DVBInput #1
2006-10-04 22:58:29.799 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:29.800 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:29.800 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:29.800 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:29.800 read <- 25 41 QUERY_RECORDER 1[]:[]SET_CHANNEL[]:[]9467
2006-10-04 22:58:29.800 TVRec(1): SetChannel() -- begin
2006-10-04 22:58:29.800 TVRec(1): ClearFlags(RingBufferReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,RecorderRunning,
2006-10-04 22:58:29.800 TVRec(1): Request: Program(no) channel(9467) input() flags(LiveTV,)
2006-10-04 22:58:29.801 TVRec(1): ClearFlags(PENDINGACTIONS,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,RecorderRunning,
2006-10-04 22:58:29.801 TVRec(1): SetFlags(WaitingForRecPause,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForRecPause,RecorderRunning,
2006-10-04 22:58:29.811 TVRec(1): ClearFlags(WaitingForRecPause,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,RecorderRunning,
2006-10-04 22:58:29.811 DVBRec(0): Close() fd(26) -- begin
2006-10-04 22:58:29.811 DVBRec(0): Close() fd(-1) -- end
2006-10-04 22:58:29.811 RecBase(0): SetRingBuffer(0)
2006-10-04 22:58:29.811 DVBChan(0): Opening DVB channel
2006-10-04 22:58:29.811 DVBChan(0): SetChannelByString(9467):
2006-10-04 22:58:29.813 DVBChan(0): Frequency: 12398960 Symbol Rate: 21500000 Pol: r Inv: Auto
2006-10-04 22:58:29.813 invalidating DeCSA for card 0
2006-10-04 22:58:29.813 Got Key type(73) idx: 0, pid: 0
2006-10-04 22:58:29.829 DiSEqCDevTree: Changing to DiSEqC switch port 1/3
2006-10-04 22:58:29.829 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f0
2006-10-04 22:58:29.999 DVBChan(0): Old Params: Frequency: 12413540 Symbol Rate: 20000000 Pol: l Inv: Auto
DVBChan(0): New Params: Frequency: 12398960 Symbol Rate: 21500000 Pol: r Inv: Auto
2006-10-04 22:58:29.999 DVBChan(0): Tune(): Tuning to 1148960kHz
2006-10-04 22:58:29.999 dvbchannel.cpp:wait_for_backend: Status:
2006-10-04 22:58:29.999 DVBChan(0): Tune(): Frequency tuning successful.
2006-10-04 22:58:29.999 DVBChan(0): SetChannelByString(9467): Tuned to frequency.
2006-10-04 22:58:29.999 TVRec(1): SwitchLiveTVRingBuffer(discont 1, set_rec 0)
2006-10-04 22:58:29.999 TVRec(1): GetProgramRingBufferForLiveTV()
2006-10-04 22:58:30.040 TVRec(1): StartedRecording(0x82727e0) fn(/mythtv/246_20061004225830.mpg)
2006-10-04 22:58:30.040 MythEvent: RECORDING_LIST_CHANGE
2006-10-04 22:58:30.043 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:30.043 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:30.043 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:30.043 write -> 24 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2006-10-04 22:58:30.043 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:30.043 MythSocket(8256918:25): UpRef: 3
2006-10-04 22:58:30.044 MythSocket(8256918:25): DownRef: 2
2006-10-04 22:58:30.045 TVRec(1): FinishedRecording(Bring It On: All or ... (All Day)) in recgroup: LiveTV
2006-10-04 22:58:30.046 Chain: Updated endtime for '1520_20061004225816' to 20061004225830
2006-10-04 22:58:30.046 MythEvent: LIVETV_CHAIN UPDATE live-inductor-2006-10-04T22:51:21
2006-10-04 22:58:30.046 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:30.046 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:30.046 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:30.046 write -> 24 83 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-inductor-2006-10-04T...
2006-10-04 22:58:30.046 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:30.046 MythSocket(8256918:25): UpRef: 3
2006-10-04 22:58:30.046 MythSocket(8256918:25): DownRef: 2
2006-10-04 22:58:30.050 Finished recording Bring It On: All or ... (All Day) "All Day (5:30am - 5:30am Eastern)": channel 1520
2006-10-04 22:58:30.051 Preview: 'myth://192.168.0.54:6543/1520_20061004225816.mpg' is not local,
replacing with '/mythtv/1520_20061004225816.mpg', which is local.
2006-10-04 22:58:30.052 Chain: Appended@2 '246_20061004225830'
2006-10-04 22:58:30.052 MythEvent: LIVETV_CHAIN UPDATE live-inductor-2006-10-04T22:51:21
2006-10-04 22:58:30.052 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:30.052 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:30.052 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:30.053 write -> 24 83 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-inductor-2006-10-04T...
2006-10-04 22:58:30.053 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:30.053 MythSocket(8256918:25): UpRef: 3
2006-10-04 22:58:30.053 MythSocket(8256918:25): DownRef: 2
2006-10-04 22:58:30.054 RingBuf(/mythtv/1520_20061004225816.mpg): OpenFile(/mythtv/1520_20061004225816.mpg, 0)
2006-10-04 22:58:30.054 RingBuf(/mythtv/1520_20061004225816.mpg): CalcReadAheadThresh(4000 KB)
-> threshhold(146 KB) min read(32 KB) blk size(64 KB)
0: start_time: 1180.165 duration: 0.976
1: start_time: 1180.121 duration: 0.985
2: start_time: 1180.114 duration: 0.998
stream: start_time: 13112.376 duration: 11.411 bitrate=2056 kb/s
2006-10-04 22:58:30.066 AFD: Stream #0, has id 0x6690 codec id MPEG2VIDEO, type Video, bitrate 15000000 at 0x0x829db40
2006-10-04 22:58:30.066 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
2006-10-04 22:58:30.067 AFD: Looking for decoder for MPEG2VIDEO
2006-10-04 22:58:30.067 AFD: Opened codec 0x829ecb0, id(MPEG2VIDEO) type(Video)
2006-10-04 22:58:30.067 AFD: Stream #1, has id 0x6691 codec id MP3, type Audio, bitrate 160000 at 0x0x829f1c0
2006-10-04 22:58:30.067 AFD: Looking for decoder for MP3
2006-10-04 22:58:30.080 AFD: Opened codec 0x82da630, id(MP3) type(Audio)
2006-10-04 22:58:30.080 AFD: Audio Track #1 is A/V stream #1 and has 2 channels in the English language(6647399).
2006-10-04 22:58:30.080 AFD: Stream #2, has id 0x6692 codec id MP3, type Audio, bitrate 160000 at 0x0x82daaf0
2006-10-04 22:58:30.080 AFD: Looking for decoder for MP3
2006-10-04 22:58:30.080 AFD: Opened codec 0x82dabe0, id(MP3) type(Audio)
2006-10-04 22:58:30.080 AFD: Audio Track #2 is A/V stream #2 and has 2 channels in the Unknown language(6648684).
2006-10-04 22:58:30.080 RingBuf(/mythtv/1520_20061004225816.mpg): CalcReadAheadThresh(15320 KB)
-> threshhold(561 KB) min read(32 KB) blk size(256 KB)
2006-10-04 22:58:30.080 AFD: Trying to select audio track (w/lang)
2006-10-04 22:58:30.080 AFD: Selected track 1: English MP2 2ch (A/V Stream #1)
2006-10-04 22:58:30.080 AFD: Initializing audio parms from audio track #1
2006-10-04 22:58:30.080 AFD: Audio format changed
from id(NONE) -1Hz -1ch 0bps ; id(NONE) -1Hz -1ch 0bps
to id( MP3) 48000Hz 2ch 16bps ; id( MP3) 48000Hz 2ch 16bps
2006-10-04 22:58:30.081 Dec: Trying to select track (w/lang)
2006-10-04 22:58:30.081 Dec: Selecting first track
2006-10-04 22:58:30.081 Dec: Selected track #1 in the Unknown language(0)
2006-10-04 22:58:30.081 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2006-10-04 22:58:30.081 Position map filled from DB to: 294
2006-10-04 22:58:30.081 SyncPositionMap prerecorded, from DB: 11 entries
2006-10-04 22:58:30.081 SyncPositionMap, new totframes: 294, new length: 9, posMap size: 11
2006-10-04 22:58:30.082 AFD: Position map found
2006-10-04 22:58:30.082 AFD: Successfully opened decoder for file: "/mythtv/1520_20061004225816.mpg". novideo(0)
2006-10-04 22:58:30.086 TVRec(1): Starting Signal Monitor
2006-10-04 22:58:30.086 TVRec(1): SetupSignalMonitor(1, 1)
2006-10-04 22:58:30.086 DVBChan(0): Opening DVB channel
2006-10-04 22:58:30.086 SM(0)::AddFlags: Seen() Match() Wait(Sig,SNR,BER,UB,)
2006-10-04 22:58:30.087 DVBSM(0)::constructor(): initial flags 0x7400000
2006-10-04 22:58:30.088 VideoOutputNull()
2006-10-04 22:58:30.088 Display Rect left: 0, top: 0, width: 0, height: 0, aspect: 1.33333
2006-10-04 22:58:30.088 Video Rect left: 0, top: 0, width: 544, height: 480, aspect: 1.33333
2006-10-04 22:58:30.088 Created data @0xa9392020->0xa93f1a22
2006-10-04 22:58:30.089 Created data @0xa8a60020->0xa8abfa22
2006-10-04 22:58:30.089 Created data @0xa8a00020->0xa8a5fa22
2006-10-04 22:58:30.089 Created data @0xa89a0020->0xa89ffa22
2006-10-04 22:58:30.089 Created data @0xa8940020->0xa899fa22
2006-10-04 22:58:30.089 Created data @0xa88e0020->0xa893fa22
2006-10-04 22:58:30.089 Created data @0xa8880020->0xa88dfa22
2006-10-04 22:58:30.089 Created data @0xa8820020->0xa887fa22
2006-10-04 22:58:30.089 Created data @0xa87c0020->0xa881fa22
2006-10-04 22:58:30.089 Created data @0xa8760020->0xa87bfa22
2006-10-04 22:58:30.089 Created data @0xa8700020->0xa875fa22
2006-10-04 22:58:30.089 Created data @0xa652d020->0xa658ca22
2006-10-04 22:58:30.089 Created data @0xa64cd020->0xa652ca22
2006-10-04 22:58:30.089 Created data @0xa646d020->0xa64cca22
2006-10-04 22:58:30.089 Created data @0xa640d020->0xa646ca22
2006-10-04 22:58:30.089 Created data @0xa63ad020->0xa640ca22
2006-10-04 22:58:30.089 Created data @0xa634d020->0xa63aca22
2006-10-04 22:58:30.089 Created data @0xa62ed020->0xa634ca22
2006-10-04 22:58:30.089 Created data @0xa628d020->0xa62eca22
2006-10-04 22:58:30.089 Created data @0xa622d020->0xa628ca22
2006-10-04 22:58:30.089 Created data @0xa61cd020->0xa622ca22
2006-10-04 22:58:30.089 Created data @0xa616d020->0xa61cca22
2006-10-04 22:58:30.089 Created data @0xa610d020->0xa616ca22
2006-10-04 22:58:30.089 Created data @0xa60ad020->0xa610ca22
2006-10-04 22:58:30.089 Created data @0xa604d020->0xa60aca22
2006-10-04 22:58:30.089 Created data @0xa5fed020->0xa604ca22
2006-10-04 22:58:30.089 Created data @0xa5f8d020->0xa5feca22
2006-10-04 22:58:30.089 Created data @0xa5f2d020->0xa5f8ca22
2006-10-04 22:58:30.089 Created data @0xa5ecd020->0xa5f2ca22
2006-10-04 22:58:30.089 Created data @0xa5e6d020->0xa5ecca22
2006-10-04 22:58:30.089 Created data @0xa5e0d020->0xa5e6ca22
2006-10-04 22:58:30.089 Created data @0xa5dad020->0xa5e0ca22
2006-10-04 22:58:30.106 Display Rect left: 0, top: 0, width: 0, height: 0, aspect: 1.33333
2006-10-04 22:58:30.106 Video Rect left: 0, top: 0, width: 544, height: 480, aspect: 1.33333
2006-10-04 22:58:30.107 NVP: ClearAfterSeek(1)
2006-10-04 22:58:30.113 AFD: video timecode 63020 51008 566 274
2006-10-04 22:58:30.113 AFD: DoFastForward(146 (1), do discard frames)
2006-10-04 22:58:30.113 Dec: DoFastForward(146 (1), do discard frames)
2006-10-04 22:58:30.113 AFD: SeekReset(150, 0, do flush, do discard)
2006-10-04 22:58:30.113 AFD: SeekReset() flushing
2006-10-04 22:58:30.113 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2006-10-04 22:58:30.113 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2006-10-04 22:58:30.113 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2006-10-04 22:58:30.113 NVP: ClearAfterSeek(0)
2006-10-04 22:58:30.119 AFD: video timecode 626083 614071 6823 6359
2006-10-04 22:58:30.124 ~VideoOutputNull()
2006-10-04 22:58:30.388 TVRec(1): Signal monitor successfully created
2006-10-04 22:58:30.388 TVRec(1): Setting up table monitoring.
2006-10-04 22:58:30.389 Using profile 'Live TV' to record
2006-10-04 22:58:30.390 TVRec(1): DVB service_id 9467 on net_id 4102 tsid 213
2006-10-04 22:58:30.390 DTVSM(0)::SetDVBService(transport_id: 213, network_id: 4102, service_id: 9467):
2006-10-04 22:58:30.390 SM(0)::RemoveFlags: Seen(PMT,SDT,) Match(PMT,SDT,) Wait()
2006-10-04 22:58:30.390 SM(0)::AddFlags: Seen() Match() Wait(PMT,NIT,)
2006-10-04 22:58:30.390 SM(0)::AddFlags: Seen() Match() Wait(PMT,NIT,)
2006-10-04 22:58:30.390 SM(0)::AddFlags: Seen() Match() Wait()
2006-10-04 22:58:30.390 TVRec(1): Successfully set up DVB table monitoring.
2006-10-04 22:58:30.390 SM(0)::Start: begin
2006-10-04 22:58:30.391 SM(0)::Start: end
2006-10-04 22:58:30.391 TVRec(1): SetFlags(SignalMonitorRunning,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,SignalMonitorRunning,RecorderRunning,
2006-10-04 22:58:30.391 TVRec(1): ClearFlags(WaitingForSignal,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,SignalMonitorRunning,RecorderRunning,
2006-10-04 22:58:30.391 TVRec(1): SetFlags(WaitingForSignal,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,SignalMonitorRunning,RecorderRunning,
2006-10-04 22:58:30.391 RecBase(0): SetRingBuffer(0)
2006-10-04 22:58:30.391 TVRec(1): SetFlags(DummyRecorderRunning,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,
2006-10-04 22:58:30.391 DummyDTVRecorder -- started
2006-10-04 22:58:30.391 TVRec(1): SetFlags(RingBufferReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady,
2006-10-04 22:58:30.391 TVRec(1): ClearFlags(NeedToStartRecorder,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady,
2006-10-04 22:58:30.391 TVRec(1): SetFlags(NeedToStartRecorder,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady,
2006-10-04 22:58:30.391 TVRec(1): SetChannel() -- end
2006-10-04 22:58:30.391 write -> 25 2 ok
2006-10-04 22:58:30.391 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:30.391 DTVSM(0)::GetStatusList: WaitForPMT seen(0) matching(0)
2006-10-04 22:58:30.392 MythEvent: SIGNAL 1
2006-10-04 22:58:30.392 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:30.392 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:30.392 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:30.392 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:30.392 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:30.392 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:30.392 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:30.494 MythSocket(823ce28:21): socket is readable
2006-10-04 22:58:30.494 MythSocket(823ce28:21): cb->readyRead()
2006-10-04 22:58:30.494 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:30.494 read <- 21 33 MESSAGE[]:[]RECORDING_LIST_CHANGE
2006-10-04 22:58:30.494 MythEvent: RECORDING_LIST_CHANGE
2006-10-04 22:58:30.494 write -> 21 2 OK
2006-10-04 22:58:30.494 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:30.494 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:30.494 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:30.494 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:30.494 write -> 24 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2006-10-04 22:58:30.494 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:30.494 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:30.494 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:30.504 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:30.504 MythSocket(8256918:25): cb->readyRead()
2006-10-04 22:58:30.504 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:30.504 read <- 25 30 QUERY_RECORDER 1[]:[]GET_INPUT
2006-10-04 22:58:30.504 write -> 25 11 DVBInput #1
2006-10-04 22:58:30.504 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:30.510 MythSocket(823ce28:21): socket is readable
2006-10-04 22:58:30.510 MythSocket(823ce28:21): cb->readyRead()
2006-10-04 22:58:30.510 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:30.510 read <- 21 33 MESSAGE[]:[]RECORDING_LIST_CHANGE
2006-10-04 22:58:30.510 MythEvent: RECORDING_LIST_CHANGE
2006-10-04 22:58:30.510 write -> 21 2 OK
2006-10-04 22:58:30.510 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:30.510 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:30.510 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:30.510 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:30.510 write -> 24 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2006-10-04 22:58:30.510 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:30.510 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:30.510 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:30.693 MythEvent: SIGNAL 1
2006-10-04 22:58:30.693 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:30.693 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:30.693 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:30.693 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:30.693 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:30.693 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:30.693 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:30.994 MythEvent: SIGNAL 1
2006-10-04 22:58:30.994 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:30.994 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:30.994 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:30.994 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:30.994 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:30.994 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:30.994 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:31.295 MythEvent: SIGNAL 1
2006-10-04 22:58:31.295 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:31.295 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:31.295 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:31.295 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:31.295 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:31.295 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:31.296 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:31.596 MythEvent: SIGNAL 1
2006-10-04 22:58:31.596 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:31.596 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:31.596 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:31.596 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:31.596 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:31.596 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:31.596 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:31.897 MythEvent: SIGNAL 1
2006-10-04 22:58:31.897 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:31.897 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:31.897 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:31.897 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:31.897 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:31.897 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:31.897 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:32.198 MythEvent: SIGNAL 1
2006-10-04 22:58:32.198 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:32.198 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:32.198 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:32.198 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:32.198 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:32.198 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:32.198 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:32.499 MythEvent: SIGNAL 1
2006-10-04 22:58:32.499 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:32.499 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:32.499 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:32.499 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:32.499 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:32.499 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:32.499 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:32.800 MythEvent: SIGNAL 1
2006-10-04 22:58:32.800 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:32.800 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:32.800 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:32.800 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:32.800 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:32.800 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:32.800 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:33.101 MythEvent: SIGNAL 1
2006-10-04 22:58:33.101 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:33.101 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:33.101 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:33.101 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:33.101 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:33.101 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:33.101 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:33.444 MythEvent: SIGNAL 1
2006-10-04 22:58:33.444 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:33.444 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:33.444 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:33.444 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:33.444 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:33.444 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:33.444 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:33.745 MythEvent: SIGNAL 1
2006-10-04 22:58:33.745 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:33.745 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:33.745 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:33.745 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:33.745 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:33.745 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:33.745 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:34.046 MythEvent: SIGNAL 1
2006-10-04 22:58:34.046 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:34.046 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:34.046 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:34.046 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:34.046 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:34.046 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:34.046 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:34.347 MythEvent: SIGNAL 1
2006-10-04 22:58:34.347 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:34.347 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:34.347 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:34.347 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:34.347 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:34.347 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:34.347 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:34.647 MythEvent: SIGNAL 1
2006-10-04 22:58:34.648 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:34.648 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:34.648 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:34.648 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:34.648 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:34.648 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:34.648 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:34.948 MythEvent: SIGNAL 1
2006-10-04 22:58:34.949 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:34.949 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:34.949 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:34.949 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:34.949 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:34.949 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:34.949 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:35.249 MythEvent: SIGNAL 1
2006-10-04 22:58:35.250 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:35.250 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:35.250 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:35.250 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:35.250 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:35.250 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:35.250 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:35.550 MythEvent: SIGNAL 1
2006-10-04 22:58:35.550 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:35.551 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:35.551 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:35.551 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:35.551 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:35.551 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:35.551 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:35.851 MythEvent: SIGNAL 1
2006-10-04 22:58:35.851 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:35.852 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:35.852 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:35.852 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:35.852 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:35.852 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:35.852 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:36.152 MythEvent: SIGNAL 1
2006-10-04 22:58:36.152 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:36.152 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:36.152 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:36.153 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:36.153 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:36.153 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:36.153 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:36.453 MythEvent: SIGNAL 1
2006-10-04 22:58:36.453 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:36.453 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:36.453 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:36.453 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:36.454 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:36.454 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:36.454 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:36.754 MythEvent: SIGNAL 1
2006-10-04 22:58:36.754 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:36.754 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:36.754 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:36.754 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:36.754 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:36.755 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:36.755 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:37.055 MythEvent: SIGNAL 1
2006-10-04 22:58:37.055 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:37.055 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:37.055 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:37.055 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:37.055 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:37.056 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:37.056 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:37.356 MythEvent: SIGNAL 1
2006-10-04 22:58:37.356 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:37.356 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:37.356 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:37.356 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:37.356 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:37.356 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:37.357 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:37.688 MythEvent: SIGNAL 1
2006-10-04 22:58:37.688 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:37.688 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:37.688 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:37.688 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:37.688 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:37.688 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:37.688 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:37.989 MythEvent: SIGNAL 1
2006-10-04 22:58:37.989 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:37.989 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:37.989 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:37.989 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:37.989 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:37.989 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:37.989 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:38.290 MythEvent: SIGNAL 1
2006-10-04 22:58:38.290 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:38.290 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:38.290 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:38.290 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:38.290 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:38.290 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:38.290 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:38.591 MythEvent: SIGNAL 1
2006-10-04 22:58:38.591 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:38.591 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:38.591 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:38.591 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:38.591 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:38.591 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:38.591 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:38.892 MythEvent: SIGNAL 1
2006-10-04 22:58:38.892 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:38.892 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:38.892 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:38.892 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:38.892 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:38.892 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:38.892 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:39.193 MythEvent: SIGNAL 1
2006-10-04 22:58:39.193 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:39.193 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:39.193 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:39.193 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:39.193 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:39.193 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:39.193 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:39.494 MythEvent: SIGNAL 1
2006-10-04 22:58:39.494 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:39.494 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:39.494 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:39.494 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:39.494 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:39.494 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:39.494 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:39.795 MythEvent: SIGNAL 1
2006-10-04 22:58:39.795 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:39.795 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:39.795 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:39.795 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:39.795 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:39.795 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:39.795 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:40.096 MythEvent: SIGNAL 1
2006-10-04 22:58:40.096 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:40.096 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:40.096 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:40.096 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:40.096 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:40.096 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:40.096 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:40.397 MythEvent: SIGNAL 1
2006-10-04 22:58:40.397 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:40.397 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:40.397 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:40.397 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:40.397 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:40.397 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:40.397 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:40.698 MythEvent: SIGNAL 1
2006-10-04 22:58:40.698 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:40.698 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:40.698 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:40.698 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:40.698 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:40.698 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:40.698 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:40.999 MythEvent: SIGNAL 1
2006-10-04 22:58:40.999 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:40.999 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:40.999 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:40.999 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:40.999 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:40.999 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:40.999 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:41.300 MythEvent: SIGNAL 1
2006-10-04 22:58:41.300 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:41.300 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:41.300 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:41.300 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:41.300 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:41.300 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:41.300 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:41.600 MythEvent: SIGNAL 1
2006-10-04 22:58:41.601 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:41.601 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:41.601 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:41.601 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:41.601 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:41.601 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:41.601 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:41.932 MythEvent: SIGNAL 1
2006-10-04 22:58:41.932 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:41.932 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:41.933 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:41.933 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:41.933 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:41.933 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:41.933 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:42.233 MythEvent: SIGNAL 1
2006-10-04 22:58:42.233 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:42.233 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:42.233 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:42.234 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:42.234 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:42.234 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:42.234 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:42.534 MythEvent: SIGNAL 1
2006-10-04 22:58:42.534 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:42.535 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:42.535 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:42.535 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:42.535 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:42.535 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:42.535 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:42.835 MythEvent: SIGNAL 1
2006-10-04 22:58:42.835 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:42.835 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:42.835 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:42.835 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:42.835 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:42.836 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:42.836 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:43.136 MythEvent: SIGNAL 1
2006-10-04 22:58:43.136 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:43.136 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:43.136 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:43.136 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:43.136 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:43.136 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:43.137 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:43.437 MythEvent: SIGNAL 1
2006-10-04 22:58:43.437 MythSocket(823ce28:21): UpRef: 2
2006-10-04 22:58:43.437 MythSocket(823ce28:21): DownRef: 1
2006-10-04 22:58:43.437 MythSocket(823cec8:24): UpRef: 2
2006-10-04 22:58:43.437 write -> 24 489 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 0 1 0 1 30...
2006-10-04 22:58:43.438 MythSocket(823cec8:24): DownRef: 1
2006-10-04 22:58:43.438 MythSocket(8256918:25): UpRef: 2
2006-10-04 22:58:43.438 MythSocket(8256918:25): DownRef: 1
2006-10-04 22:58:43.602 MythSocket(823cec8:24): socket is readable
2006-10-04 22:58:43.602 MythSocket(823cec8:24): socket closed
2006-10-04 22:58:43.602 MythSocket(823cec8:24): state change Connected -> Idle
2006-10-04 22:58:43.602 MythSocket(823cec8:-1): cb->connectionClosed()
2006-10-04 22:58:43.603 MythSocket(823ce28:21): socket is readable
2006-10-04 22:58:43.603 MythSocket(823ce28:21): socket closed
2006-10-04 22:58:43.603 MythSocket(823ce28:21): state change Connected -> Idle
2006-10-04 22:58:43.603 MythSocket(823ce28:-1): cb->connectionClosed()
2006-10-04 22:58:43.603 MythSocket(8256918:25): socket is readable
2006-10-04 22:58:43.603 MythSocket(8256918:25): socket closed
2006-10-04 22:58:43.603 MythSocket(8256918:25): state change Connected -> Idle
2006-10-04 22:58:43.603 MythSocket(8256918:-1): cb->connectionClosed()
2006-10-04 22:58:43.603 StopLiveTV(void) curRec: 0x82727e0 pseudoRec: 0
2006-10-04 22:58:43.604 TVRec(1): Changing from WatchingLiveTV to None
2006-10-04 22:58:43.604 TVRec(1): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady,
2006-10-04 22:58:43.604 TVRec(1): SetFlags(AskAllowRecording,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady,
2006-10-04 22:58:43.604 TVRec(1): Request: Program(no) channel() input() flags(KillRec,KillRingBuffer,)
2006-10-04 22:58:43.604 TVRec(1): TeardownSignalMonitor() -- begin
2006-10-04 22:58:43.604 DVBSM(0)::Stop: begin
2006-10-04 22:58:43.604 SM(0)::Stop: begin
2006-10-04 22:58:43.738 MythEvent: SIGNAL 1
2006-10-04 22:58:43.738 MythSocket(8256918:-1): UpRef: 2
2006-10-04 22:58:43.738 MythSocket(8256918:-1): DownRef: 1
2006-10-04 22:58:43.738 SM(0)::Stop: end
2006-10-04 22:58:43.738 DVBSM(0)::Stop: end
2006-10-04 22:58:43.738 TVRec(1): TeardownSignalMonitor() -- end
2006-10-04 22:58:43.738 TVRec(1): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady,
2006-10-04 22:58:43.738 TVRec(1): ClearFlags(WaitingForSignal,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady,
2006-10-04 22:58:43.738 TVRec(1): ClearFlags(DummyRecorderRunning,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady,
2006-10-04 22:58:43.739 TVRec(1): FinishedRecording(Unknown) in recgroup: LiveTV
2006-10-04 22:58:43.739 Chain: Updated endtime for '246_20061004225830' to 20061004225843
2006-10-04 22:58:43.739 MythEvent: LIVETV_CHAIN UPDATE live-inductor-2006-10-04T22:51:21
2006-10-04 22:58:43.740 MythSocket(8256918:-1): UpRef: 2
2006-10-04 22:58:43.740 MythSocket(8256918:-1): DownRef: 1
2006-10-04 22:58:43.740 Finished recording Unknown: channel 246
2006-10-04 22:58:43.740 MythEvent: DONE_RECORDING 1 10
2006-10-04 22:58:43.740 MythSocket(8256918:-1): UpRef: 2
2006-10-04 22:58:43.740 MythSocket(8256918:-1): DownRef: 1
2006-10-04 22:58:43.779 DVBRec(0): Close() fd(-1) -- begin
2006-10-04 22:58:43.779 DVBRec(0): Close() fd(-1) -- end
2006-10-04 22:58:43.782 TVRec(1): ClearFlags(RecorderRunning,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,RingBufferReady,
2006-10-04 22:58:43.782 DVBRec(0): SetOutputPAT(NULL)
2006-10-04 22:58:43.782 DVBRec(0): SetOutputPMT(NULL)
2006-10-04 22:58:43.783 TVRec(1): FinishedRecording(Unknown) in recgroup: LiveTV
2006-10-04 22:58:43.783 Chain: Updated endtime for '246_20061004225830' to 20061004225843
2006-10-04 22:58:43.784 MythEvent: LIVETV_CHAIN UPDATE live-inductor-2006-10-04T22:51:21
2006-10-04 22:58:43.784 MythSocket(8256918:-1): UpRef: 2
2006-10-04 22:58:43.784 MythSocket(8256918:-1): DownRef: 1
2006-10-04 22:58:43.784 Finished recording Unknown: channel 246
2006-10-04 22:58:43.785 MythEvent: RECORDING_LIST_CHANGE
2006-10-04 22:58:43.785 MythSocket(8256918:-1): UpRef: 2
2006-10-04 22:58:43.785 MythSocket(8256918:-1): DownRef: 1
2006-10-04 22:58:43.785 TVRec(1): ClearFlags(RecorderRunning,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,RingBufferReady,
2006-10-04 22:58:43.785 TVRec(1): Tearing down RingBuffer
2006-10-04 22:58:43.785 TVRec(1): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,AskAllowRecording,RingBufferReady,