data rate > 9Mbit/s und Buffer Overflow

[marlo77]

Hi @ all,

habe gestern das aktulle yadi Image 2.0.0.6 (21.12.2004) auf meine Box gezogen und neues JtG 0.7.4. installiert und bin gerade dabei SG1 Staffel 7 von Scifi zu Streamen, dabei gibt er mir ein paar merkwürdige Fehlermeldungen raus, von denen ich nicht weiß, ob sie durch die Box oder den Stream verursacht werden., Vielleicht wisst Ihr ja damit was anzufangen.

Hier das Logfile:
Jack the Grabber Logfile
Erstellt am: 31.12.2004 10:20
Timer Aufnahme
Kanal=SCI FI
Sendung=Stargate - %SERIE%
Start=31.12.2004 10:20:00
Ende=11:05
*******************************************************
@00:44:57: Aufnahme Event:
@00:44:57: IP: 192.168.0.140
@00:44:57: VPid: 2047
@00:44:57: APid 1: 2048
@00:44:57: APid 2: 0
@00:44:57: APid 3: 0
@00:44:57: Filename: G:\Streams\2004-12-31-Stargate - Der falsche Klon\Stargate - Der falsche Klon.mpg
@00:44:57: Splittsize: 39999
@00:44:57: KanalID: 1100850024
@00:44:57: EPG Infos geschrieben
@00:44:57: -host 192.168.0.140 -vp 7FF -ap 800 -asplit 3 -gtc -o "G:\Streams\2004-12-31-Stargate - Der falsche Klon\Stargate - Der falsche Klon-1"
@00:00:02: 10:20:29 to DBox: VIDEO 31341 16 0 1 va 7ff 800
@00:00:02: 10:20:29 from DBox: INFO: IP c0a80064 Port 31341
@00:00:02: 10:20:29 from DBox: PID va 2 7ff 800
@00:00:02: 10:20:29 to DBox: START
@00:00:02: 10:20:29 from DBox: INFO: UdpSender() - PID536 R0 W0
@00:00:02: 10:20:29 from DBox: INFO: DmxReader() - Pid 7ff 204960 0 0
@00:00:02: 10:20:29 from DBox: INFO: DmxReader() - Pid 800 29280 0 0
@00:00:08: Video: 2047
@00:00:08: Audio1: 2048
@00:00:08: Audio2: 0
@00:00:08: Audio3: 0
@00:00:08: Beginne Aufnahme nach: G:\Streams\2004-12-31-Stargate - Der falsche Klon
@00:00:17: 10:20:44 data rate > 9Mbit/s
@00:00:18: Video:[0]G:\Streams\2004-12-31-Stargate - Der falsche Klon\stargate - der falsche klon-1_01_00.mpv
@00:00:18: 10:20:45 found video stream (e0)
@00:00:18: Audio_1:[0]G:\Streams\2004-12-31-Stargate - Der falsche Klon\stargate - der falsche klon-1_01_00.mp2
@00:00:18: 10:20:45 found mp2 stream (c0)
@00:00:21: 10:20:48 17:22:26.09: video resync
@00:00:37: 10:21:04 data rate > 9Mbit/s
@00:02:22: 10:22:49 data rate > 9Mbit/s
@00:03:02: 10:23:30 data rate > 9Mbit/s
@00:03:37: 10:24:05 data rate > 9Mbit/s
@00:04:17: 10:24:45 data rate > 9Mbit/s
@00:05:32: 10:26:00 data rate > 9Mbit/s
@00:07:02: 10:27:30 data rate > 9Mbit/s
@00:07:57: 10:28:25 data rate > 9Mbit/s
@00:11:02: 10:31:30 data rate > 9Mbit/s
@00:11:52: 10:32:20 data rate > 9Mbit/s
@00:14:42: 10:35:11 data rate > 9Mbit/s
@00:16:42: 10:37:11 data rate > 9Mbit/s
@00:17:12: 10:37:41 data rate > 9Mbit/s
@00:17:37: 10:38:06 data rate > 9Mbit/s
@00:18:07: 10:38:36 data rate > 9Mbit/s
@00:18:37: 10:39:06 data rate > 9Mbit/s
@00:19:22: 10:39:51 data rate > 9Mbit/s
@00:22:32: 10:43:01 data rate > 9Mbit/s
@00:22:37: 10:43:06 data rate > 9Mbit/s
@00:23:12: 10:43:41 data rate > 9Mbit/s
@00:23:47: 10:44:16 data rate > 9Mbit/s
@00:25:58: 10:46:27 Buffer Overflow Start: packet 261118 read 262079 max 267199
@00:25:58: 10:46:27 Buffer Overflow Stop: packet count 264558
@00:25:58: 10:46:27 Buffer Overflow Start: packet 261146 read 262079 max 267199
@00:25:58: 10:46:27 Buffer Overflow Start: packet 25240 read 25359 max 26383
@00:25:59: 10:46:29 Buffer Overflow Stop: packet count 264600
@00:26:00: 10:46:30 Buffer Overflow Stop: packet count 25620
@00:26:02: 10:46:32 data rate > 9Mbit/s
@00:26:03: 10:46:32 Buffer Overflow Start: packet 261145 read 262246 max 267366
@00:26:03: 10:46:32 Buffer Overflow Stop: packet count 264740
@00:26:03: 10:46:32 Buffer Overflow Start: packet 261823 read 262286 max 267406
@00:26:03: 10:46:32 Buffer Overflow Stop: packet count 264821
@00:28:47: 10:49:17 data rate > 9Mbit/s
@00:29:42: 10:50:12 data rate > 9Mbit/s
@00:30:02: 10:50:32 data rate > 9Mbit/s
@00:30:17: 10:50:47 data rate > 9Mbit/s
@00:31:12: 10:51:42 data rate > 9Mbit/s
@00:31:32: 10:52:02 data rate > 9Mbit/s
@00:32:57: 10:53:27 data rate > 9Mbit/s
@00:33:22: 10:53:52 data rate > 9Mbit/s
@00:33:42: 10:54:12 data rate > 9Mbit/s
@00:34:17: 10:54:47 data rate > 9Mbit/s
@00:34:47: 10:55:17 data rate > 9Mbit/s
@00:35:27: 10:55:12 data rate > 9Mbit/s
@00:35:34: 10:55:19 from DBox: ERROR: main() - SPkt buffer overflow
@00:35:35: 10:55:20 from DBox: ERROR: main() - SPkt buffer overflow
@00:35:35: 10:55:20 from DBox: ERROR: main() - SPkt buffer overflow
@00:35:35: 10:55:20 from DBox: ERROR: main() - SPkt buffer overflow
@00:35:35: 10:55:20 from DBox: ERROR: main() - SPkt buffer overflow
@00:35:35: 10:55:20 from DBox: ERROR: main() - SPkt buffer overflow
@00:35:35: 10:55:20 from DBox: ERROR: main() - SPkt buffer overflow
@00:35:35: 10:55:20 from DBox: ERROR: main() - SPkt buffer overflow
@00:35:35: 10:55:20 from DBox: ERROR: main() - SPkt buffer overflow
@00:35:35: 10:55:20 from DBox: ERROR: main() - SPkt buffer overflow
@00:35:35: 10:55:20 from DBox: ERROR: main() - SPkt buffer overflow
@00:35:35: 10:55:20 from DBox: ERROR: main() - SPkt buffer overflow
@00:35:50: 10:55:35 packet lost 35378 35120
@00:35:50: 10:55:35 20 packets lost
@00:35:50: 10:55:35 17:57:56.18: corrupt audio frame(s)
@00:35:51: 10:55:36 packet lost 362865 360360
@00:35:51: 10:55:36 140 packets lost
@00:35:51: 10:55:36 17:57:58.61: video resync
@00:36:42: 10:56:27 data rate > 9Mbit/s
@00:36:47: 10:56:32 data rate > 9Mbit/s
@00:37:42: 10:57:27 data rate > 9Mbit/s
@00:37:52: 10:57:37 data rate > 9Mbit/s
@00:38:42: 10:58:27 data rate > 9Mbit/s
@00:39:08: 10:58:54 Buffer Overflow Start: packet 393216 read 394207 max 399327
@00:39:08: 10:58:54 Buffer Overflow Stop: packet count 396757
@00:39:08: 10:58:54 Buffer Overflow Start: packet 393257 read 394207 max 399327
@00:39:08: 10:58:54 Buffer Overflow Start: packet 38300 read 38438 max 39462
@00:39:09: 10:58:54 Buffer Overflow Stop: packet count 396701
@00:39:09: 10:58:54 Buffer Overflow Start: packet 393408 read 394207 max 399327
@00:39:09: 10:58:54 Buffer Overflow Stop: packet count 396741
@00:39:09: 10:58:54 Buffer Overflow Stop: packet count 38640
@00:39:09: 10:58:54 Buffer Overflow Start: packet 393216 read 394207 max 399327
@00:39:09: 10:58:54 Buffer Overflow Stop: packet count 396740
@00:39:09: 10:58:54 Buffer Overflow Start: packet 393240 read 394207 max 399327
@00:39:09: 10:58:54 Buffer Overflow Start: packet 38300 read 38438 max 39462
@00:39:11: 10:58:56 Buffer Overflow Stop: packet count 396760
@00:39:11: 10:58:56 Buffer Overflow Start: packet 393294 read 394284 max 399404
@00:39:11: 10:58:56 Buffer Overflow Stop: packet count 396801
@00:39:11: 10:58:56 Buffer Overflow Start: packet 393319 read 394359 max 399479
@00:39:11: 10:58:56 Buffer Overflow Stop: packet count 396863
@00:39:11: 10:58:56 Buffer Overflow Start: packet 393350 read 394359 max 399479
@00:39:11: 10:58:56 Buffer Overflow Stop: packet count 396760
@00:39:11: 10:58:57 Buffer Overflow Start: packet 393349 read 394359 max 399479
@00:39:11: 10:58:57 Buffer Overflow Stop: packet count 396780
@00:39:11: 10:58:57 Buffer Overflow Start: packet 393319 read 394359 max 399479
@00:39:11: 10:58:57 Buffer Overflow Stop: packet count 396841
@00:39:11: 10:58:57 Buffer Overflow Start: packet 393327 read 394359 max 399479
@00:39:11: 10:58:57 Buffer Overflow Stop: packet count 396760
@00:39:11: 10:58:57 Buffer Overflow Start: packet 393317 read 394359 max 399479
@00:39:11: 10:58:57 Buffer Overflow Stop: packet count 396823
@00:39:11: 10:58:57 Buffer Overflow Start: packet 393319 read 394359 max 399479
@00:39:12: 10:58:57 Buffer Overflow Stop: packet count 396886
@00:39:12: 10:58:57 Buffer Overflow Start: packet 393299 read 394359 max 399479
@00:39:12: 10:58:57 Buffer Overflow Stop: packet count 396760
@00:39:12: 10:58:57 Buffer Overflow Start: packet 393298 read 394359 max 399479
@00:39:12: 10:58:57 Buffer Overflow Stop: packet count 396804
@00:39:12: 10:58:57 Buffer Overflow Start: packet 393319 read 394359 max 399479
@00:39:12: 10:58:57 Buffer Overflow Stop: packet count 396867
@00:39:12: 10:58:57 Buffer Overflow Start: packet 393355 read 394359 max 399479
@00:39:12: 10:58:57 Buffer Overflow Stop: packet count 396760
@00:39:12: 10:58:57 Buffer Overflow Start: packet 393354 read 394359 max 399479
@00:39:12: 10:58:57 Buffer Overflow Stop: packet count 396787
@00:39:12: 10:58:57 Buffer Overflow Start: packet 393319 read 394359 max 399479
@00:39:12: 10:58:57 Buffer Overflow Stop: packet count 396849
@00:39:12: 10:58:57 Buffer Overflow Start: packet 393336 read 394359 max 399479
@00:39:12: 10:58:58 Buffer Overflow Stop: packet count 396760
@00:39:12: 10:58:58 Buffer Overflow Start: packet 393335 read 394359 max 399479
@00:39:12: 10:58:58 Buffer Overflow Stop: packet count 396768
@00:39:12: 10:58:58 Buffer Overflow Start: packet 393319 read 394359 max 399479
@00:39:12: 10:58:58 Buffer Overflow Stop: packet count 396896
@00:39:12: 10:58:58 Buffer Overflow Start: packet 393309 read 394359 max 399479
@00:39:12: 10:58:58 Buffer Overflow Stop: packet count 396760
@00:39:12: 10:58:58 Buffer Overflow Start: packet 393308 read 394359 max 399479
@00:39:12: 10:58:58 Buffer Overflow Stop: packet count 396815
@00:39:13: 10:58:58 Buffer Overflow Start: packet 393319 read 394359 max 399479
@00:39:13: 10:58:58 Buffer Overflow Stop: packet count 396880
@00:39:13: 10:58:58 Buffer Overflow Start: packet 393292 read 394359 max 399479
@00:39:13: 10:58:58 Buffer Overflow Stop: packet count 396760
@00:39:13: 10:58:58 Buffer Overflow Start: packet 393370 read 394359 max 399479
@00:39:13: 10:58:58 Buffer Overflow Stop: packet count 396800
@00:39:13: 10:58:58 Buffer Overflow Start: packet 393319 read 394359 max 399479
@00:39:13: 10:58:58 Buffer Overflow Stop: packet count 396864
@00:39:13: 10:58:58 Buffer Overflow Start: packet 393354 read 394359 max 399479
@00:39:13: 10:58:58 Buffer Overflow Stop: packet count 396760
@00:39:13: 10:58:58 Buffer Overflow Start: packet 393353 read 394359 max 399479
@00:39:13: 10:58:58 Buffer Overflow Stop: packet count 396786
@00:39:13: 10:58:58 Buffer Overflow Start: packet 393319 read 394359 max 399479
@00:39:13: 10:58:59 Buffer Overflow Stop: packet count 396850
@00:39:13: 10:58:59 Buffer Overflow Start: packet 393337 read 394359 max 399479
@00:39:13: 10:58:59 Buffer Overflow Stop: packet count 396760
@00:39:13: 10:58:59 Buffer Overflow Start: packet 393336 read 394359 max 399479
@00:39:13: 10:58:59 Buffer Overflow Stop: packet count 396770
@00:39:13: 10:58:59 Buffer Overflow Start: packet 393320 read 394359 max 399479
@00:39:13: 10:58:59 Buffer Overflow Stop: packet count 396760
@00:39:13: 10:58:59 Buffer Overflow Start: packet 393314 read 394359 max 399479
@00:39:13: 10:58:59 Buffer Overflow Stop: packet count 396823
@00:39:13: 10:58:59 Buffer Overflow Start: packet 393363 read 394359 max 399479
@00:39:13: 10:58:59 Buffer Overflow Stop: packet count 38660
@00:39:13: 10:58:59 Buffer Overflow Stop: packet count 396900
@00:39:14: 10:58:59 Buffer Overflow Start: packet 393326 read 394505 max 399625
@00:39:14: 10:58:59 Buffer Overflow Stop: packet count 397040
@00:39:14: 10:59:00 Buffer Overflow Start: packet 393306 read 394727 max 399847
@00:39:14: 10:59:00 Buffer Overflow Stop: packet count 397320
@00:39:14: 10:59:00 Buffer Overflow Start: packet 393317 read 394881 max 400001
@00:39:14: 10:59:00 Buffer Overflow Stop: packet count 396803
@00:39:14: 10:59:00 Buffer Overflow Start: packet 393370 read 395051 max 400171
@00:39:14: 10:59:00 Buffer Overflow Stop: packet count 397600
@00:41:22: 11:01:08 data rate > 9Mbit/s
@00:41:32: 11:01:18 data rate > 9Mbit/s
@00:42:32: 11:02:18 data rate > 9Mbit/s
@00:42:52: 11:02:38 data rate > 9Mbit/s
@00:43:22: 11:03:08 data rate > 9Mbit/s
@00:43:47: 11:03:33 data rate > 9Mbit/s
@00:44:42: 11:04:28 data rate > 9Mbit/s
@00:45:17: Ende Aufnahme
******************************************************
Video Ausgabe:
Video[0] FileSize=669491200
******************************************************
Audio Ausgabe:
Audio_1[0] FileSize=64512000
******************************************************
Muxer Ausgabe:

******************************************************
Video High:
Video Low:
Audio High:
Audio Low:
******************************************************
@00:45:17: 11:05:03 to DBox: STOP
@00:45:17: 11:05:03 network statistics:
@00:45:17: max: 9,8 Mbit/s total data rate
@00:45:17: average: 3,7 Mbit/s total data rate
@00:45:18: average: 1,490 Mbit/s resend data rate
@00:45:18: count: 337780 resend packets
@00:45:18: average: 1 resend packet per 2,5 total packets
@00:45:18: max: 256 packets per resend
@00:45:18: average: 75,0 packets per resend
@00:45:18: 11:05:03 TcpSender stopped
@00:45:18: 11:05:04 ESSync stopped - stream errors found
@00:45:18: 11:05:04 KeyboardRecordControl stopped
@00:45:18: udrec beendet
@00:45:21:
******************************************************
Jack wünscht viel Spass mit der Aufnahme...
besucht uns auch mal auf: http://www.jackthegrabber.de

Habe zu diesem Prob nichts gefunden, sorry, wenn diesbezgl. doch schon was vorhanden ist.

marlo77

[jmittelst]

Datarate > 9Mbit/s? Auf SciFi?
Irgendwas läuft da schief. Ich streame gerade die 7.Staffel, keine Meldungen bislang. Ich streame TS.
cu
Jens

