aller ca5-7Min Aussetzer von ca 1Minute (1 Viewer)

Ultra1988

Portal Member
December 15, 2008
23
0
Home Country
Germany Germany
Hallo Leute,

Ich habe aktuell dass Problem seit Update auf 1.3.0.
Ich habe den TV-Server separat.

Wenn ich TV schaue, dann gibt es immer nach ca 5-7Minuten einen mehr als deutlichen Aussetzer von knapp einer Minute.

Ich hege Verdacht, dass es irgendetwas mit diesem "Timeshifting" hat... :(
Ich habe absolut keinen Plan, woran das liegt und hoffe ihr könnt mir helfen :)


-----------------------------------------------------------------------------------------------------------------------------
Logs:

MP-Client -- TS-Reader xxx.log
Code:
28-03-2013 13:50:24.088 [ab4]vidPin : Video to render late= -36.691
28-03-2013 13:50:24.251 [1528]audPin : Audio to render late= -36.266
28-03-2013 13:50:24.253 [ab4]vidPin : Video to render late= -35.856
28-03-2013 13:50:24.353 [1314]Pause 195 mS renderer clock to match provider/RTSP clock, A/V = 0/0
28-03-2013 13:50:24.353 [1314]CTsReaderFilter::Pause() - IsTimeShifting = 1 - state = 2
28-03-2013 13:50:24.353 [1314]CTsReaderFilter::Pause() - END - state = 1
28-03-2013 13:50:24.549 [1314]CTsReaderFilter::Run(6894.64) state 1 seeking 0
28-03-2013 13:50:24.549 [1314]Run() - Elapsed time from pause to Audio/Video ( total zapping time ) : 196 mS
28-03-2013 13:50:24.549 [1314]CTsReaderFilter::Run(6894.64) state 2 -->done
28-03-2013 13:50:24.550 [1314]CTsReaderFilter:: ForcePosnUpdate: 964.347 s
28-03-2013 13:50:24.553 [ab4]vidPin : Video to render late= -33.550
28-03-2013 13:50:24.555 [1528]audPin : Audio to render late= -33.892
28-03-2013 13:50:24.663 [1528]audPin : Audio to render late= -33.040
28-03-2013 13:50:24.663 [ab4]vidPin : Video to render late= -32.660
28-03-2013 13:50:24.764 [1528]audPin : Audio to render late= -32.253
28-03-2013 13:50:24.764 [ab4]vidPin : Video to render late= -31.921
28-03-2013 13:50:24.869 [1314]Pause 195 mS renderer clock to match provider/RTSP clock, A/V = 0/0
28-03-2013 13:50:24.869 [1314]CTsReaderFilter::Pause() - IsTimeShifting = 1 - state = 2
28-03-2013 13:50:24.869 [1314]CTsReaderFilter::Pause() - END - state = 1
28-03-2013 13:50:25.065 [1314]CTsReaderFilter::Run(6894.84) state 1 seeking 0
28-03-2013 13:50:25.065 [1314]Run() - Elapsed time from pause to Audio/Video ( total zapping time ) : 196 mS
28-03-2013 13:50:25.065 [1314]CTsReaderFilter::Run(6894.84) state 2 -->done
28-03-2013 13:50:25.066 [1314]CTsReaderFilter:: ForcePosnUpdate: 964.657 s
28-03-2013 13:50:25.074 [ab4]vidPin : Video to render late= -29.625
28-03-2013 13:50:25.075 [1528]audPin : Audio to render late= -30.006
28-03-2013 13:50:25.176 [1528]audPin : Audio to render late= -29.219
28-03-2013 13:50:25.281 [1528]audPin : Audio to render late= -28.484
28-03-2013 13:50:25.288 [ab4]vidPin : Video to render late= -28.079
28-03-2013 13:50:25.385 [1314]Pause 195 mS renderer clock to match provider/RTSP clock, A/V = 0/0
28-03-2013 13:50:25.385 [1314]CTsReaderFilter::Pause() - IsTimeShifting = 1 - state = 2
28-03-2013 13:50:25.386 [1314]CTsReaderFilter::Pause() - END - state = 1
28-03-2013 13:50:25.582 [1314]CTsReaderFilter::Run(6895.05) state 1 seeking 0
28-03-2013 13:50:25.582 [1314]Run() - Elapsed time from pause to Audio/Video ( total zapping time ) : 196 mS
28-03-2013 13:50:25.582 [1314]CTsReaderFilter::Run(6895.05) state 2 -->done
28-03-2013 13:50:25.583 [1314]CTsReaderFilter:: ForcePosnUpdate: 964.967 s
28-03-2013 13:50:25.583 [ab4]vidPin : Video to render late= -25.767
28-03-2013 13:50:25.584 [1528]audPin : Audio to render late= -26.132
28-03-2013 13:50:25.700 [ab4]vidPin : Video to render late= -25.004
28-03-2013 13:50:25.700 [1528]audPin : Audio to render late= -25.360
28-03-2013 13:50:25.796 [ab4]vidPin : Video to render late= -24.260
28-03-2013 13:50:25.806 [1528]audPin : Audio to render late= -24.578
28-03-2013 13:50:25.905 [1528]audPin : Audio to render late= -24.317
28-03-2013 13:50:25.907 [ab4]vidPin : Video to render late= -23.931
28-03-2013 13:50:26.012 [1528]audPin : Audio to render late= -23.704
28-03-2013 13:50:26.013 [ab4]vidPin : Video to render late= -23.397
28-03-2013 13:50:26.118 [ab4]vidPin : Video to render late= -22.702
28-03-2013 13:50:26.120 [1528]audPin : Audio to render late= -23.044
28-03-2013 13:50:26.226 [1528]audPin : Audio to render late= -22.310
28-03-2013 13:50:26.230 [ab4]vidPin : Video to render late= -21.934
28-03-2013 13:50:26.336 [ab4]vidPin : Video to render late= -21.200
 
