FerretBomb
Active Member
I've been having minor issues/annoyances with my stream for a long while now; namely, micro-hitching (I attribute this to the lagged frames I'm getting from somewhere) and more irritatingly, a growing delay for all of my viewers. The longer they watch, the greater the delay grows.
I'm fairly certain this is on Twitch's side, as a refresh of the page returns it to the initial 5-8 second delay between chat send and showing up on the stream. Generally it appears to run around 20 seconds every half-hour or so. Still, a lot of streamers I watch do not have this issue, and I'd like to know if there's anything I can do to ease the load on their end.
As far as the micro-hitching, it appears most of the delay happens in preprocess, or Convert444Threads ([528%]). No idea what's causing that. Not really playing anything overly-heavy on the game settings, and the five scene layers are a watermark, background for my chatbox, window capture for my chat, game capture for the game, and a webcam. Doesn't seem like it would be super-heavy. I also did not have the CPU going above 80% at any time (any a G1 i7 doesn't have the core-parking issue or stepdown of the later versions), and maintained reasonable core and GPU temps throughout the cast. Normally stream at 720@60, but also happens at 720@30, and 1080@30. Note that there are a few different presets/profiles being used in the log below; only the last one (720p@60 CBR 2500kbps) should be used for diagnosis, included the full logfile for completeness though.
Zero dropped frames means not a network issue, or a server receiving issue. Same problem on three different servers (Chicago, Dallas, San Antonio).
Links to the VODs:
Part 1- http://www.twitch.tv/ferretbomb/b/430706092
Part 2- http://www.twitch.tv/ferretbomb/b/430736375 (switched to CBR to try and see if that would fix it; momentary audio hitches are SUPER-noticeable at about the 29 minute mark, with the in-game music 'muting' for a split second at a time; the same happens with my voice, but less noticeably)
Log of the VOD session:
I'm fairly certain this is on Twitch's side, as a refresh of the page returns it to the initial 5-8 second delay between chat send and showing up on the stream. Generally it appears to run around 20 seconds every half-hour or so. Still, a lot of streamers I watch do not have this issue, and I'd like to know if there's anything I can do to ease the load on their end.
As far as the micro-hitching, it appears most of the delay happens in preprocess, or Convert444Threads ([528%]). No idea what's causing that. Not really playing anything overly-heavy on the game settings, and the five scene layers are a watermark, background for my chatbox, window capture for my chat, game capture for the game, and a webcam. Doesn't seem like it would be super-heavy. I also did not have the CPU going above 80% at any time (any a G1 i7 doesn't have the core-parking issue or stepdown of the later versions), and maintained reasonable core and GPU temps throughout the cast. Normally stream at 720@60, but also happens at 720@30, and 1080@30. Note that there are a few different presets/profiles being used in the log below; only the last one (720p@60 CBR 2500kbps) should be used for diagnosis, included the full logfile for completeness though.
Zero dropped frames means not a network issue, or a server receiving issue. Same problem on three different servers (Chicago, Dallas, San Antonio).
Links to the VODs:
Part 1- http://www.twitch.tv/ferretbomb/b/430706092
Part 2- http://www.twitch.tv/ferretbomb/b/430736375 (switched to CBR to try and see if that would fix it; momentary audio hitches are SUPER-noticeable at about the 29 minute mark, with the in-game music 'muting' for a split second at a time; the same happens with my voice, but less noticeably)
Log of the VOD session:
Code:
23:44:17: Video Source Plugin strings not found, dynamically loading 38 strings
23:44:26: Open Broadcaster Software v0.541b - 64bit ( ^ω^)
23:44:26: -------------------------------
23:44:26: CPU Name: Intel(R) Core(TM) i7 CPU 920 @ 2.67GHz
23:44:26: CPU Speed: 2672MHz
23:44:26: Physical Memory: 49143MB Total, 39257MB Free
23:44:26: stepping id: 4, model 10, family 6, type 0, extmodel 0, extfamily 0, HTT 1, logical cores 8, total cores 4
23:44:26: monitor 1: pos={-1920, 0}, size={1920, 1080}
23:44:26: monitor 2: pos={0, 0}, size={1920, 1080}
23:44:26: monitor 3: pos={1920, 0}, size={1920, 1080}
23:44:26: Windows Version: 6.1 Build 7601 S
23:44:26: Aero is Enabled
23:44:26: -------------------------------
23:44:26: OBS Modules:
23:44:26: Base Address Module
23:44:26: 000000003FB60000 OBS.exe
23:44:26: 00000000F0C50000 OBSApi.dll
23:44:26: 00000000FAAB0000 DShowPlugin.dll
23:44:26: 00000000FAC40000 GraphicsCapture.dll
23:44:26: 00000000FAA90000 NoiseGate.dll
23:44:26: 00000000FAA70000 PSVPlugin.dll
23:44:26: 00000000FAB90000 scenesw.dll
23:44:26: 00000000FAA60000 VideoSourcePluginWrapper.dll
23:44:26: ------------------------------------------
23:44:26: Adapter 1
23:44:26: Video Adapter: AMD Radeon HD 6800 Series
23:44:26: Video Adapter Dedicated Video Memory: 1017868288
23:44:26: Video Adapter Shared System Memory: 4021872640
23:44:26: =====Stream Start: 2013-07-14, 23:44:26===============================================
23:44:26: Multithreaded optimizations: On
23:44:26: Base resolution: 1920x1080
23:44:26: Output resolution: 1280x720
23:44:26: ------------------------------------------
23:44:26: Loading up D3D10...
23:44:27: Playback device Default
23:44:27: ------------------------------------------
23:44:27: Using desktop audio input: Speakers (SoundMAX Integrated Digital HD Audio)
23:44:27: ------------------------------------------
23:44:27: Using auxilary audio input: Microphone (SoundMAX Integrated Digital HD Audio)
23:44:27: Using Mic QPC timestamps
23:44:27: ------------------------------------------
23:44:27: Audio Encoding: AAC
23:44:27: bitrate: 128
23:44:27: Using bitmap image
23:44:27: ------------------------------------------
23:44:27: device: Logitech HD Pro Webcam C920,
23:44:27: device id \\?\usb#vid_046d&pid_082d&mi_00#6&3361f4d&0&0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
23:44:27: chosen type: I420, usingFourCC: false, res: 1920x1080 - 1920x1080, frameIntervals: 333333-2000000, fourCC: 'I420'
23:44:27: audio device: Disable,
23:44:27: audio device id Disabled,
23:44:27:
23:44:27: Using directshow input
23:44:27: Using Window Capture
23:44:27: Using bitmap image
23:44:27: Using Window Capture
23:44:27: ------------------------------------------
23:44:27: Video Encoding: x264
23:44:27: fps: 30
23:44:27: width: 1280, height: 720
23:44:27: preset: veryfast
23:44:27: CBR: no
23:44:27: CFR: no
23:44:27: max bitrate: 2600
23:44:27: buffer size: 2600
23:44:27: quality: 8
23:44:27: ------------------------------------------
23:44:27: Syncing audio to video time (WARNING: you should not be doing this if you are just having webcam desync, that's a separate issue)
23:44:45: Total frames rendered: 527, number of frames that lagged: 0 (0.00%) (it's okay for some frames to lag)
23:44:48: =====Stream End: 2013-07-14, 23:44:48=================================================
23:59:13: =====Stream Start: 2013-07-14, 23:59:13===============================================
23:59:13: Multithreaded optimizations: On
23:59:13: Base resolution: 1920x1080
23:59:13: Output resolution: 1280x720
23:59:13: ------------------------------------------
23:59:13: Loading up D3D10...
23:59:14: Playback device Default
23:59:14: ------------------------------------------
23:59:14: Using desktop audio input: Speakers (SoundMAX Integrated Digital HD Audio)
23:59:14: ------------------------------------------
23:59:14: Using auxilary audio input: Microphone (SoundMAX Integrated Digital HD Audio)
23:59:14: Using Mic QPC timestamps
23:59:14: ------------------------------------------
23:59:14: Audio Encoding: AAC
23:59:14: bitrate: 128
23:59:14: Using bitmap image
23:59:14: ------------------------------------------
23:59:14: device: Logitech HD Pro Webcam C920,
23:59:14: device id \\?\usb#vid_046d&pid_082d&mi_00#6&3361f4d&0&0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
23:59:14: chosen type: I420, usingFourCC: false, res: 1920x1080 - 1920x1080, frameIntervals: 333333-2000000, fourCC: 'I420'
23:59:14: audio device: Disable,
23:59:14: audio device id Disabled,
23:59:14:
23:59:14: Using directshow input
23:59:14: Using Window Capture
23:59:14: Using bitmap image
23:59:14: Using Window Capture
23:59:14: Using graphics capture
23:59:14: ------------------------------------------
23:59:14: Video Encoding: x264
23:59:14: fps: 60
23:59:14: width: 1280, height: 720
23:59:14: preset: veryfast
23:59:14: CBR: no
23:59:14: CFR: no
23:59:14: max bitrate: 2500
23:59:14: buffer size: 2500
23:59:14: quality: 10
23:59:14: ------------------------------------------
23:59:14: Syncing audio to video time (WARNING: you should not be doing this if you are just having webcam desync, that's a separate issue)
23:59:14: SharedTexCapture hooked
23:59:18: GraphicsCaptureSource::NewCapture: eliminating old capture
23:59:18: SharedTexCapture hooked
00:00:11: Using graphics capture
00:00:28: SharedTexCapture hooked
00:00:43: SharedTexCapture hooked
00:00:46: GraphicsCaptureSource::NewCapture: eliminating old capture
00:00:46: SharedTexCapture hooked
00:01:14: Total frames rendered: 7174, number of frames that lagged: 156 (2.17%) (it's okay for some frames to lag)
00:01:15: =====Stream End: 2013-07-15, 00:01:15=================================================
00:01:42: =====Stream Start: 2013-07-15, 00:01:42===============================================
00:01:42: Multithreaded optimizations: On
00:01:42: Base resolution: 1920x1080
00:01:42: Output resolution: 1280x720
00:01:42: ------------------------------------------
00:01:42: Loading up D3D10...
00:01:42: Playback device Default
00:01:42: ------------------------------------------
00:01:42: Using desktop audio input: Speakers (SoundMAX Integrated Digital HD Audio)
00:01:42: ------------------------------------------
00:01:42: Using auxilary audio input: Microphone (SoundMAX Integrated Digital HD Audio)
00:01:42: Using Mic QPC timestamps
00:01:42: ------------------------------------------
00:01:42: Audio Encoding: AAC
00:01:42: bitrate: 128
00:01:42: Using bitmap image
00:01:42: ------------------------------------------
00:01:42: Video Encoding: x264
00:01:42: fps: 60
00:01:42: width: 1280, height: 720
00:01:42: preset: veryfast
00:01:42: CBR: no
00:01:42: CFR: no
00:01:42: max bitrate: 2500
00:01:42: buffer size: 2500
00:01:42: quality: 10
00:01:42: ------------------------------------------
00:01:42: Syncing audio to video time (WARNING: you should not be doing this if you are just having webcam desync, that's a separate issue)
00:01:44: Using RTMP service: Twitch / Justin.tv
00:01:44: Server selection: rtmp://live-ord.justin.tv/app
00:01:45: SO_SNDBUF was at 8192
00:01:45: SO_SNDBUF is now 65536
00:02:52: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
00:02:52: New Scene
00:02:52: ------------------------------------------
00:02:52: device: Logitech HD Pro Webcam C920,
00:02:52: device id \\?\usb#vid_046d&pid_082d&mi_00#6&3361f4d&0&0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
00:02:52: chosen type: I420, usingFourCC: false, res: 1920x1080 - 1920x1080, frameIntervals: 333333-2000000, fourCC: 'I420'
00:02:52: audio device: Disable,
00:02:52: audio device id Disabled,
00:02:52:
00:02:52: Using directshow input
00:02:52: Using Window Capture
00:02:52: Using bitmap image
00:17:16: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
00:17:16: New Scene
00:17:16: Using bitmap image
00:17:16: Using Window Capture
00:17:16: Using bitmap image
00:17:16: Using graphics capture
00:17:16: SharedTexCapture hooked
00:17:20: GraphicsCaptureSource::NewCapture: eliminating old capture
00:17:20: SharedTexCapture hooked
00:40:50: GraphicsCaptureSource::NewCapture: eliminating old capture
00:40:50: SharedTexCapture hooked
00:42:21: GraphicsCaptureSource::NewCapture: eliminating old capture
00:42:21: SharedTexCapture hooked
01:52:00: Total frames rendered: 401436, number of frames that lagged: 9459 (2.36%) (it's okay for some frames to lag)
01:52:00: RTMPPublisher::SocketLoop: Aborting due to bStopping
01:52:00: Number of times waited to send: 0, Waited for a total of 0 bytes
01:52:00: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
01:52:00: =====Stream End: 2013-07-15, 01:52:00=================================================
01:52:15: =====Stream Start: 2013-07-15, 01:52:15===============================================
01:52:15: Multithreaded optimizations: On
01:52:15: Base resolution: 1920x1080
01:52:15: Output resolution: 1280x720
01:52:15: ------------------------------------------
01:52:15: Loading up D3D10...
01:52:15: Playback device Default
01:52:15: ------------------------------------------
01:52:15: Using desktop audio input: Speakers (SoundMAX Integrated Digital HD Audio)
01:52:15: ------------------------------------------
01:52:15: Using auxilary audio input: Microphone (SoundMAX Integrated Digital HD Audio)
01:52:15: Using Mic QPC timestamps
01:52:15: ------------------------------------------
01:52:15: Audio Encoding: AAC
01:52:15: bitrate: 128
01:52:15: Using bitmap image
01:52:15: ------------------------------------------
01:52:15: device: Logitech HD Pro Webcam C920,
01:52:15: device id \\?\usb#vid_046d&pid_082d&mi_00#6&3361f4d&0&0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
01:52:15: chosen type: I420, usingFourCC: false, res: 1920x1080 - 1920x1080, frameIntervals: 333333-2000000, fourCC: 'I420'
01:52:15: audio device: Disable,
01:52:15: audio device id Disabled,
01:52:15:
01:52:15: Using directshow input
01:52:16: Using Window Capture
01:52:16: Using bitmap image
01:52:16: Using graphics capture
01:52:16: ------------------------------------------
01:52:16: Video Encoding: x264
01:52:16: fps: 60
01:52:16: width: 1280, height: 720
01:52:16: preset: veryfast
01:52:16: CBR: yes
01:52:16: CFR: no
01:52:16: max bitrate: 2500
01:52:16: ------------------------------------------
01:52:16: SharedTexCapture hooked
01:52:16: Syncing audio to video time (WARNING: you should not be doing this if you are just having webcam desync, that's a separate issue)
01:52:17: Using RTMP service: Twitch / Justin.tv
01:52:17: Server selection: rtmp://live-ord.justin.tv/app
01:52:18: SO_SNDBUF was at 8192
01:52:18: SO_SNDBUF is now 65536
01:52:23: GraphicsCaptureSource::NewCapture: eliminating old capture
01:52:23: SharedTexCapture hooked
01:53:07: GraphicsCaptureSource::NewCapture: eliminating old capture
01:53:07: SharedTexCapture hooked
03:57:01: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
03:57:01: New Scene
03:57:01: Using Window Capture
03:57:01: Using bitmap image
04:08:16: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
04:08:16: New Scene
04:08:16: Using bitmap image
04:08:16: Using Window Capture
04:08:16: Using bitmap image
04:08:29: Using Window Capture
05:20:41: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
05:20:41: New Scene
05:20:41: Using Window Capture
05:20:41: Using bitmap image
05:20:52: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
05:20:52: New Scene
05:20:52: Using bitmap image
05:22:13: Total frames rendered: 765772, number of frames that lagged: 12363 (1.61%) (it's okay for some frames to lag)
05:22:13: RTMPPublisher::SocketLoop: Aborting due to bStopping
05:22:13: Number of times waited to send: 0, Waited for a total of 0 bytes
05:22:13: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
05:22:13: =====Stream End: 2013-07-15, 05:22:13=================================================
05:22:16: Video Source Plugin instance deleted; removing dynamically loaded localization strings
05:22:16:
05:22:16: Profiler results:
05:22:16:
05:22:16: ==============================================================
05:22:16: frame - [100%] [avg time: 6.235 ms (cpu time: avg 4.376 ms, total 5.14221e+006 ms)] [avg calls per frame: 1] [children: 88.4%] [unaccounted: 11.6%]
05:22:16: | scene->Preprocess - [55.7%] [avg time: 3.47 ms (cpu time: avg 2.08 ms, total 2.44416e+006 ms)] [avg calls per frame: 1]
05:22:16: | video encoding and uploading - [32.7%] [avg time: 2.039 ms (cpu time: avg 1.914 ms, total 2.24939e+006 ms)] [avg calls per frame: 1] [children: 31.6%] [unaccounted: 1.12%]
05:22:16: | | CopyResource - [0.257%] [avg time: 0.016 ms (cpu time: avg 0.015 ms, total 17674.9 ms)] [avg calls per frame: 0]
05:22:16: | | conversion to 4:2:0 - [0.0962%] [avg time: 0.006 ms (cpu time: avg 0.005 ms, total 6458.44 ms)] [avg calls per frame: 0]
05:22:16: | | call to encoder - [30.7%] [avg time: 1.913 ms (cpu time: avg 1.86 ms, total 2.18575e+006 ms)] [avg calls per frame: 0]
05:22:16: | | sending stuff out - [0.545%] [avg time: 0.034 ms (cpu time: avg 0.015 ms, total 18002.5 ms)] [avg calls per frame: 0]
05:22:16: | Convert444Threads - [528%] [avg time: 32.935 ms (cpu time: avg 2.242 ms, total 2.63529e+006 ms)] [avg calls per frame: 1]
05:22:16: ==============================================================
05:22:16: