Failed Recording, XML file but no .ts [Recordings ending with . fail] (1 Viewer)

R

revs

MP Donator
  • Premium Supporter
    • February 1, 2007
    1,272
    72
    The Sauce of Worcester
    Home Country
    Wales Wales
    TV-Server Version: RC 4
    MediaPortal Version: RC 4
    MediaPortal Skin: StreamedMP
    Windows Version: XP SP3
    CPU Type: AMD X2 3800+
    HDD: 2x 1TB WD GP/250GB Seagate
    Memory: 2GB
    Motherboard: Gigabyte GA-K8N-Ultra 9
    Video Card: Sapphire ATI HD 3450 512MB
    Video Card Driver: Latest (cur. 8.8)
    Sound Card:
    Sound Card AC3: Coaxial Out / SPDIF
    Sound Card Driver:
    1. TV Card: Hauppage Nova-T
    1. TV Card Type: DVB-T
    1. TV Card Driver:
    2. TV Card: Hauppage Nova-S2-HD
    2. TV Card Type: DVB-S2
    2. TV Card Driver:
    3. TV Card: Pinnacle 2000i Pro Dual DVB-T
    3. TV Card Type: DVB-T (dual)
    3. TV Card Driver:
    4. TV Card:
    4. TV Card Type:
    4. TV Card Driver:
    MPEG2 Video Codec: Cyberlink Power DVD 8
    MPEG2 Audio Codec: Cyberlink Power DVD 8
    h.264 Video Codec: Cyberlink Power DVD 8
    Satelite/CableTV Provider: Astra 2D/2E / Freesat
    HTPC Case: Antec Mid-Tower
    Cooling: Artic Cooling fan on CPU, Seasonic PSU with 120mm fan
    Power Supply: Seasonic
    Remote: Logitech Harmony 555
    TV: Samsung 40" M87
    TV - HTPC Connection: DVI --> HDMI



    A recording scheduled for yesterday failed to record.

    I had an XML file, but not .ts file.

    I am at work, so have to just past the log text directly (as opposed to adding files as i should)

    TSWriter log:

    Code: 
    17-12-2008 20:55:03.562 del m_pVideoAnalyzer
17-12-2008 20:55:03.562 analyzer: reset
17-12-2008 20:55:03.562 del m_pPmtGrabber
17-12-2008 20:55:03.562 del m_pRecorder
17-12-2008 20:55:03.562 del m_pTimeShifting
17-12-2008 20:55:03.562 del m_pTeletextGrabber
17-12-2008 20:55:03.562 del m_pCaGrabber
17-12-2008 20:55:03.562 del done...
17-12-2008 20:55:03.562 CMpTsFilter::Pause()
17-12-2008 20:55:03.562 CMpTsFilter::Stop()
17-12-2008 20:55:03.671 analyzer: reset
17-12-2008 20:55:03.671 cagrabber: reset
17-12-2008 20:55:03.687 epg: reset
17-12-2008 20:55:03.687 epg:ResetEPG()
17-12-2008 20:55:03.687 mhw reset
17-12-2008 20:55:04.156 CMpTsFilter::Pause()
17-12-2008 20:55:04.843 CMpTsFilter::Run()
17-12-2008 20:55:04.843 pmtgrabber: set callback:d7ff38
17-12-2008 20:55:04.843 pmtgrabber: grab pmt:108 sid:20c0
17-12-2008 20:55:04.859 service_id=8384
17-12-2008 20:55:04.859 pmtgrabber: got pmt 108 sid:20c0
17-12-2008 20:55:04.859 pmtgrabber: got pmt version:4 -1
17-12-2008 20:55:04.859 pmtgrabber: do calback
17-12-2008 20:55:04.890 analyzer: set video pid:230
17-12-2008 20:55:04.906 analyzer: reset
17-12-2008 20:55:04.906 analyzer: set audio pid:231
17-12-2008 20:55:04.906 analyzer: reset
17-12-2008 20:55:06.640 Recorder: RECORD    transport stream mode
17-12-2008 20:55:06.640 Recorder: RECORD    set filename:E:\Unsorted Recordings\Willie's Perfect Chocolate...\Willie's Perfect Chocolate... - Channel 4 - 2008-12-17.ts
17-12-2008 20:55:06.656 Recorder: RECORD    Received from TvService: pmt pid:0x108 serviceId: 0x20c0 pmtlength:165
17-12-2008 20:55:06.656 Recorder: RECORD    Old pids cleared
17-12-2008 20:55:06.656 Recorder: RECORD    got pmt - tableid: 0x2 section_length: 165 sid: 0x20c0
17-12-2008 20:55:06.656 Recorder: RECORD    PMT parsed  - Pid 0x108 ServiceId 0x20c0 stream count: 8
17-12-2008 20:55:06.656 Recorder: RECORD    pcr pid:0x1ffe
17-12-2008 20:55:06.656 Recorder: RECORD    SetPcrPid clear old PIDs
17-12-2008 20:55:06.656 Recorder: RECORD    add video stream pid: 0x230 fake pid: 0x30 stream type: 0x2 logical type: 0x2 descriptor length: 16
17-12-2008 20:55:06.656 Recorder: RECORD    add audio stream pid: 0x231 fake pid: 0x40 stream type: 0x4 logical type: 0x4 descriptor length: 17
17-12-2008 20:55:06.656 Recorder: RECORD    add audio stream pid: 0x232 fake pid: 0x41 stream type: 0x4 logical type: 0x4 descriptor length: 17
17-12-2008 20:55:06.656 Recorder: RECORD    add subtitle stream pid: 0x233 fake pid: 0x50 stream type: 0x6 logical type: 0x6 descriptor length: 15
17-12-2008 20:55:06.656 Recorder: RECORD    stream rejected - pid: 0x834 stream type: 0xb logical type: 0xb descriptor length: 8
17-12-2008 20:55:06.656 Recorder: RECORD    stream rejected - pid: 0x8fe stream type: 0xb logical type: 0xb descriptor length: 8
17-12-2008 20:55:06.656 Recorder: RECORD    stream rejected - pid: 0x8fd stream type: 0xb logical type: 0xb descriptor length: 8
17-12-2008 20:55:06.656 Recorder: RECORD    stream rejected - pid: 0x8ff stream type: 0xb logical type: 0xb descriptor length: 23
17-12-2008 20:55:06.656 Recorder:unable to create file:'E:\Unsorted Recordings\Willie's Perfect Chocolate...\Willie's Perfect Chocolate... - Channel 4 - 2008-12-17.ts' 3
17-12-2008 21:00:31.250 epg: abort grabbing
17-12-2008 21:00:31.250 mhw abort grabbing
17-12-2008 21:00:31.250 epg: do callback
17-12-2008 21:00:31.265 mhw grabber ended
17-12-2008 21:00:31.265 CMhwParser:GetTitleCount:0
17-12-2008 21:00:31.265 EpgScanner::GetMHWTitleCount:0
17-12-2008 21:00:31.265 epg: reset
17-12-2008 21:00:31.265 epg:ResetEPG()
17-12-2008 21:00:31.265 mhw reset
17-12-2008 22:05:14.687 Recorder: RECORD    Stop 'E:\Unsorted Recordings\Willie's Perfect Chocolate...\Willie's Perfect Chocolate... - Channel 4 - 2008-12-17.ts'
17-12-2008 22:05:14.687 Recorder: RECORD    Reset
17-12-2008 22:05:14.687 del m_pVideoAnalyzer
17-12-2008 22:05:14.687 analyzer: reset
17-12-2008 22:05:14.687 del m_pPmtGrabber
17-12-2008 22:05:14.687 del m_pRecorder
17-12-2008 22:05:14.687 del m_pTimeShifting
17-12-2008 22:05:14.687 del m_pTeletextGrabber
17-12-2008 22:05:14.687 del m_pCaGrabber
17-12-2008 22:05:14.687 del done...
17-12-2008 22:05:14.703 CMpTsFilter::Pause()
17-12-2008 22:05:14.703 CMpTsFilter::Stop()

    It was a record once item.


    and here is the tv.log

    Code: 
    2008-12-17 20:50:26.687500 [12]: **************************************************