28-03-2013 13:50:26.444 [1528]audPin : Audio to render late= -20.728
28-03-2013 13:50:26.445 [ab4]vidPin : Video to render late= -20.349
28-03-2013 13:50:26.538 [ab4]vidPin : Video to render late= -19.722
28-03-2013 13:50:26.539 [1528]audPin : Audio to render late= -19.983
28-03-2013 13:50:26.649 [1528]audPin : Audio to render late= -19.253
28-03-2013 13:50:26.652 [ab4]vidPin : Video to render late= -18.876
28-03-2013 13:50:26.750 [1528]audPin : Audio to render late= -18.466
28-03-2013 13:50:26.754 [ab4]vidPin : Video to render late= -18.098
28-03-2013 13:50:26.856 [ab4]vidPin : Video to render late= -17.320
28-03-2013 13:50:26.867 [1528]audPin : Audio to render late= -17.647
28-03-2013 13:50:26.969 [1528]audPin : Audio to render late= -16.837
28-03-2013 13:50:26.971 [ab4]vidPin : Video to render late= -16.435
28-03-2013 13:50:27.068 [1528]audPin : Audio to render late= -16.120
28-03-2013 13:50:27.069 [ab4]vidPin : Video to render late= -15.813
28-03-2013 13:50:27.189 [ab4]vidPin : Video to render late= -14.933
28-03-2013 13:50:27.190 [1528]audPin : Audio to render late= -15.330
28-03-2013 13:50:27.282 [ab4]vidPin : Video to render late= -14.306
28-03-2013 13:50:27.283 [1528]audPin : Audio to render late= -14.655
28-03-2013 13:50:27.386 [1314]Pause 195 mS renderer clock to match provider/RTSP clock, A/V = 0/0
28-03-2013 13:50:27.386 [1314]CTsReaderFilter::Pause() - IsTimeShifting = 1 - state = 2
28-03-2013 13:50:27.387 [1314]CTsReaderFilter::Pause() - END - state = 1
28-03-2013 13:50:27.583 [1314]CTsReaderFilter::Run(6895.26) state 1 seeking 0
28-03-2013 13:50:27.583 [1314]Run() - Elapsed time from pause to Audio/Video ( total zapping time ) : 196 mS
28-03-2013 13:50:27.583 [1314]CTsReaderFilter::Run(6895.26) state 2 -->done
28-03-2013 13:50:27.583 [1314]CTsReaderFilter:: ForcePosnUpdate: 966.760 s
28-03-2013 13:50:27.588 [ab4]vidPin : Video to render late= -13.765
28-03-2013 13:50:27.588 [1528]audPin : Audio to render late= -14.129
28-03-2013 13:50:27.653 [ab4]vidPin : Video to render late= -13.430
28-03-2013 13:50:27.653 [1528]audPin : Audio to render late= -13.762
28-03-2013 13:50:27.871 [1528]audPin : Audio to render late= -13.452
28-03-2013 13:50:27.872 [ab4]vidPin : Video to render late= -13.089
28-03-2013 13:50:27.970 [1314]Pause 195 mS renderer clock to match provider/RTSP clock, A/V = 0/0
28-03-2013 13:50:27.970 [1314]CTsReaderFilter::Pause() - IsTimeShifting = 1 - state = 2
28-03-2013 13:50:27.970 [1314]CTsReaderFilter::Pause() - END - state = 1
28-03-2013 13:50:28.165 [1314]CTsReaderFilter::Run(6895.46) state 1 seeking 0
28-03-2013 13:50:28.165 [1314]Run() - Elapsed time from pause to Audio/Video ( total zapping time ) : 195 mS
28-03-2013 13:50:28.166 [1314]CTsReaderFilter::Run(6895.46) state 2 -->done
28-03-2013 13:50:28.166 [1314]CTsReaderFilter:: ForcePosnUpdate: 967.138 s
28-03-2013 13:50:28.382 [ab4]vidPin : Video to render late= -11.194
28-03-2013 13:50:28.384 [1528]audPin : Audio to render late= -11.552
28-03-2013 13:50:28.485 [1314]Pause 195 mS renderer clock to match provider/RTSP clock, A/V = 0/2
28-03-2013 13:50:28.485 [1314]CTsReaderFilter::Pause() - IsTimeShifting = 1 - state = 2
28-03-2013 13:50:28.486 [1314]CTsReaderFilter::Pause() - END - state = 1
28-03-2013 13:50:28.681 [1314]CTsReaderFilter::Run(6895.67) state 1 seeking 0
28-03-2013 13:50:28.682 [1314]Run() - Elapsed time from pause to Audio/Video ( total zapping time ) : 196 mS
28-03-2013 13:50:28.682 [1314]CTsReaderFilter::Run(6895.67) state 2 -->done
28-03-2013 13:50:28.683 [1314]CTsReaderFilter:: ForcePosnUpdate: 967.449 s
28-03-2013 13:50:28.685 [1528]audPin : Audio to render late= -9.511
28-03-2013 13:50:28.686 [ab4]vidPin : Video to render late= -9.132
28-03-2013 13:50:28.805 [1528]audPin : Audio to render late= -8.791
28-03-2013 13:50:28.806 [ab4]vidPin : Video to render late= -8.452
28-03-2013 13:50:28.897 [1528]audPin : Audio to render late= -8.163
28-03-2013 13:50:28.905 [ab4]vidPin : Video to render late= -7.791
28-03-2013 13:50:29.069 [ab4]vidPin : Video to render late= -7.395
28-03-2013 13:50:29.070 [1528]audPin : Audio to render late= -7.784
28-03-2013 13:50:29.270 [ab4]vidPin : Video to render late= -6.956
28-03-2013 13:50:29.271 [1528]audPin : Audio to render late= -7.313
28-03-2013 13:50:29.322 [1528]audPin : Audio to render late= -7.124
28-03-2013 13:50:29.322 [ab4]vidPin : Video to render late= -6.728
28-03-2013 13:50:29.430 [1528]audPin : Audio to render late= -6.248
28-03-2013 13:50:29.430 [ab4]vidPin : Video to render late= -5.916
28-03-2013 13:50:29.535 [1528]audPin : Audio to render late= -5.513
28-03-2013 13:50:29.537 [ab4]vidPin : Video to render late= -5.143
28-03-2013 13:50:29.641 [1528]audPin : Audio to render late= -4.755
28-03-2013 13:50:29.643 [ab4]vidPin : Video to render late= -4.409
28-03-2013 13:50:29.751 [ab4]vidPin : Video to render late= -3.797
28-03-2013 13:50:29.753 [1528]audPin : Audio to render late= -4.147
28-03-2013 13:50:30.047 [ab4]vidPin : Video to render late= -3.453
28-03-2013 13:50:30.048 [1528]audPin : Audio to render late= -3.794
28-03-2013 13:50:30.173 [1528]audPin : Audio to render late= -3.079
28-03-2013 13:50:30.173 [ab4]vidPin : Video to render late= -2.739
28-03-2013 13:50:30.275 [ab4]vidPin : Video to render late= -2.001
28-03-2013 13:50:30.276 [1528]audPin : Audio to render late= -2.438
28-03-2013 13:50:30.386 [1528]audPin : Audio to render late= -1.708
28-03-2013 13:50:30.390 [ab4]vidPin : Video to render late= -1.316
28-03-2013 13:50:30.487 [1314]Pause 195 mS renderer clock to match provider/RTSP clock, A/V = 2/2
28-03-2013 13:50:30.487 [1314]CTsReaderFilter::Pause() - IsTimeShifting = 1 - state = 2
28-03-2013 13:50:30.487 [1314]CTsReaderFilter::Pause() - END - state = 1
28-03-2013 13:50:30.511 [ab4]vidPin: Set discontinuity L:1 B:0 fTime:-0.267 SampCnt:18266
28-03-2013 13:50:30.511 [ab4]Vid/Ref : 2535.408, P-frame(07), Compensated = 968.880 ( -0.267 A/V buffers=02/03), Clk : 969.147354, SampCnt 18266, stallPt 2.500
28-03-2013 13:50:30.512 [ab4]Vid/Ref : 2535.448, P-frame(08), Compensated = 968.920 ( -0.227 A/V buffers=01/02), Clk : 969.147354, SampCnt 18267, stallPt 2.500
28-03-2013 13:50:30.526 [ab4]Vid/Ref : 2535.488, P-frame(09), Compensated = 968.960 ( -0.187 A/V buffers=00/01), Clk : 969.147354, SampCnt 18268, stallPt 2.500
28-03-2013 13:50:30.532 [ab4]Vid/Ref : 2535.528, P-frame(10), Compensated = 969.000 ( -0.147 A/V buffers=00/03), Clk : 969.147354, SampCnt 18269, stallPt 2.500
28-03-2013 13:50:30.533 [ab4]Vid/Ref : 2535.568, P-frame(11), Compensated = 969.040 ( -0.107 A/V buffers=00/02), Clk : 969.147354, SampCnt 18270, stallPt 2.500
28-03-2013 13:50:30.534 [ab4]Vid/Ref : 2535.608, P-frame(12), Compensated = 969.080 ( -0.067 A/V buffers=00/01), Clk : 969.147354, SampCnt 18271, stallPt 2.500
28-03-2013 13:50:30.545 [ab4]Vid/Ref : 2535.648, P-frame(13), Compensated = 969.120 ( -0.027 A/V buffers=00/02), Clk : 969.147354, SampCnt 18272, stallPt 2.500
28-03-2013 13:50:30.546 [ab4]Vid/Ref : 2535.688, P-frame(14), Compensated = 969.160 ( 0.013 A/V buffers=00/01), Clk : 969.147354, SampCnt 18273, stallPt 2.500
28-03-2013 13:50:30.560 [1528]audPin: Set discontinuity L:1 B:0 fTime:-0.263 SampCnt:17342
28-03-2013 13:50:30.560 [1528]Aud/Ref : 2535.412, Compensated = 968.884 ( -0.263 A/V buffers=01/00), Clk : 969.147354, SampCnt 17342, Sleep 1 ms, stallPt 1.500
28-03-2013 13:50:30.571 [1528]Aud/Ref : 2535.460, Compensated = 968.932 ( -0.215 A/V buffers=03/01), Clk : 969.147354, SampCnt 17343, Sleep 1 ms, stallPt 1.500
28-03-2013 13:50:30.572 [1528]Aud/Ref : 2535.484, Compensated = 968.956 ( -0.191 A/V buffers=02/01), Clk : 969.147354, SampCnt 17344, Sleep 1 ms, stallPt 1.500
28-03-2013 13:50:30.573 [1528]Aud/Ref : 2535.532, Compensated = 969.004 ( -0.143 A/V buffers=01/01), Clk : 969.147354, SampCnt 17345, Sleep 1 ms, stallPt 1.500
28-03-2013 13:50:30.584 [1528]Aud/Ref : 2535.580, Compensated = 969.052 ( -0.095 A/V buffers=03/03), Clk : 969.147354, SampCnt 17346, Sleep 1 ms, stallPt 1.500
28-03-2013 13:50:30.585 [1528]Aud/Ref : 2535.628, Compensated = 969.100 ( -0.047 A/V buffers=02/03), Clk : 969.147354, SampCnt 17347, Sleep 1 ms, stallPt 1.500
28-03-2013 13:50:30.586 [1528]Aud/Ref : 2535.652, Compensated = 969.124 ( -0.023 A/V buffers=01/02), Clk : 969.147354, SampCnt 17348, Sleep 1 ms, stallPt 1.500
28-03-2013 13:50:30.682 [1314]CTsReaderFilter::Run(6895.87) state 1 seeking 0
28-03-2013 13:50:30.683 [1314]Run() - Elapsed time from pause to Audio/Video ( total zapping time ) : 196 mS
28-03-2013 13:50:30.683 [1314]CTsReaderFilter::Run(6895.87) state 2 -->done
28-03-2013 13:50:30.684 [1314]CTsReaderFilter:: ForcePosnUpdate: 969.243 s
28-03-2013 13:50:30.697 [1528]audPin : Audio to render late= 0.182
28-03-2013 13:50:30.697 [ab4]Vid/Ref : 2536.168, P-frame(11), Compensated = 969.640 ( 0.386 A/V buffers=06/10), Clk : 969.254354, SampCnt 18285, stallPt 2.500
28-03-2013 13:50:30.697 [1528]Aud/Ref : 2535.964, Compensated = 969.436 ( 0.182 A/V buffers=06/10), Clk : 969.254354, SampCnt 17355, Sleep 1 ms, stallPt 1.500
28-03-2013 13:50:30.698 [1528]Aud/Ref : 2535.988, Compensated = 969.460 ( 0.205 A/V buffers=05/09), Clk : 969.255354, SampCnt 17356, Sleep 1 ms, stallPt 1.500
28-03-2013 13:50:30.698 [ab4]Vid/Ref : 2536.208, P-frame(12), Compensated = 969.680 ( 0.425 A/V buffers=04/09), Clk : 969.255354, SampCnt 18286, stallPt 2.500
28-03-2013 13:50:30.699 [ab4]Vid/Ref : 2536.248, P-frame(13), Compensated = 969.720 ( 0.464 A/V buffers=04/08), Clk : 969.256354, SampCnt 18287, stallPt 2.500
28-03-2013 13:50:30.797 [1528]Aud/Ref : 2536.036, Compensated = 969.508 ( 0.260 A/V buffers=07/10), Clk : 969.248354, SampCnt 17357, Sleep 1 ms, stallPt 1.500

