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

Ultra1988 · March 28, 2013

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

mrbonsen · March 28, 2013

@Ultra1988

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

ausfüllen der systemspezifikationen (im profil) würde zusätzlich auch sehr helfen

Ultra1988 · March 28, 2013

mrbonsen said:
ausfüllen der systemspezifikationen (im profil) würde zusätzlich auch sehr helfen

So, habs nachgetragen

Wie sieht die Diagnose aus, Doktor?

Ultra1988 · March 30, 2013

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

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

Ultra1988

Portal Member

mrbonsen

Super User

Ultra1988

Portal Member

Ultra1988

Portal Member

Users who are viewing this thread