2008-12-17 20:50:26.687500 [12]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 94 *****
2008-12-17 20:50:26.687500 [12]: **************************************************
2008-12-17 20:50:26.687500 [12]: card: tuned user: epg subchannel: 0
2008-12-17 20:50:26.687500 [12]: user:epg add
2008-12-17 20:50:26.703125 [DVB EPG timer]: dvb:grab epg...
2008-12-17 20:50:31.406250 [12]: Recording Willie's Perfect Chocolate...  added in _recordingsInProgressList
2008-12-17 20:50:31.484375 [4]: Recording Willie's Perfect Chocolate...  added in _recordingsInProgressList
2008-12-17 20:50:46.390625 [10]: Recording Willie's Perfect Chocolate...  added in _recordingsInProgressList
2008-12-17 20:50:46.468750 [14]: Recording Willie's Perfect Chocolate...  added in _recordingsInProgressList
2008-12-17 20:51:01.390625 [14]: Recording Willie's Perfect Chocolate...  added in _recordingsInProgressList
2008-12-17 20:55:03.468750 [Scheduler timer]: Scheduler : time to record TvDatabase.Channel 17/12/2008 20:55:03-17/12/2008 22:00:00 Willie's Perfect Chocolate...
2008-12-17 20:55:03.500000 [Scheduler timer]: Controller: find free card for channel Channel 4
2008-12-17 20:55:03.500000 [Scheduler timer]: Controller:   got 2 tuning details for Channel 4
2008-12-17 20:55:03.500000 [Scheduler timer]: Controller:   channel #1 DVBT:tv:Channel 4 TV Channel 4 Freq:482000 ONID:9018 TSID:8197 SID:8384 PMT:0x108 FTA:True LCN:4 BandWidth:8 
2008-12-17 20:55:03.515625 [Scheduler timer]: Controller:    card:1 type:DvbT is available priority:4 #users:0 same transponder:False
2008-12-17 20:55:03.515625 [Scheduler timer]: Controller:    card:2 type:DvbT is available priority:3 #users:0 same transponder:False
2008-12-17 20:55:03.531250 [Scheduler timer]: Controller:    card:3 type:DvbS cannot tune to channel
2008-12-17 20:55:03.531250 [Scheduler timer]: Controller:    card:4 type:DvbT is available priority:5 #users:0 same transponder:False
2008-12-17 20:55:03.531250 [Scheduler timer]: Controller:    card:5 type:RadioWebStream is disabled
2008-12-17 20:55:03.531250 [Scheduler timer]: Controller:   channel #2 DVBS:tv:BSkyB Channel 4 Freq:10714000 ONID:2 TSID:2041 SID:9211 PMT:0x100 FTA:True LCN:11 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet 
2008-12-17 20:55:03.546875 [Scheduler timer]: Controller:    card:3 type:DvbS is available priority:-98 #users:1 same transponder:False
2008-12-17 20:55:03.546875 [Scheduler timer]: Controller:    card:5 type:RadioWebStream is disabled
2008-12-17 20:55:03.546875 [Scheduler timer]: Controller: found 4 available
2008-12-17 20:55:03.546875 [Scheduler timer]: Scheduler : record on free card:4 priority:5
2008-12-17 20:55:03.562500 [Scheduler timer]: user:epg remove
2008-12-17 20:55:03.562500 [Scheduler timer]: tvcard:FreeSubChannel:1 #0 keep graph=False
2008-12-17 20:55:03.562500 [Scheduler timer]: DVB subch:0 Decompose()
2008-12-17 20:55:03.562500 [Scheduler timer]: FreeSubChannel CA: freeing sub channel : 0
2008-12-17 20:55:03.562500 [Scheduler timer]: tvcard:FreeAllSubChannels:
2008-12-17 20:55:03.562500 [Scheduler timer]: dvb:StopGraph
2008-12-17 20:55:03.656250 [Scheduler timer]: Scheduler : record, first tune to channel
2008-12-17 20:55:03.656250 [12]: card: Tune 4 to Channel 4
2008-12-17 20:55:03.656250 [12]: card: user: scheduler39:4:-1 tune DVBT:tv:Channel 4 TV Channel 4 Freq:482000 ONID:9018 TSID:8197 SID:8384 PMT:0x108 FTA:True LCN:4 BandWidth:8
2008-12-17 20:55:03.671875 [12]: dvbt:  Tune:DVBT:tv:Channel 4 TV Channel 4 Freq:482000 ONID:9018 TSID:8197 SID:8384 PMT:0x108 FTA:True LCN:4 BandWidth:8
2008-12-17 20:55:03.671875 [12]: dvbt: tune: Assigning oldChannel
2008-12-17 20:55:03.671875 [12]: dvbt: tune: Current channel is null
2008-12-17 20:55:03.671875 [12]: dvbt: tune: Graph is tunning
2008-12-17 20:55:03.671875 [12]: dvbt: tune: Getting default locator
2008-12-17 20:55:03.671875 [12]: dvbt: tune: Putting bandwidth 8
2008-12-17 20:55:03.671875 [12]: dvbt: tune: put_ONID 9018
2008-12-17 20:55:03.671875 [12]: dvbt: tune: put_SID 8384
2008-12-17 20:55:03.671875 [12]: dvbt: tune: put_TSID 8197
2008-12-17 20:55:03.671875 [12]: dvbt: tune: Carrier frequency 482000
2008-12-17 20:55:03.671875 [12]: dvbt: tune: put_Locator
2008-12-17 20:55:03.671875 [12]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:Channel 4 TV Channel 4 Freq:482000 ONID:9018 TSID:8197 SID:8384 PMT:0x108 FTA:True LCN:4 BandWidth:8
2008-12-17 20:55:03.671875 [12]: dvbt: tune: Submitting tune request: System.__ComObject
2008-12-17 20:55:03.671875 [12]: dvb:Submiting tunerequest Channel:Channel 4 subChannel:-1 
2008-12-17 20:55:03.671875 [12]: dvb:Getting new subchannel
2008-12-17 20:55:03.671875 [12]: dvb:GetNewSubChannel:0 #0
2008-12-17 20:55:03.671875 [12]: dvb:Submit tunerequest size:1 new:0
2008-12-17 20:55:03.687500 [12]: subch:0 OnBeforeTune
2008-12-17 20:55:03.687500 [12]: dvb:Submit tunerequest calling put_TuneRequest
2008-12-17 20:55:04.140625 [12]: dvb:Submit tunerequest done calling put_TuneRequest
2008-12-17 20:55:04.140625 [12]: subch:0 OnAfterTune
2008-12-17 20:55:04.140625 [12]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-12-17 20:55:04.156250 [12]: dvbt: tune: SubChannel 0
2008-12-17 20:55:04.156250 [12]: subch:0 OnGraphStart
2008-12-17 20:55:04.156250 [12]: subch:0 Graph already running - skip WaitForPMT
2008-12-17 20:55:04.156250 [12]: dvb:  RunGraph
2008-12-17 20:55:04.843750 [12]: dvb:  LockedInOnSignal ok
2008-12-17 20:55:04.843750 [12]: subch:0 OnGraphStarted
2008-12-17 20:55:04.843750 [12]: subch:0 SetupPmtGrabber:pid 108 sid:20C0
2008-12-17 20:55:04.843750 [12]: subch:0 set pmt grabber pmt:108 sid:20C0
2008-12-17 20:55:04.843750 [12]: WaitForPMT: Waiting for PMT.
2008-12-17 20:55:04.859375 [11]: subch:0 OnPMTReceived() True
2008-12-17 20:55:04.875000 [12]: WaitForPMT: Found PMT after 0.015625 seconds.
2008-12-17 20:55:04.875000 [12]: subch:0 SendPmt:20C0 20C0 FFFFFFFF 4
2008-12-17 20:55:04.875000 [12]: Decode pmt
2008-12-17 20:55:04.875000 [12]: Set descriptor data with length 3
2008-12-17 20:55:04.875000 [12]: Set descriptor data with length 6
2008-12-17 20:55:04.875000 [12]: PROBLEM : descriptor lengths dont match 3 9
2008-12-17 20:55:04.875000 [12]: Set descriptor data with length 6
2008-12-17 20:55:04.875000 [12]: PROBLEM : descriptor lengths dont match 3 9
2008-12-17 20:55:04.875000 [12]: Set descriptor data with length 10
2008-12-17 20:55:04.875000 [12]: Set descriptor data with length 3
2008-12-17 20:55:04.875000 [12]: Set descriptor data with length 3
2008-12-17 20:55:04.890625 [12]: Set descriptor data with length 3
2008-12-17 20:55:04.890625 [12]: Set descriptor data with length 3
2008-12-17 20:55:04.890625 [12]: subch:0 SendPMT version:4 len:165 8384
2008-12-17 20:55:04.890625 [12]: subch:0 cam flags:True
2008-12-17 20:55:04.890625 [12]: subch:0 SetMpegPidMapping
2008-12-17 20:55:04.890625 [12]: subch:0  pid:0 pcr
2008-12-17 20:55:04.890625 [12]: subch:0  pid:108 pmt
2008-12-17 20:55:04.890625 [12]: subch:0  pid:230 video type:MPEG-2
2008-12-17 20:55:04.890625 [12]: subch:0    map pid:230 video type:MPEG-2
2008-12-17 20:55:04.906250 [12]: subch:0  pid:231 audio lang: type:MPEG-1
2008-12-17 20:55:04.906250 [12]: subch:0    map pid:231 audio lang: type:MPEG-1
2008-12-17 20:55:04.906250 [12]: subch:0  pid:232 audio lang: type:MPEG-1
2008-12-17 20:55:04.906250 [12]: subch:0  pid:233 subtitle type:6
2008-12-17 20:55:04.906250 [12]: subch:0  pid:834 type:B
2008-12-17 20:55:04.906250 [12]: subch:0  pid:8FE type:B
2008-12-17 20:55:04.906250 [12]: subch:0  pid:8FD type:B
2008-12-17 20:55:04.906250 [12]: subch:0  pid:8FF type:B
2008-12-17 20:55:04.906250 [12]: subch:0 stop tif
2008-12-17 20:55:04.921875 [12]: WaitForPMT: sending PMT to CAM took 0.03125 seconds.
2008-12-17 20:55:04.921875 [12]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2008-12-17 20:55:05.937500 [12]: card: Tuner locked: True
2008-12-17 20:55:05.937500 [12]: **************************************************
2008-12-17 20:55:05.937500 [12]: ***** SIGNAL LEVEL: 0, SIGNAL QUALITY: 100 *****
2008-12-17 20:55:05.937500 [12]: **************************************************
2008-12-17 20:55:05.937500 [12]: card: tuned user: scheduler39 subchannel: 0
2008-12-17 20:55:05.937500 [12]: user:scheduler39 add
2008-12-17 20:55:06.609375 [Scheduler timer]: Scheduler : record to E:\Unsorted Recordings\Willie's Perfect Chocolate...\Willie's Perfect Chocolate... - Channel 4 - 2008-12-17.mpg
2008-12-17 20:55:06.625000 [12]: card: StartRecording 4 E:\Unsorted Recordings\Willie's Perfect Chocolate...\Willie's Perfect Chocolate... - Channel 4 - 2008-12-17.ts
2008-12-17 20:55:06.640625 [12]: StartRecording to E:\Unsorted Recordings\Willie's Perfect Chocolate...\Willie's Perfect Chocolate... - Channel 4 - 2008-12-17.ts
2008-12-17 20:55:06.640625 [12]: subch:0 StartRecord(E:\Unsorted Recordings\Willie's Perfect Chocolate...\Willie's Perfect Chocolate... - Channel 4 - 2008-12-17.ts)
2008-12-17 20:55:06.640625 [12]: subch:0 record transport stream mode
2008-12-17 20:55:06.640625 [12]: subch:0-0 tswriter StartRecording...
2008-12-17 20:55:06.656250 [12]: SetRecorderPids
2008-12-17 20:55:06.671875 [12]: DVB subch:0 Started recording
2008-12-17 20:55:07.078125 [Channel state thread]: Controller: DoSetChannelStates for 229 channels
2008-12-17 20:55:07.375000 [Scheduler timer]: Scheduler: recList: count: 1 add scheduleid: 39 card: Hauppauge WinTV 88x DVB-T Tuner/Demod
2008-12-17 20:55:16.406250 [12]: Recording Willie's Perfect Chocolate... already added in _recordingsInProgressList, skipping
2008-12-17 20:55:31.390625 [12]: Recording Willie's Perfect Chocolate... already added in _recordingsInProgressList, skipping
2008-12-17 20:55:46.375000 [12]: Recording Willie's Perfect Chocolate... already added in _recordingsInProgressList, skipping
2008-12-17 20:56:01.359375 [12]: Recording Willie's Perfect Chocolate... already added in _recordingsInProgressList, skipping
2008-12-17 20:56:16.390625 [12]: Recording Willie's Perfect Chocolate... already added in _recordingsInProgressList, skipping
2008-12-17 21:00:31.250000 [8]: dvb:abort grabbing epg
2008-12-17 21:00:31.281250 [8]: EPG: database updated for #0 10510
2008-12-17 21:00:31.281250 [8]: user:epg remove
2008-12-17 21:00:31.296875 [8]: tvcard:FreeSubChannel:0 #0 keep graph=False
2008-12-17 21:00:31.296875 [8]: tvcard:FreeSubChannel :0 - sub channel not found
2008-12-17 21:00:31.296875 [8]: tvcard:FreeAllSubChannels:
2008-12-17 21:00:31.296875 [8]: Stopcard
2008-12-17 21:00:31.296875 [8]: tvcard:FreeAllSubChannels:
2008-12-17 21:00:31.296875 [8]: Timeshifting epg grabber stopped.
2008-12-17 21:00:31.359375 [18]: Recording Willie's Perfect Chocolate... already added in _recordingsInProgressList, skipping
2008-12-17 21:00:46.375000 [18]: Recording Willie's Perfect Chocolate... already added in _recordingsInProgressList, skipping
2008-12-17 21:01:01.390625 [18]: Recording Willie's Perfect Chocolate... already added in _recordingsInProgressList, skipping
2008-12-17 21:01:16.375000 [18]: Recording Willie's Perfect Chocolate... already added in _recordingsInProgressList, skipping
2008-12-17 21:01:31.390625 [18]: Recording Willie's Perfect Chocolate... already added in _recordingsInProgressList, skipping
2008-12-17 22:05:14.656250 [Scheduler timer]: Scheduler: stop record Channel 4 17/12/2008 20:55:07-17/12/2008 22:00:00 Willie's Perfect Chocolate...
2008-12-17 22:05:14.671875 [14]: card: StopRecording 4
2008-12-17 22:05:14.671875 [14]: card: StopRecording for card:4
2008-12-17 22:05:14.687500 [14]: subch:0-0 tswriter StopRecording...
2008-12-17 22:05:14.687500 [14]: tvcard:FreeSubChannel:1 #0 keep graph=False
2008-12-17 22:05:14.687500 [14]: DVB subch:0 Decompose()
2008-12-17 22:05:14.703125 [14]: FreeSubChannel CA: freeing sub channel : 0
2008-12-17 22:05:14.703125 [14]: tvcard:FreeAllSubChannels:
2008-12-17 22:05:14.703125 [14]: dvb:StopGraph
2008-12-17 22:05:14.718750 [14]: card: remove user:scheduler39 sub:0
2008-12-17 22:05:14.718750 [14]: user:scheduler39 remove
2008-12-17 22:05:14.718750 [14]: tvcard:FreeAllSubChannels:
2008-12-17 22:05:14.718750 [Channel state thread]: Controller: DoSetChannelStates for 229 channels
2008-12-17 22:05:15.125000 [Scheduler timer]: ComSkipLauncher: Recording ended (E:\Unsorted Recordings\Willie's Perfect Chocolate...\Willie's Perfect Chocolate... - Channel 4 - 2008-12-17.ts on Channel 4), launching program (C:\Scripts\comskip\comskip.exe "E:\Unsorted Recordings\Willie's Perfect Chocolate...\Willie's Perfect Chocolate... - Channel 4 - 2008-12-17.ts") ...
2008-12-17 22:05:25.265625 [14]: card: Tune 1 to Five
2008-12-17 22:05:25.265625 [14]: card: user: epg:1:-1 tune DVBT:tv:five Five Freq:562000 ONID:9018 TSID:12290 SID:12866 PMT:0x101 FTA:True LCN:5 BandWidth:8
2008-12-17 22:05:25.281250 [14]: dvbt:  Tune:DVBT:tv:five Five Freq:562000 ONID:9018 TSID:12290 SID:12866 PMT:0x101 FTA:True LCN:5 BandWidth:8
2008-12-17 22:05:25.281250 [14]: dvbt: tune: Assigning oldChannel
2008-12-17 22:05:25.281250 [14]: dvbt: tune: Current channel is null
2008-12-17 22:05:25.296875 [14]: dvbt: tune: Building graph
2008-12-17 22:05:25.296875 [14]: BuildGraph
2008-12-17 22:05:25.296875 [14]: dvb:AddNetworkProviderFilter
2008-12-17 22:05:25.296875 [14]: dvb:Add DVBTNetworkProvider
2008-12-17 22:05:25.312500 [14]: CreateTuningSpace()
2008-12-17 22:05:25.312500 [14]: Found tuningspace Cable
2008-12-17 22:05:25.312500 [14]: Found tuningspace Antenna
2008-12-17 22:05:25.312500 [14]: Found tuningspace ATSC
2008-12-17 22:05:25.312500 [14]: Found tuningspace Digital Cable
2008-12-17 22:05:25.312500 [14]: Found tuningspace AuxIn1
2008-12-17 22:05:25.312500 [14]: Found tuningspace FM Radio
2008-12-17 22:05:25.328125 [14]: Found tuningspace DVB-T
2008-12-17 22:05:25.328125 [14]: Found tuningspace DVBT TuningSpace
2008-12-17 22:05:25.328125 [14]: Found correct tuningspace DVBT TuningSpace
2008-12-17 22:05:25.328125 [14]: dvb:Add MPEG2 Demultiplexer filter
2008-12-17 22:05:25.328125 [14]: dvb:add Inf Tee filter
2008-12-17 22:05:25.328125 [14]: dvb:AddAndConnectBDABoardFilters
2008-12-17 22:05:25.328125 [14]: dvb: find bda tuner
2008-12-17 22:05:25.359375 [14]: dvb:  using [Tuner]: Pinnacle RoyalTS Tuner
2008-12-17 22:05:25.359375 [14]: dvb:  Render [Network provider]->[Tuner] OK
2008-12-17 22:05:25.359375 [14]: dvb:  Find BDA receiver
2008-12-17 22:05:25.375000 [14]: dvb:  match Capture by Tuner device path
2008-12-17 22:05:25.375000 [14]: dvb:  -Pinnacle RoyalTS Capture
2008-12-17 22:05:25.390625 [14]: dvb:  Render [Tuner]->[Capture] AOK
2008-12-17 22:05:25.390625 [14]: dvb:  WinTv CI module not detected.
2008-12-17 22:05:25.406250 [14]: dvb:  Render [capture]->[inftee]
2008-12-17 22:05:25.406250 [14]: dvb:  OK
2008-12-17 22:05:25.406250 [14]: mdplugs: Create
2008-12-17 22:05:25.406250 [14]: dvb:  Render [inftee]->[demux]
2008-12-17 22:05:25.406250 [14]: dvb:  Add Mediaportal TsWriter filter
2008-12-17 22:05:25.406250 [14]: dvb:  Render [InfTee]->[TsWriter]
2008-12-17 22:05:25.406250 [14]: CAM is Default model
2008-12-17 22:05:25.406250 [14]: Check for KNC
2008-12-17 22:05:25.437500 [14]: KNC: IsKNC False
2008-12-17 22:05:25.437500 [14]: Check for Digital Everywhere
2008-12-17 22:05:25.437500 [14]: Check for Twinhan
2008-12-17 22:05:25.437500 [14]: Check for TechnoTrend
2008-12-17 22:05:25.453125 [14]: Check for Hauppauge
2008-12-17 22:05:25.453125 [14]: Check for Conexant based card
2008-12-17 22:05:25.453125 [14]: Check for Generic DVB-S card
2008-12-17 22:05:25.453125 [14]: Generic BDA card detected
2008-12-17 22:05:25.453125 [14]: Check for Hauppauge WinTV CI
2008-12-17 22:05:25.453125 [14]: dvb:  AddTransportStreamFiltersToGraph
2008-12-17 22:05:25.453125 [14]:     add BDA MPEG2 Transport Information Filter filter
2008-12-17 22:05:25.453125 [14]:     pinTif:name:IB Input [3/1] Direction:Input Connected:False
2008-12-17 22:05:25.453125 [14]:     Connect tif and mpeg2 sections and tables
2008-12-17 22:05:25.453125 [14]: dvb:try tif:name:1 [4/1] Direction:Output Connected:False
2008-12-17 22:05:25.468750 [14]:     tif connected
2008-12-17 22:05:25.468750 [14]: dvb: GetTunerSignalStatistics()
2008-12-17 22:05:25.468750 [14]: dvb:GetNewSubChannel:0 #0
2008-12-17 22:05:25.468750 [14]: dvbt: tune: Getting default locator
2008-12-17 22:05:25.468750 [14]: dvbt: tune: Putting bandwidth 8
2008-12-17 22:05:25.468750 [14]: dvbt: tune: put_ONID 9018
2008-12-17 22:05:25.468750 [14]: dvbt: tune: put_SID 12866
2008-12-17 22:05:25.468750 [14]: dvbt: tune: put_TSID 12290
2008-12-17 22:05:25.468750 [14]: dvbt: tune: Carrier frequency 562000
2008-12-17 22:05:25.468750 [14]: dvbt: tune: put_Locator
2008-12-17 22:05:25.468750 [14]: dvbt: tune: Submitting tune request: SubId 0, channel DVBT:tv:five Five Freq:562000 ONID:9018 TSID:12290 SID:12866 PMT:0x101 FTA:True LCN:5 BandWidth:8
2008-12-17 22:05:25.468750 [14]: dvbt: tune: Submitting tune request: System.__ComObject
2008-12-17 22:05:25.468750 [14]: dvb:Submiting tunerequest Channel:Five subChannel:0 
2008-12-17 22:05:25.468750 [14]: dvb:using existing subchannel:0
2008-12-17 22:05:25.468750 [14]: dvb:Submit tunerequest size:1 new:0
2008-12-17 22:05:25.468750 [14]: subch:0 OnBeforeTune
2008-12-17 22:05:25.484375 [14]: dvb:Submit tunerequest calling put_TuneRequest
2008-12-17 22:05:25.734375 [14]: dvb:Submit tunerequest done calling put_TuneRequest
2008-12-17 22:05:25.734375 [14]: subch:0 OnAfterTune
2008-12-17 22:05:25.734375 [14]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-12-17 22:05:25.734375 [14]: dvbt: tune: SubChannel 0
2008-12-17 22:05:25.734375 [14]: subch:0 OnGraphStart
2008-12-17 22:05:25.734375 [14]: subch:0 Graph already running - skip WaitForPMT
2008-12-17 22:05:25.734375 [14]: dvb:  RunGraph
2008-12-17 22:05:30.015625 [14]: dvb:  LockedInOnSignal waiting 20ms
2008-12-17 22:05:30.031250 [14]: dvb:  LockedInOnSignal waiting 20ms
2008-12-17 22:05:30.062500 [14]: dvb:  LockedInOnSignal waiting 20ms
2008-12-17 22:05:30.078125 [14]: dvb:  LockedInOnSignal waiting 20ms
2008-12-17 22:05:30.109375 [14]: dvb:  LockedInOnSignal waiting 20ms
2008-12-17 22:05:30.234375 [14]: dvb:  LockedInOnSignal ok
2008-12-17 22:05:30.234375 [14]: subch:0 OnGraphStarted
2008-12-17 22:05:30.234375 [14]: subch:0 SetupPmtGrabber:pid 101 sid:3242
2008-12-17 22:05:30.234375 [14]: subch:0 set pmt grabber pmt:101 sid:3242
2008-12-17 22:05:30.234375 [14]: WaitForPMT: Waiting for PMT.
2008-12-17 22:05:30.406250 [10]: subch:0 OnPMTReceived() True
2008-12-17 22:05:30.406250 [14]: WaitForPMT: Found PMT after 0.171875 seconds.
2008-12-17 22:05:30.406250 [14]: subch:0 SendPmt:3242 3242 FFFFFFFF C
2008-12-17 22:05:30.406250 [14]: Decode pmt
2008-12-17 22:05:30.406250 [14]: Set descriptor data with length 3
2008-12-17 22:05:30.406250 [14]: Set descriptor data with length 6
2008-12-17 22:05:30.406250 [14]: PROBLEM : descriptor lengths dont match 3 9
2008-12-17 22:05:30.421875 [14]: Set descriptor data with length 6
2008-12-17 22:05:30.421875 [14]: PROBLEM : descriptor lengths dont match 3 9
2008-12-17 22:05:30.421875 [14]: Set descriptor data with length 10
2008-12-17 22:05:30.421875 [14]: Set descriptor data with length 3
2008-12-17 22:05:30.421875 [14]: Set descriptor data with length 3
2008-12-17 22:05:30.421875 [14]: subch:0 SendPMT version:12 len:99 12866
2008-12-17 22:05:30.421875 [14]: subch:0 cam flags:True
2008-12-17 22:05:30.421875 [14]: subch:0 SetMpegPidMapping
2008-12-17 22:05:30.421875 [14]: subch:0  pid:0 pcr
2008-12-17 22:05:30.421875 [14]: subch:0  pid:101 pmt
2008-12-17 22:05:30.421875 [14]: subch:0  pid:1781 video type:MPEG-2
2008-12-17 22:05:30.437500 [14]: subch:0    map pid:1781 video type:MPEG-2
2008-12-17 22:05:30.437500 [14]: subch:0  pid:1782 audio lang:eng type:MPEG-2
2008-12-17 22:05:30.437500 [14]: subch:0    map pid:1782 audio lang:eng type:MPEG-2
2008-12-17 22:05:30.437500 [14]: subch:0  pid:1783 audio lang:eng type:MPEG-2
2008-12-17 22:05:30.437500 [14]: subch:0  pid:1786 subtitle type:6
2008-12-17 22:05:30.437500 [14]: subch:0  pid:1BAD type:B
2008-12-17 22:05:30.437500 [14]: subch:0  pid:1BAE type:B
2008-12-17 22:05:30.437500 [14]: subch:0 stop tif
2008-12-17 22:05:30.453125 [14]: WaitForPMT: sending PMT to CAM took 0.03125 seconds.
2008-12-17 22:05:30.453125 [14]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2008-12-17 22:05:30.484375 [14]: card: Tuner locked: True
2008-12-17 22:05:30.484375 [14]: **************************************************
2008-12-17 22:05:30.484375 [14]: ***** SIGNAL LEVEL: 50, SIGNAL QUALITY: 84 *****
2008-12-17 22:05:30.484375 [14]: **************************************************
2008-12-17 22:05:30.500000 [14]: card: tuned user: epg subchannel: 0
2008-12-17 22:05:30.500000 [14]: user:epg add
2008-12-17 22:05:30.500000 [DVB EPG timer]: dvb:grab epg...



    and error.log

    Code: 
    2008-12-17 20:55:06.656250 [12]: subch:0 tswriter StartRecord failed:1


    epg.log

    Code: 
    2008-12-17 20:50:26.703125 [DVB EPG timer]: Epg: card:3 start grab DVBS:tv: 10510 Freq:11428000 ONID:59 TSID:2315 SID:10510 PMT:0xD48 FTA:True LCN:304 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate2_3 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-12-17 20:55:03.562500 [Scheduler timer]: epg cancelled due to start recording
2008-12-17 21:00:31.250000 [8]: Epg: card:3 timeout after 10.0755208333333 mins
2008-12-17 21:00:31.265625 [8]: dvb:mhw ready MHW 0 titles found
2008-12-17 21:00:31.265625 [8]: dvb:dvb ready.EPG 0 channels
2008-12-17 21:00:31.265625 [8]: Epg: card:3 no epg found
2008-12-17 22:05:25.250000 [DVB EPG timer]: epg:Grab for card:#1 transponder #9/18 channel: Five
2008-12-17 22:05:25.250000 [DVB EPG timer]: grab epg card:#1 transponder: #8 ch:Five 
2008-12-17 22:05:30.515625 [DVB EPG timer]: Epg: card:1 start grab DVBT:tv:five Five Freq:562000 ONID:9018 TSID:12290 SID:12866 PMT:0x101 FTA:True LCN:5 BandWidth:8



    :D
     
    T

    tourettes

    Retired Team Member
  • Premium Supporter
    • January 7, 2005
    17,301
    4,800
    unable to create file:'E:\Unsorted Recordings\Willie's Perfect Chocolate...\Willie's Perfect Chocolate... - Channel 4 - 2008-12-17.ts' 3
    Click to expand...

    No space left on the device? Or maybe its that "..." few times in the path + filename. At least I wasnt able to create a folder named "...", so most likely the program name is causing the issue. Althou in that case the .xml file creation should have failed also. Could you check the file name for the .xml file?
     
    R

    revs

    MP Donator
  • Premium Supporter
    • February 1, 2007
    1,272
    72
    The Sauce of Worcester
    Home Country
    Wales Wales
    I'm afraid that the XML file is gone as when I realized that the recording had failed I deleted it.
    The drive has 800+ GB free, so no problems there.

    Maybe it was the ...

    UPDATE: Yes definately the ... As I tried recording "close encounters of ..." and "star wars II..." last week. They both failed too!! XML file was created in each instance though.

    What do I need to do to get this on mantis? ;-)

    UPDATE2: When creating a directory in Windows ending in "..." the "..." is removed. This is also the case with recordings, the directory created did not have "..."

    Maybe the recording fails as it wants to place the .ts file in \myrecording...\video.ts however windows has stripped the "..." so actual path would be \myrecording\video.ts ??

    The removal of "..." only seems to affect directories - as files in windows can have "myfile....xml" quite happily.

    Also of note - the directory path ending in "..." is also passed to comskip - so guess this would also fail!

    UPDATE 3: any "." after a directory name are removed - be it 1, 3 or 10000
     
    morpheus_xx

    morpheus_xx

    Retired Team Member
  • Team MediaPortal
    • March 24, 2007
    12,070
    7,459
    Home Country
    Germany Germany
    I think I now have fixed the right place, the creation of recording path / filename.

    I replace any trailing dots on subfolder for recording and testet it:

    Movie recording path pattern:
    Code: 
    %title%\%title% - %date% - %start%

    that's where trailing dots in title causes trouble. after my path the recording does work and looks like:

    Code: 
    Recorder: RECORD    Start 'E:\Capture\EinsExtra Aktuell\EinsExtra Aktuell... - 2008-12-19 - 09_00.ts'

    (I modified an epg entry to test this)

    Hope someone can check and commit this.


    Bye
    Morpheus_xx
     
    R

    revs

    MP Donator
  • Premium Supporter
    • February 1, 2007
    1,272
    72
    The Sauce of Worcester
    Home Country
    Wales Wales
    Does the recording name come from the scheduled recording table? Or does MP look at the epg name justbefore recording? Am wondering if I can edit the scheduled table entries to workaround the issue with some upcoming recordings?
     
    morpheus_xx

    morpheus_xx

    Retired Team Member
  • Team MediaPortal
    • March 24, 2007
    12,070
    7,459
    Home Country
    Germany Germany
    I patched the place where the path and filename is created from the tv server settings for scheduled recordings.

    you could also use a naming pattern that has anything appended to the %title% field. In my case it was the channel, so I never experienced this problem.

    after commit of this patch you can use it as before...
     
    R

    revs

    MP Donator
  • Premium Supporter
    • February 1, 2007
    1,272
    72
    The Sauce of Worcester
    Home Country
    Wales Wales
    An yes! Changing the path is a very easy way to fix it! Thanks!

    Have added a underscore to the end of the folder folder path name and now it works fine! just get "..._" which windows doesn't seem to mind!
     

    Users who are viewing this thread

    Online now: 2 (members: 0, guests: 2)

    About The Project

    The vision of the MediaPortal project is to create a free open source media centre application, which supports all advanced media centre functions, and is accessible to all Windows users.

    In reaching this goal we are working every day to make sure our software is one of the best.

             

    Quick Navigation

    Support MediaPortal!

    The team works very hard to make sure the community is running the best HTPC-software. We give away MediaPortal for free but hosting and software is not for us.

    Care to support our work with a few bucks? We'd really appreciate it!

    Community platform by XenForo® © 2010-2021 XenForo Ltd.
    Top Bottom