MP-Client -- MediaPortal.log
Code:
2013-03-28 13:34:13.022566 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Titan\tvOSD.xml' is missing control id 100 (window property: lblCurrentTime)
2013-03-28 13:34:13.028566 [Info.][MPMain(1)]: xml:C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Titan\tvOSD.xml image id:350 width:0 height:0 gfx:-
2013-03-28 13:34:13.031566 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Titan\tvOSD.xml' is missing control id 10 (window property: imgTvChannelLogo)
2013-03-28 13:34:13.032567 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Titan\tvOSD.xml' is missing control id 100 (window property: lblCurrentTime)
2013-03-28 13:34:13.154574 [Info.][(3)]: planescene:PresentImage() frame:50 enabled:True allowed:False
2013-03-28 13:34:13.155574 [Info.][(3)]: planescene:PresentImage() frame:51 enabled:True allowed:False
2013-03-28 13:34:13.217577 [Info.][(3)]: planescene:PresentImage() frame:52 enabled:True allowed:False
2013-03-28 13:34:13.252579 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Titan\tvZAPOSD.xml' is missing control id 100 (window property: lblCurrentTime)
2013-03-28 13:34:13.254579 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Titan\tvZAPOSD.xml' is missing control id 100 (window property: lblCurrentTime)
2013-03-28 13:34:13.284581 [Info.][(3)]: planescene:PresentImage() frame:53 enabled:True allowed:False
2013-03-28 13:34:13.358585 [Info.][(3)]: PlaneScene: video WxH  : 720x576
2013-03-28 13:34:13.359585 [Info.][(3)]: PlaneScene: screen WxH : 1920x1080
2013-03-28 13:40:23.892779 [Info.][(3)]: planescene:PresentImage() frame:0 enabled:True allowed:False
2013-03-28 13:40:23.903779 [Info.][(3)]: planescene:PresentImage() frame:1 enabled:True allowed:False
2013-03-28 13:45:08.860078 [Info.][(3)]: planescene:PresentImage() frame:0 enabled:True allowed:False
2013-03-28 13:50:30.691485 [Info.][(3)]: planescene:PresentImage() frame:0 enabled:True allowed:False

MP-Server -- TV.log
Code:
2013-03-28 13:33:21.048685 [(32)]: Controller: find free card for channel VOX
2013-03-28 13:33:21.049185 [(32)]: Controller: find card for channel VOX
2013-03-28 13:33:21.051185 [(32)]: Controller:  got 2 tuning details for VOX
2013-03-28 13:33:21.051685 [(32)]: Controller:  channel #1 tv:VOX Freq:0 Channel:64 Country:Germany Tuner:Cable Video:Tuner Audio:Automatic
2013-03-28 13:33:21.053686 [(32)]: Controller:	card:5 type:Analog can tune to channel
2013-03-28 13:33:21.054186 [(32)]: Controller:	card:5 type:Analog users: 0
2013-03-28 13:33:21.055686 [(32)]: Controller:	card:6 type:Analog can tune to channel
2013-03-28 13:33:21.056186 [(32)]: Controller:	card:6 type:Analog users: 0
2013-03-28 13:33:21.056686 [(32)]: Controller:	card:2 type:DvbT is disabled
2013-03-28 13:33:21.057186 [(32)]: Controller:	card:3 type:DvbT is disabled
2013-03-28 13:33:21.057686 [(32)]: Controller:	card:1 type:DvbIP is disabled
2013-03-28 13:33:21.058686 [(32)]: Controller:	card:7 type:RadioWebStream is disabled
2013-03-28 13:33:21.059186 [(32)]: Controller:  channel #2 tv:64 Freq:0 Channel:64 Country:Germany Tuner:Cable Video:Tuner Audio:Automatic
2013-03-28 13:33:21.060187 [(32)]: Controller:	card:5 type:Analog can tune to channel
2013-03-28 13:33:21.061187 [(32)]: Controller:	card:5 type:Analog users: 0
2013-03-28 13:33:21.062187 [(32)]: Controller:	card:6 type:Analog can tune to channel
2013-03-28 13:33:21.062687 [(32)]: Controller:	card:6 type:Analog users: 0
2013-03-28 13:33:21.064187 [(32)]: Controller:	card:2 type:DvbT is disabled
2013-03-28 13:33:21.066187 [(32)]: Controller:	card:3 type:DvbT is disabled
2013-03-28 13:33:21.067187 [(32)]: Controller:	card:1 type:DvbIP is disabled
2013-03-28 13:33:21.068188 [(32)]: Controller:	card:7 type:RadioWebStream is disabled
2013-03-28 13:33:21.069188 [(32)]: Controller: found 4 card(s) for channel
2013-03-28 13:33:21.070188 [(32)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 18 msec
2013-03-28 13:33:21.071188 [(32)]: Controller:	card:5 type:Analog is available
2013-03-28 13:33:21.072188 [(32)]: Controller:	card:5 type:Analog is available
2013-03-28 13:33:21.073188 [(32)]: Controller:	card:6 type:Analog is available
2013-03-28 13:33:21.074188 [(32)]: Controller:	card:6 type:Analog is available
2013-03-28 13:33:21.075188 [(32)]: Controller: found 4 free card(s)
2013-03-28 13:33:21.076189 [(32)]: AdvancedCardAllocation.GetFreeCardsForChannel took 24 msec
2013-03-28 13:33:21.080189 [(32)]: Controller: TimeShiftingWouldUseCard VOX 330
2013-03-28 13:33:21.080689 [(32)]: Controller: find free card for channel VOX
2013-03-28 13:33:21.082189 [(32)]: Controller: find card for channel VOX
2013-03-28 13:33:21.083690 [(32)]: Controller:  got 2 tuning details for VOX
2013-03-28 13:33:21.084690 [(32)]: Controller:  channel #1 tv:VOX Freq:0 Channel:64 Country:Germany Tuner:Cable Video:Tuner Audio:Automatic
2013-03-28 13:33:21.085690 [(32)]: Controller:	card:5 type:Analog can tune to channel
2013-03-28 13:33:21.086190 [(32)]: Controller:	card:5 type:Analog users: 0
2013-03-28 13:33:21.087690 [(32)]: Controller:	card:6 type:Analog can tune to channel
2013-03-28 13:33:21.088190 [(32)]: Controller:	card:6 type:Analog users: 0
2013-03-28 13:33:21.088690 [(32)]: Controller:	card:2 type:DvbT is disabled
2013-03-28 13:33:21.090190 [(32)]: Controller:	card:3 type:DvbT is disabled
2013-03-28 13:33:21.090690 [(32)]: Controller:	card:1 type:DvbIP is disabled
2013-03-28 13:33:21.091191 [(32)]: Controller:	card:7 type:RadioWebStream is disabled
2013-03-28 13:33:21.091691 [(32)]: Controller:  channel #2 tv:64 Freq:0 Channel:64 Country:Germany Tuner:Cable Video:Tuner Audio:Automatic
2013-03-28 13:33:21.093191 [(32)]: Controller:	card:5 type:Analog can tune to channel
2013-03-28 13:33:21.093691 [(32)]: Controller:	card:5 type:Analog users: 0
2013-03-28 13:33:21.095191 [(32)]: Controller:	card:6 type:Analog can tune to channel
2013-03-28 13:33:21.095691 [(32)]: Controller:	card:6 type:Analog users: 0
2013-03-28 13:33:21.096191 [(32)]: Controller:	card:2 type:DvbT is disabled
2013-03-28 13:33:21.096691 [(32)]: Controller:	card:3 type:DvbT is disabled
2013-03-28 13:33:21.097191 [(32)]: Controller:	card:1 type:DvbIP is disabled
2013-03-28 13:33:21.097691 [(32)]: Controller:	card:7 type:RadioWebStream is disabled
2013-03-28 13:33:21.098191 [(32)]: Controller: found 4 card(s) for channel
2013-03-28 13:33:21.098691 [(32)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 17 msec
2013-03-28 13:33:21.099692 [(32)]: Controller:	card:5 type:Analog is available
2013-03-28 13:33:21.100692 [(32)]: Controller:	card:5 type:Analog is available
2013-03-28 13:33:21.101692 [(32)]: Controller:	card:6 type:Analog is available
2013-03-28 13:33:21.102192 [(32)]: Controller:	card:6 type:Analog is available
2013-03-28 13:33:21.102692 [(32)]: Controller: found 4 free card(s)
2013-03-28 13:33:21.103192 [(32)]: AdvancedCardAllocation.GetFreeCardsForChannel took 22 msec
2013-03-28 13:33:21.107693 [(32)]: CiMenuSupported called cardid 5
2013-03-28 13:33:21.108193 [(32)]: CiMenuSupported card Hauppauge WinTV-7164 Analog TV Tuner supported: False
2013-03-28 13:33:21.114193 [(32)]: Controller: StartTimeShifting VOX 330
2013-03-28 13:33:21.114693 [(32)]: Controller: epg stop
2013-03-28 13:33:21.115694 [(32)]: Controller: find free card for channel VOX
2013-03-28 13:33:21.116194 [(32)]: Controller: find card for channel VOX
2013-03-28 13:33:21.118694 [(32)]: Controller:  got 2 tuning details for VOX
2013-03-28 13:33:21.119194 [(32)]: Controller:  channel #1 tv:VOX Freq:0 Channel:64 Country:Germany Tuner:Cable Video:Tuner Audio:Automatic
2013-03-28 13:33:21.121194 [(32)]: Controller:	card:5 type:Analog can tune to channel
2013-03-28 13:33:21.123195 [(32)]: Controller:	card:6 type:Analog can tune to channel
2013-03-28 13:33:21.124195 [(32)]: Controller:	card:2 type:DvbT is disabled
2013-03-28 13:33:21.125195 [(32)]: Controller:	card:3 type:DvbT is disabled
2013-03-28 13:33:21.126195 [(32)]: Controller:	card:1 type:DvbIP is disabled
2013-03-28 13:33:21.127195 [(32)]: Controller:	card:7 type:RadioWebStream is disabled
2013-03-28 13:33:21.129195 [(32)]: Controller:  channel #2 tv:64 Freq:0 Channel:64 Country:Germany Tuner:Cable Video:Tuner Audio:Automatic
2013-03-28 13:33:21.131196 [(32)]: Controller:	card:5 type:Analog can tune to channel
2013-03-28 13:33:21.133196 [(32)]: Controller:	card:6 type:Analog can tune to channel
2013-03-28 13:33:21.134196 [(32)]: Controller:	card:2 type:DvbT is disabled
2013-03-28 13:33:21.135196 [(32)]: Controller:	card:3 type:DvbT is disabled
2013-03-28 13:33:21.136196 [(32)]: Controller:	card:1 type:DvbIP is disabled
2013-03-28 13:33:21.137196 [(32)]: Controller:	card:7 type:RadioWebStream is disabled
2013-03-28 13:33:21.138196 [(32)]: Controller: found 4 card(s) for channel
2013-03-28 13:33:21.139197 [(32)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 24 msec
2013-03-28 13:33:21.139697 [(32)]: Controller: found 4 free card(s)
2013-03-28 13:33:21.140697 [(32)]: AdvancedCardAllocation.GetFreeCardsForChannel took 26 msec
2013-03-28 13:33:21.142697 [(32)]: CardReservationBase.RequestCardTuneReservation: placed reservation with id=10, tuningdetails=tv:VOX Freq:0 Channel:64 Country:Germany Tuner:Cable Video:Tuner Audio:Automatic
2013-03-28 13:33:21.144197 [(32)]: CardReservationBase.RequestCardTuneReservation: placed reservation with id=11, tuningdetails=tv:VOX Freq:0 Channel:64 Country:Germany Tuner:Cable Video:Tuner Audio:Automatic
2013-03-28 13:33:21.144697 [(32)]: Controller:	card:5 type:Analog users: 0
2013-03-28 13:33:21.145697 [(32)]: Controller:	card:5 type:Analog is available
2013-03-28 13:33:21.146197 [(32)]: Controller:	card:5 type:Analog users: 0
2013-03-28 13:33:21.147198 [(32)]: Controller:	card:5 type:Analog is available
2013-03-28 13:33:21.147698 [(32)]: Controller:	card:6 type:Analog users: 0
2013-03-28 13:33:21.148198 [(32)]: Controller:	card:6 type:Analog is available
2013-03-28 13:33:21.148698 [(32)]: Controller:	card:6 type:Analog users: 0
2013-03-28 13:33:21.149198 [(32)]: Controller:	card:6 type:Analog is available
2013-03-28 13:33:21.150198 [(32)]: Controller: try max 4 of 4 cards for timeshifting
2013-03-28 13:33:21.151698 [(32)]: CardReservationBase: tvcard=5, user=Felix-PC, dbChannel=330, ticket=10, tunestate=Tuning, stopstate=Idle
2013-03-28 13:33:21.266213 [(32)]: card: CardTune 5 VOX Felix-PC:5:-1
2013-03-28 13:33:21.268213 [(32)]: card: Tune on card 5 to subchannel VOX
2013-03-28 13:33:21.268713 [(32)]: card: user: Felix-PC:5:-1 tune tv:VOX Freq:0 Channel:64 Country:Germany Tuner:Cable Video:Tuner Audio:Automatic
2013-03-28 13:33:21.278714 [(32)]: TimeShifter.OnBeforeTune: resetting audio/video events
2013-03-28 13:33:21.279714 [(32)]: analog:  Tune:tv:VOX Freq:0 Channel:64 Country:Germany Tuner:Cable Video:Tuner Audio:Automatic
2013-03-28 13:33:21.280715 [(32)]: analog:GetNewSubChannel:0 #0
2013-03-28 13:33:21.285215 [(32)]: analog subch:0 OnBeforeTune
2013-03-28 13:33:22.177328 [(32)]: Analog: Tuned to country:49 video:511250000 Hz audio:516750000 Hz locked:True
2013-03-28 13:33:22.500870 [(32)]: analog subch:0 OnAfterTune
2013-03-28 13:33:22.501870 [(32)]: analog subch:0 OnGraphStart
2013-03-28 13:33:22.502870 [(32)]: TimeShifter.OnAfterTune: resetting audio/video time
2013-03-28 13:33:22.503870 [(32)]: analog: RunGraph
2013-03-28 13:33:22.712396 [(32)]: analog: RunGraph succeeded
2013-03-28 13:33:22.863916 [(32)]: analog:  LockInOnSignal ok
2013-03-28 13:33:22.864916 [(32)]: analog subch:0 OnGraphStarted
2013-03-28 13:33:22.867416 [(32)]: TimeShifter.OnAfterTune: resetting audio/video time
2013-03-28 13:33:23.020435 [(32)]: card: Tuner locked: True
2013-03-28 13:33:23.021436 [(32)]: **************************************************
2013-03-28 13:33:23.325474 [(32)]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
2013-03-28 13:33:23.326474 [(32)]: **************************************************
2013-03-28 13:33:23.327474 [(32)]: card: tuned user: Felix-PC subchannel: 0
2013-03-28 13:33:23.328475 [(32)]: user:Felix-PC add
2013-03-28 13:33:23.329475 [(32)]: card2:Felix-PC 5 0
2013-03-28 13:33:23.330975 [(32)]: Controller: delete timeshift files D:\Timeshift\live5-0.ts
2013-03-28 13:33:23.333975 [(32)]: Controller: epg stop
2013-03-28 13:33:23.335976 [(32)]: card: StartTimeShifting 5 D:\Timeshift\live5-0.ts
2013-03-28 13:33:23.336976 [(32)]: card: CAM enabled : False
2013-03-28 13:33:23.337476 [(32)]: analog: Encoder mode setting to ConstantBitRate
2013-03-28 13:33:23.630513 [(32)]: analog: Encoder mode set to ConstantBitRate
2013-03-28 13:33:23.631513 [(32)]: analog: Encoder BitRate setting to Default
2013-03-28 13:33:23.644015 [(32)]: analog: Encoder BitRate Min 0 Max 6000000 Delta 1
2013-03-28 13:33:23.950054 [(32)]: analog: Encoder BitRate set to 4000000
2013-03-28 13:33:23.950554 [(32)]: analog:SetTimeShiftFileName:D:\Timeshift\live5-0.ts
2013-03-28 13:33:23.951054 [(32)]: analog:SetTimeShiftFileName: uses .ts
2013-03-28 13:33:23.955054 [(32)]: card: WaitForFile - waiting _eventAudio & _eventVideo
2013-03-28 13:33:25.178210 [(13)]: PID seen - type = Audio
2013-03-28 13:33:25.179210 [(13)]: audioVideoEventHandler Audio
2013-03-28 13:33:25.179710 [(13)]: PID seen - type = Video
2013-03-28 13:33:25.180210 [(13)]: audioVideoEventHandler Video
2013-03-28 13:33:25.180710 [(32)]: card: WaitForFile - video and audio are seen after 1,2256556 seconds
2013-03-28 13:33:25.184710 [(32)]: user:Felix-PC card:5 sub:0 add stream:D:\Timeshift\live5-0.ts.tsbuffer
2013-03-28 13:33:25.185210 [(32)]: RTSP: add stream stream5.0 file:D:\Timeshift\live5-0.ts.tsbuffer
2013-03-28 13:33:25.186711 [(32)]: Controller: StartTimeShifting started on card:5 to D:\Timeshift\live5-0.ts.tsbuffer
2013-03-28 13:33:25.187711 [(32)]: CardReservation.RemoveTuneTicket: removed reservation with id=10, tuningdetails=tv:VOX Freq:0 Channel:64 Country:Germany Tuner:Cable Video:Tuner Audio:Automatic
2013-03-28 13:33:25.188211 [(32)]: Controller: Felix-PC 5 0
2013-03-28 13:33:25.189711 [(32)]: CardReservation.CancelCardReservation id=11
2013-03-28 13:33:25.190211 [(32)]: control2:Felix-PC 5 0
2013-03-28 13:33:25.198212 [Channel state thread(11)]: Controller: DoSetChannelStates for 31 channels
2013-03-28 13:33:25.204713 [(32)]: CiMenuSupported called cardid 5
2013-03-28 13:33:25.205213 [(32)]: CiMenuSupported card Hauppauge WinTV-7164 Analog TV Tuner supported: False
2013-03-28 13:33:25.206213 [Channel state thread(11)]: ChannelStates.GetAllTimeshiftingAndRecordingChannels took 0 msec
2013-03-28 13:33:25.295724 [Channel state thread(11)]: ChannelStates.DoSetChannelStates took 97 msec

MP-Server -- Streaming-Server.log
Code:
28-03-2013 13:33:25.185 Stream server: add timeshift  mpeg-2 transport stream stream5.0 filename:D:\Timeshift\live5-0.ts.tsbuffer
28-03-2013 13:33:25.186 Stream server: url for stream is rtsp://192.168.10.9:10450/stream5.0

MP-Server -- MPFileWriter.log
Code:
28-03-2013 13:33:21.282 CMPFileWriter::AddChannel() - ID: 0
28-03-2013 13:33:21.283 CSubChannel::ctor() - (0)
28-03-2013 13:33:21.283 CProgramToTransportStream::ctor
28-03-2013 13:33:21.283 CProgramToTransportStreamRecorder::ctor
28-03-2013 13:33:21.284 CTeletextGrabber::ctor()
28-03-2013 13:33:22.504 CMPFileWriterFilter::Pause()
28-03-2013 13:33:22.541 CMPFileWriterFilter::Run()
28-03-2013 13:33:23.951 CSubChannel::SetVideoAudioObserver() - (0)
28-03-2013 13:33:23.951 CProgramToTransportStream::SetVideoAudioObserver - 390038
28-03-2013 13:33:23.952 CSubChannel::StartTimeShifting() - (0) - Filename:'D:\Timeshift\live5-0.ts.tsbuffer'
28-03-2013 13:33:23.952 CProgramToTransportStream::Initialize D:\Timeshift\live5-0.ts.tsbuffer
28-03-2013 13:33:23.953 CMemoryStreamSource::ctor()
28-03-2013 13:33:23.953 CMultiWriterFileSink::create file:D:\Timeshift\live5-0.ts.tsbuffer
28-03-2013 13:33:23.953 CMultiWriterFileSink::ctor
28-03-2013 13:33:23.954 CTeletextGrabber::Start()
28-03-2013 13:33:24.714 CProgramToTransportStream::Thread playing()
28-03-2013 13:33:25.180 CMultiWriterFileSink::OnTsPacket() - Throttle to 376 bytes
28-03-2013 13:33:25.180 MultiFileWriter: Creating first file
28-03-2013 13:33:25.181 MultiFileWriter: PrepareTSFile()
28-03-2013 13:33:25.182 MultiFileWriter: CreateNewTSFile.
28-03-2013 13:33:25.183 MultiFileWriter: new file created
28-03-2013 13:33:25.184 CMultiWriterFileSink::OnTsPacket() - Throttle to 564 bytes
28-03-2013 13:33:25.185 CMultiWriterFileSink::OnTsPacket() - Throttle to 940 bytes
28-03-2013 13:33:25.185 CMultiWriterFileSink::OnTsPacket() - Throttle to 940 bytes
28-03-2013 13:33:25.186 CMultiWriterFileSink::OnTsPacket() - Throttle to 940 bytes
28-03-2013 13:33:25.186 CMultiWriterFileSink::OnTsPacket() - Throttle to 940 bytes
28-03-2013 13:33:25.187 CMultiWriterFileSink::OnTsPacket() - Throttle to 940 bytes
28-03-2013 13:33:25.187 CMultiWriterFileSink::OnTsPacket() - Throttle to 1880 bytes
28-03-2013 13:33:25.188 CMultiWriterFileSink::OnTsPacket() - Throttle to 1880 bytes
28-03-2013 13:33:25.188 CMultiWriterFileSink::OnTsPacket() - Throttle to 1880 bytes
28-03-2013 13:33:25.189 CMultiWriterFileSink::OnTsPacket() - Throttle to 1880 bytes
28-03-2013 13:33:25.189 CMultiWriterFileSink::OnTsPacket() - Throttle to 1880 bytes
28-03-2013 13:33:25.190 CMultiWriterFileSink::OnTsPacket() - Throttle to 3760 bytes
28-03-2013 13:33:25.190 CMultiWriterFileSink::OnTsPacket() - Throttle to 3760 bytes
28-03-2013 13:33:25.191 CMultiWriterFileSink::OnTsPacket() - Throttle to 3760 bytes
28-03-2013 13:33:25.191 CMultiWriterFileSink::OnTsPacket() - Throttle to 6016 bytes
28-03-2013 13:33:25.192 CMultiWriterFileSink::OnTsPacket() - Throttle to 7520 bytes
28-03-2013 13:33:25.192 CMultiWriterFileSink::OnTsPacket() - Throttle to 9400 bytes
28-03-2013 13:33:25.193 CMultiWriterFileSink::OnTsPacket() - Throttle to 15416 bytes
28-03-2013 13:33:25.194 CMultiWriterFileSink::OnTsPacket() - Throttle to 32336 bytes (max)
28-03-2013 13:40:19.100 MultiFileWriter: PrepareTSFile()
28-03-2013 13:40:19.101 MultiFileWriter: CreateNewTSFile.
28-03-2013 13:40:19.103 MultiFileWriter: new file created
28-03-2013 13:47:13.38 MultiFileWriter: PrepareTSFile()
28-03-2013 13:47:13.38 MultiFileWriter: CreateNewTSFile.
28-03-2013 13:47:13.39 MultiFileWriter: new file created
28-03-2013 13:54:07.180 MultiFileWriter: PrepareTSFile()
28-03-2013 13:54:07.180 MultiFileWriter: CreateNewTSFile.
28-03-2013 13:54:07.182 MultiFileWriter: new file created

Vielen Dank schon mal für die Hilfe :) Bei Bedarf poste ich gerne genaueres oder anderes :D
 

Ultra1988

Portal Member
December 15, 2008
23
0
Home Country
Germany Germany
Während ich auf Hilfe warte, habe ich heut mal die Logfiles gelöscht und Testweise mit Stoppuhr das TV genutzt:
Gestoppte Zeit ab Startzeit -- gestoppte Zeit nach letztem Ereignis -- Ereignis

Startzeit: 15:57Uhr
00:48,5 -- 0:48,5 -- Bild/Ton da
02:21,0 -- 1:32,4 -- Aussetzer
02:53,5 -- 0:32,5 -- Bild/Ton wieder da
08:58,2 -- 6:04,7 -- Aussetzer
10:10,3 -- 1:12,0 -- Bild/Ton wieder da
17:58,9 -- 7:48,6 -- Aussetzer
18:50,1 -- 0:51,2 -- Bild/Ton wieder da
22:15,3 -- 3:25,1 -- Aussetzer
22:27,4 -- 0:12,0 -- Fortsetzung
30:16,0 -- 7:48,6 -- Aussetzer
31:22,2 -- 1:06,2 -- Fortsetzung
Endzeit: 16:31Uhr

Die Logdateien sowohl vom Client als auch von Server liegen dem Anhang bei :)

EDIT: Im Clientlog (TSreader) sind verdächtig viele Einträge, kenne mich damit aber absolut nicht aus, und englische Forenbeiträge helfen mir mangels der Sprache auch nicht sonderlich weiter :(

EDIT2: Das steht seit neuestem in der TSReader.log:
Code:
01-04-2013 13:04:08.549 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:08.550 [1764]READ FAILED2
01-04-2013 13:04:08.563 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:08.563 [1764]READ FAILED1
01-04-2013 13:04:08.701 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:08.701 [1764]READ FAILED2
01-04-2013 13:04:08.713 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:08.713 [1764]READ FAILED2
01-04-2013 13:04:08.724 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:08.725 [1764]READ FAILED1
01-04-2013 13:04:09.026 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:09.026 [1764]READ FAILED2
01-04-2013 13:04:09.040 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:09.040 [1764]READ FAILED2
01-04-2013 13:04:09.050 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:09.050 [1764]READ FAILED1
01-04-2013 13:04:09.173 [540]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:09.173 [540]READ FAILED2
01-04-2013 13:04:09.184 [540]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:09.185 [540]READ FAILED2
01-04-2013 13:04:09.340 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:09.341 [1764]READ FAILED2
01-04-2013 13:04:09.352 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:09.352 [1764]READ FAILED2
01-04-2013 13:04:09.362 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:09.363 [1764]READ FAILED1
01-04-2013 13:04:09.588 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:09.589 [1764]READ FAILED2
01-04-2013 13:04:09.599 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:09.600 [1764]READ FAILED2
01-04-2013 13:04:09.612 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:09.613 [1764]READ FAILED1
01-04-2013 13:04:09.861 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:09.862 [1764]READ FAILED2
01-04-2013 13:04:09.873 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:09.874 [1764]READ FAILED2
01-04-2013 13:04:09.888 [1764]FileReader::Read() read failed - error = -2147024832
01-04-2013 13:04:09.888 [1764]READ FAILED1
 
Last edited:

Users who are viewing this thread

Top Bottom