[marlo77]

Habe das Problem nach Neustart der Box immernoch. Streame ES.

marlo77

[Pedant]

Hallo marlo77,

"data rate > 9Mbit/s" ist erstmal nur ein Hinweis, dass es eng wird, noch kein Fehler.
"packet lost" und "Buffer Overflow" sind Fehlermeldungen.
In der Zusammenfassung steht "max: 9,8 Mbit/s total data rate", was die Fehler erklärt.
So hohe Datenraten können nicht fehlerfrei gestreamt werden.
Wieso es aber bei SciFi zu so hohen Datenraten kommt oder die dbox nur denkt es wäre so, bleibt unklar.

Wie sieht es denn bei anderen Sendern aus? Sind da die Datenraten normal?

Guten Rutsch
Frank

[marlo77]

Alos hab mal bei anderen Sendern getestet. Gleiches Prob! Bin auf yadi 2.0.0.5 immernoch >9 Mbits. Gibt es vielleicht irgendeine Funktion in der Box, die zusätzliche Daten verursacht? SPTS ist übrigens ausgeschaltet.

marlo77

[KeXXeN]

So,

Ich konnte deinen Fehler heute am eigenen Leibe reproduzieren.

Von der einen Box mit MCE gestreamt und von der anderen Box nen Film gestreamt, nebenher dann noch ein paar Filme mit GordianKnot umgewandelt.

