Question / Help Random Spikes and Framedrops..

B

Balonator

New Member
So what will happen is that i usually run a 4 hour stream. during this time at random times my kb/s will go from 2000 (the normal i have it set to) to about 700 or even lower for a very short amount of time. maybe 5-8 seconds. and then go back to normal. this will repeat about 100+ times a night adding up to about 4000-12000 drop frames. (also there are sometimes hours when this doesnt even happen)

I have called my ISP several times over this with the same shrug every time. I have also rebooted everything in my network prior to streaming with no differences. and my internet connection usually runs 60Mbps down and 10Mbps up which should have no problems. I am officially out of ideas(apart from replacing everything). Send a medic.
(frame drop is bolded and underlined)

00:36:43: Multithreaded optimizations: On
00:36:43: Base resolution: 1600x900
00:36:43: Output resolution: 1064x600
00:36:43: ------------------------------------------
00:36:43: Loading up D3D10 on NVIDIA GeForce GT 630 (Adapter 1)...
00:36:44: ------------------------------------------
00:36:44: Audio Format: 48000 Hz
00:36:44: ------------------------------------------
00:36:44: Audio Channels: 2 Ch
00:36:44: Playback device Default
00:36:44: ------------------------------------------
00:36:44: Using desktop audio input: Speakers (VIA HD Audio)
00:36:44: Global Audio time adjust: 0
00:36:44: ------------------------------------------
00:36:44: Using auxilary audio input: Microphone (Yeti Stereo Microphone)
00:36:44: Mic time offset: 0
00:36:44: ------------------------------------------
00:36:44: Audio Encoding: AAC
00:36:44: bitrate: 128
00:36:44: Using bitmap image
00:36:44: Using text output
00:36:44: Using text output
00:36:44: Using bitmap image
00:36:44: Using bitmap image
00:36:44: Using text output
00:36:44: Using text output
00:36:44: ------------------------------------------
00:36:44: device: AmaRec Video Capture,
00:36:44: device id (null),
00:36:44: chosen type: RGB32, usingFourCC: false, res: 512x384 - 512x384, frameIntervals: 333666-333666
00:36:44: use buffering: false - 0, fourCC: 00000000
00:36:44: audio device: Disable,
00:36:44: audio device id Disabled,
00:36:44: audio time offset 0,
00:36:44:
00:36:44: Using directshow input
00:36:44: Using bitmap image
00:36:44: Scene buffering time set to 700
00:36:44: ------------------------------------------
00:36:44: Video Encoding: x264
00:36:44: fps: 30
00:36:44: width: 1064, height: 600
00:36:44: preset: superfast
00:36:44: profile: main
00:36:44: keyint: 60
00:36:44: CBR: yes
00:36:44: CFR: no
00:36:44: max bitrate: 2000
00:36:44: buffer size: 1000
00:36:44: ------------------------------------------
00:36:45: Using RTMP service: Twitch
00:36:45: Server selection: rtmp://live-jfk.twitch.tv/app
00:36:45: Interface: Realtek PCIe GBE Family Controller (ethernet, 1000 mbps)
00:36:46: Completed handshake with rtmp://live-jfk.twitch.tv/app in 220 ms.
00:36:47: SO_SNDBUF was at 65536
00:36:47: RTMPPublisher::SocketLoop: Send window optimization disabled by user.
00:41:20: RTMPPublisher::SocketLoop: Stalled for 2032 ms to write 272137 bytes (buffer: 0 / 272384), unstable connection?
00:42:22: FlushBufferedVideo: Flushing 9 packets over 267 ms
00:42:23: Total frames encoded: 10136, total frames duplicated: 0 (0.00%)
00:42:23: Total frames rendered: 10153, number of late frames: 1 (0.01%) (it's okay for some frames to be late)
00:42:23:
00:42:23: Profiler time results:
00:42:23:
00:42:23: ==============================================================
00:42:23: video thread frame - [100%] [avg time: 1.098 ms] [children: 38.1%] [unaccounted: 61.9%]
00:42:23: | scene->Preprocess - [24.4%] [avg time: 0.268 ms]
00:42:23: | GPU download and conversion - [13.7%] [avg time: 0.15 ms] [children: 12.6%] [unaccounted: 1.09%]
00:42:23: | | flush - [10.4%] [avg time: 0.114 ms]
00:42:23: | | CopyResource - [0.546%] [avg time: 0.006 ms]
00:42:23: | | conversion to 4:2:0 - [1.64%] [avg time: 0.018 ms]
00:42:23: Convert444Threads - [100%] [avg time: 0.41 ms] [children: 98.5%] [unaccounted: 1.46%]
00:42:23: | Convert444toNV12 - [98.5%] [avg time: 0.404 ms]
00:42:23: encoder thread frame - [100%] [avg time: 0.8 ms] [children: 2.75%] [unaccounted: 97.3%]
00:42:23: | sending stuff out - [2.75%] [avg time: 0.022 ms]
00:42:23: ==============================================================
00:42:23:
00:42:23:
00:42:23: Profiler CPU results:
00:42:23:
00:42:23: ==============================================================
00:42:23: video thread frame - [cpu time: avg 0.686 ms, total 6968.75 ms] [avg calls per frame: 1]
00:42:23: | scene->Preprocess - [cpu time: avg 0.064 ms, total 656.25 ms] [avg calls per frame: 1]
00:42:23: | GPU download and conversion - [cpu time: avg 0.118 ms, total 1203.13 ms] [avg calls per frame: 1]
00:42:23: | | flush - [cpu time: avg 0.069 ms, total 703.125 ms] [avg calls per frame: 1]
00:42:23: | | CopyResource - [cpu time: avg 0.009 ms, total 93.75 ms] [avg calls per frame: 1]
00:42:23: | | conversion to 4:2:0 - [cpu time: avg 0.027 ms, total 281.25 ms] [avg calls per frame: 1]
00:42:23: Convert444Threads - [cpu time: avg 0.361 ms, total 7328.13 ms] [avg calls per frame: 2]
00:42:23: | Convert444toNV12 - [cpu time: avg 0.359 ms, total 7281.25 ms] [avg calls per frame: 2]
00:42:23: encoder thread frame - [cpu time: avg 0.502 ms, total 5093.75 ms] [avg calls per frame: 1]
00:42:23: | sending stuff out - [cpu time: avg 0.01 ms, total 109.375 ms] [avg calls per frame: 1]
00:42:23: ==============================================================
00:42:23:
00:42:23: =====Stream End: 2016-10-02, 00:42:23=================================================
00:42:23: ~RTMPPublisher: Packet flush completed in 124 ms
00:42:23: ~RTMPPublisher: Send thread terminated in 0 ms
00:42:23: RTMPPublisher::SocketLoop: Graceful loop exit
00:42:23: ~RTMPPublisher: Socket thread terminated in 1 ms
00:42:47: ~RTMPPublisher: Final socket shutdown completed in 24182 ms
00:42:47: Average send payload: 6094 bytes, average send interval: 24 ms
00:42:47: Number of times waited to send: 6, Waited for a total of 24582 bytes
00:42:47: Number of b-frames dropped: 15 (0.15%), Number of p-frames dropped: 16 (0.16%), Total 31 (0.31%)
00:42:47: Number of bytes sent: 83903956


Any ideas on what i can do or what that drop means?
 
B

Balonator

New Member
here is more of an example of what i usually see. (still not at its worse but still present)


10:16:44: RTMPPublisher::SocketLoop: Stalled for 1612 ms to write 271822 bytes (buffer: 0 / 272384), unstable connection?
10:17:45: RTMPPublisher::SocketLoop: Stalled for 1905 ms to write 272080 bytes (buffer: 0 / 272384), unstable connection?
10:18:37: RTMPPublisher::SocketLoop: Stalled for 4527 ms to write 269742 bytes (buffer: 0 / 272384), unstable connection?
10:18:54: RTMPPublisher::SocketLoop: Stalled for 1539 ms to write 271811 bytes (buffer: 0 / 272384), unstable connection?
10:20:26: RTMPPublisher::SocketLoop: Stalled for 2297 ms to write 272319 bytes (buffer: 0 / 272384), unstable connection?
10:21:05: RTMPPublisher::SocketLoop: Stalled for 2120 ms to write 272169 bytes (buffer: 0 / 272384), unstable connection?
10:21:34: Audio timestamp for device 'Microphone (Yeti Stereo Microphone)' was behind target timestamp by 80
10:25:11: FlushBufferedVideo: Flushing 9 packets over 267 ms
10:25:11: Total frames encoded: 21953, total frames duplicated: 0 (0.00%)
10:25:11: Total frames rendered: 21969, number of late frames: 0 (0.00%) (it's okay for some frames to be late)
10:25:11:
10:25:11: Profiler time results:
10:25:11:
10:25:11: ==============================================================
10:25:11: video thread frame - [100%] [avg time: 1.027 ms] [children: 39%] [unaccounted: 61%]
10:25:11: | scene->Preprocess - [26%] [avg time: 0.267 ms]
10:25:11: | GPU download and conversion - [13%] [avg time: 0.134 ms] [children: 12%] [unaccounted: 1.07%]
10:25:11: | | flush - [10%] [avg time: 0.103 ms]
10:25:11: | | CopyResource - [0.584%] [avg time: 0.006 ms]
10:25:11: | | conversion to 4:2:0 - [1.36%] [avg time: 0.014 ms]
10:25:11: Convert444Threads - [100%] [avg time: 0.41 ms] [children: 98.5%] [unaccounted: 1.46%]
10:25:11: | Convert444toNV12 - [98.5%] [avg time: 0.404 ms]
10:25:11: encoder thread frame - [100%] [avg time: 0.809 ms] [children: 2.72%] [unaccounted: 97.3%]
10:25:11: | sending stuff out - [2.72%] [avg time: 0.022 ms]
10:25:11: ==============================================================
10:25:11:
10:25:11:
10:25:11: Profiler CPU results:
10:25:11:
10:25:11: ==============================================================
10:25:11: video thread frame - [cpu time: avg 0.586 ms, total 12875 ms] [avg calls per frame: 1]
10:25:11: | scene->Preprocess - [cpu time: avg 0.056 ms, total 1250 ms] [avg calls per frame: 1]
10:25:11: | GPU download and conversion - [cpu time: avg 0.1 ms, total 2203.13 ms] [avg calls per frame: 1]
10:25:11: | | flush - [cpu time: avg 0.069 ms, total 1531.25 ms] [avg calls per frame: 1]
10:25:11: | | CopyResource - [cpu time: avg 0.008 ms, total 187.5 ms] [avg calls per frame: 1]
10:25:11: | | conversion to 4:2:0 - [cpu time: avg 0.009 ms, total 203.125 ms] [avg calls per frame: 1]
10:25:11: Convert444Threads - [cpu time: avg 0.388 ms, total 17031.3 ms] [avg calls per frame: 2]
10:25:11: | Convert444toNV12 - [cpu time: avg 0.383 ms, total 16828.1 ms] [avg calls per frame: 2]
10:25:11: encoder thread frame - [cpu time: avg 0.524 ms, total 11500 ms] [avg calls per frame: 1]
10:25:11: | sending stuff out - [cpu time: avg 0.015 ms, total 343.75 ms] [avg calls per frame: 1]
10:25:11: ==============================================================
10:25:11:
10:25:11: =====Stream End: 2016-10-02, 10:25:11=================================================
10:25:11: ~RTMPPublisher: Packet flush completed in 134 ms
10:25:11: ~RTMPPublisher: Send thread terminated in 0 ms
10:25:11: RTMPPublisher::SocketLoop: Graceful loop exit
10:25:11: ~RTMPPublisher: Socket thread terminated in 0 ms
10:25:35: ~RTMPPublisher: Final socket shutdown completed in 24160 ms
10:25:35: Average send payload: 5957 bytes, average send interval: 23 ms
10:25:35: Number of times waited to send: 30, Waited for a total of 116224 bytes
10:25:35: Number of b-frames dropped: 112 (0.51%), Number of p-frames dropped: 109 (0.5%), Total 221 (1%)
10:25:35: Number of bytes sent: 180429064
 
B

Balonator

New Member
Here is a case of a sever frame drop. please any suggestions can help.


0:27:17: RTMPPublisher::SocketLoop: Stalled for 2811 ms to write 269094 bytes (buffer: 0 / 272384), unstable connection?
20:27:26: RTMPPublisher::SocketLoop: Stalled for 2218 ms to write 269367 bytes (buffer: 0 / 272384), unstable connection?
20:27:35: RTMPPublisher::SocketLoop: Stalled for 2114 ms to write 268921 bytes (buffer: 0 / 272384), unstable connection?
20:27:45: RTMPPublisher::SocketLoop: Stalled for 5167 ms to write 272363 bytes (buffer: 0 / 272384), unstable connection?
20:28:02: RTMPPublisher::SocketLoop: Stalled for 4213 ms to write 269980 bytes (buffer: 0 / 272384), unstable connection?
20:28:16: RTMPPublisher::SocketLoop: Stalled for 3353 ms to write 269048 bytes (buffer: 0 / 272384), unstable connection?
20:28:57: RTMPPublisher::SocketLoop: Stalled for 1585 ms to write 271144 bytes (buffer: 0 / 272384), unstable connection?
20:29:01: RTMPPublisher::SocketLoop: Stalled for 3733 ms to write 271621 bytes (buffer: 0 / 272384), unstable connection?
20:29:41: RTMPPublisher::SocketLoop: Stalled for 1983 ms to write 272229 bytes (buffer: 0 / 272384), unstable connection?
20:29:44: RTMPPublisher::SocketLoop: Stalled for 2644 ms to write 272096 bytes (buffer: 0 / 272384), unstable connection?
20:30:14: RTMPPublisher::SocketLoop: Stalled for 1989 ms to write 271296 bytes (buffer: 0 / 272384), unstable connection?
20:30:38: RTMPPublisher::SocketLoop: Stalled for 2624 ms to write 268819 bytes (buffer: 0 / 272384), unstable connection?
20:30:41: RTMPPublisher::SocketLoop: Stalled for 2535 ms to write 269934 bytes (buffer: 0 / 272384), unstable connection?
20:30:48: RTMPPublisher::SocketLoop: Stalled for 2133 ms to write 270197 bytes (buffer: 0 / 272384), unstable connection?
20:31:32: RTMPPublisher::SocketLoop: Stalled for 1541 ms to write 269579 bytes (buffer: 0 / 272384), unstable connection?
20:32:03: RTMPPublisher::SocketLoop: Stalled for 1820 ms to write 272226 bytes (buffer: 0 / 272384), unstable connection?
20:32:37: RTMPPublisher::SocketLoop: Stalled for 2063 ms to write 268441 bytes (buffer: 0 / 272384), unstable connection?
20:33:07: RTMPPublisher::SocketLoop: Stalled for 2213 ms to write 271551 bytes (buffer: 0 / 272384), unstable connection?
20:33:29: RTMPPublisher::SocketLoop: Stalled for 2366 ms to write 272292 bytes (buffer: 0 / 272384), unstable connection?
20:34:02: RTMPPublisher::SocketLoop: Stalled for 1959 ms to write 268596 bytes (buffer: 0 / 272384), unstable connection?
20:34:32: RTMPPublisher::SocketLoop: Stalled for 1665 ms to write 272376 bytes (buffer: 0 / 272384), unstable connection?
20:34:35: Audio timestamp for device 'Microphone (Yeti Stereo Microphone)' was behind target timestamp by 90
20:34:53: RTMPPublisher::SocketLoop: Stalled for 2736 ms to write 269052 bytes (buffer: 0 / 272384), unstable connection?
20:35:33: RTMPPublisher::SocketLoop: Stalled for 1632 ms to write 272272 bytes (buffer: 0 / 272384), unstable connection?
20:35:56: RTMPPublisher::SocketLoop: Stalled for 1907 ms to write 268512 bytes (buffer: 0 / 272384), unstable connection?
20:36:32: RTMPPublisher::SocketLoop: Stalled for 2080 ms to write 269618 bytes (buffer: 0 / 272384), unstable connection?
20:37:16: RTMPPublisher::SocketLoop: Stalled for 2417 ms to write 271784 bytes (buffer: 0 / 272384), unstable connection?
20:37:37: RTMPPublisher::SocketLoop: Stalled for 2212 ms to write 269947 bytes (buffer: 0 / 272384), unstable connection?
20:38:07: RTMPPublisher::SocketLoop: Stalled for 1832 ms to write 268385 bytes (buffer: 0 / 272384), unstable connection?
20:38:23: RTMPPublisher::SocketLoop: Stalled for 1851 ms to write 268389 bytes (buffer: 0 / 272384), unstable connection?
20:38:43: RTMPPublisher::SocketLoop: Stalled for 1592 ms to write 271893 bytes (buffer: 0 / 272384), unstable connection?
20:38:55: RTMPPublisher::SocketLoop: Stalled for 2337 ms to write 272154 bytes (buffer: 0 / 272384), unstable connection?
20:39:29: RTMPPublisher::SocketLoop: Stalled for 2832 ms to write 270619 bytes (buffer: 0 / 272384), unstable connection?
20:39:34: RTMPPublisher::SocketLoop: Stalled for 1675 ms to write 272009 bytes (buffer: 0 / 272384), unstable connection?
20:39:57: RTMPPublisher::SocketLoop: Stalled for 2607 ms to write 269601 bytes (buffer: 0 / 272384), unstable connection?
20:40:31: RTMPPublisher::SocketLoop: Stalled for 1838 ms to write 272237 bytes (buffer: 0 / 272384), unstable connection?
20:40:52: RTMPPublisher::SocketLoop: Stalled for 1920 ms to write 270131 bytes (buffer: 0 / 272384), unstable connection?
20:41:16: RTMPPublisher::SocketLoop: Stalled for 2591 ms to write 268494 bytes (buffer: 0 / 272384), unstable connection?
20:41:25: RTMPPublisher::SocketLoop: Stalled for 1622 ms to write 272327 bytes (buffer: 0 / 272384), unstable connection?
20:41:51: RTMPPublisher::SocketLoop: Stalled for 1920 ms to write 272183 bytes (buffer: 0 / 272384), unstable connection?
20:42:13: RTMPPublisher::SocketLoop: Stalled for 2107 ms to write 269940 bytes (buffer: 0 / 272384), unstable connection?
20:42:22: RTMPPublisher::SocketLoop: Stalled for 2101 ms to write 272363 bytes (buffer: 0 / 272384), unstable connection?
20:42:32: RTMPPublisher::SocketLoop: Stalled for 1880 ms to write 271011 bytes (buffer: 0 / 272384), unstable connection?
20:42:42: RTMPPublisher::SocketLoop: Stalled for 1527 ms to write 269947 bytes (buffer: 0 / 272384), unstable connection?
20:43:03: RTMPPublisher::SocketLoop: Stalled for 1501 ms to write 272225 bytes (buffer: 0 / 272384), unstable connection?
20:43:23: RTMPPublisher::SocketLoop: Stalled for 1627 ms to write 268683 bytes (buffer: 0 / 272384), unstable connection?
20:43:55: RTMPPublisher::SocketLoop: Stalled for 3101 ms to write 269002 bytes (buffer: 0 / 272384), unstable connection?
20:45:26: RTMPPublisher::SocketLoop: Stalled for 2378 ms to write 270654 bytes (buffer: 0 / 272384), unstable connection?
20:45:50: RTMPPublisher::SocketLoop: Stalled for 1549 ms to write 268456 bytes (buffer: 0 / 272384), unstable connection?
20:46:00: RTMPPublisher::SocketLoop: Stalled for 1547 ms to write 270951 bytes (buffer: 0 / 272384), unstable connection?
20:46:10: RTMPPublisher::SocketLoop: Stalled for 1507 ms to write 269373 bytes (buffer: 0 / 272384), unstable connection?
20:46:18: Audio timestamp for device 'Microphone (Yeti Stereo Microphone)' was behind target timestamp by 100
20:46:41: RTMPPublisher::SocketLoop: Stalled for 1509 ms to write 270935 bytes (buffer: 0 / 272384), unstable connection?
20:47:22: RTMPPublisher::SocketLoop: Stalled for 1738 ms to write 272088 bytes (buffer: 0 / 272384), unstable connection?
20:47:32: RTMPPublisher::SocketLoop: Stalled for 1507 ms to write 271936 bytes (buffer: 0 / 272384), unstable connection?
(had to cut a bunch off to fit into forum)
 
A

alpinlol

Active Member
use 1:1 Bitrate/Buffer instead of 1/0.5

Please post a Log how its described in the Sticky which is quite easy since its just a click upload within the OBS menu
 
You must log in or register to reply here.
Top