Da ich bei MCE aufgrund einen Irrsinnigen Wahnanfalles die Prozesse auf höchste Priorität gestellt hatte, und ich dies erst nach ca 20 Sekunden (dem Umwandeln eines Musikstückes) Rückgängig machen konnte, stauten sich einige Daten auf der Box an und versuchten sich dann in einer Flutwelle auf meinen PC zu ergießen.

Das Fazit war folgendes:

@00:10:52: 22:50:57 Buffer Overflow Stop: packet count 224585
@00:10:52: 22:50:57 Buffer Overflow Start: packet 224525 read 224584 max 229704
@00:10:52: 22:50:57 Buffer Overflow Stop: packet count 224584
@00:10:52: 22:50:58 Buffer Overflow Start: packet 224525 read 224584 max 229704
@00:10:52: 22:50:58 Buffer Overflow Stop: packet count 224660
@00:10:52: 22:50:58 Buffer Overflow Start: packet 224525 read 224584 max 229704
@00:10:52: 22:50:58 Buffer Overflow Stop: packet count 224660
@00:10:52: 22:50:58 Buffer Overflow Start: packet 224525 read 224584 max 229704
@00:10:52: 22:50:58 Buffer Overflow Stop: packet count 224660
@00:10:52: 22:50:58 10921 packets lost
@00:10:52: 22:50:58 Buffer Overflow Start: packet 221420 read 224585 max 229705
@00:10:52: 22:51:00 Buffer Overflow Stop: packet count 224848
@00:10:52: 22:51:03 data rate > 9Mbit/s

Dazu noch einige verlorene Packete usw.

Ich tippe also mal darauf das dein System zeitweilig vollkommen ausgelastet ist und Udrec die Daten nicht schnell genug von der Box abgreifen kann.

Am Film und dessen Datenrate und an meinem, ohne mich selber loben zu wollen, optimal laufendem Netzwerk lag es absolut nicht. Auch nicht an der Box oder dem Image, schon gar nicht an Udrec oder JTG, sondern einfach an kurzzeitig zu wenig Rechenleistung.