Question / Help Trying to track low broadcast frame-rate while live

Yargnit · Nov 20, 2013

So after initially starting with OBS, I swapped to X-split because of this issue. However because of some of the recent additions to OBS (specifically Quick-sync support) I'd really like to swap back. Basically when local recording I'm getting a consistent 60FPS on the recording. However when I go to stream live that will drop to the low 20's consistently. (Where if I tell X-split 60FPS it will give me 60) I've got 0 dropped frames the entire time, and my CPU usage when set to Quicksync mode is well under 50% so neither of those are issues.

Here's the relevant section of the log file if anyone can sort whats wrong.

Code:

19:49:08: =====Stream Start: 2013-11-19, 19:49:08===============================================
19:49:08:   Multithreaded optimizations: On
19:49:08:   Base resolution: 1920x1080
19:49:08:   Output resolution: 1920x1080
19:49:08: ------------------------------------------
19:49:08: Loading up D3D10 on NVIDIA GeForce GTX 560 Ti...
19:49:08: ------------------------------------------
19:49:08: Audio Format: 48000hz
19:49:08: Playback device Default
19:49:08: ------------------------------------------
19:49:08: Using desktop audio input: Speakers (Creative SB X-Fi)
19:49:08: Using 5.1 speaker setup
19:49:08: ------------------------------------------
19:49:08: Using auxilary audio input: Microphone (Blue Snowball)
19:49:08: ------------------------------------------
19:49:08: Audio Encoding: MP3
19:49:08:     bitrate: 128
19:49:08: ------------------------------------------
19:49:08:     device: Logitech HD Pro Webcam C920,
19:49:08:     device id \\?\usb#vid_046d&pid_082d&mi_00#8&1ba0f2d&0&0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
19:49:08:     chosen type: I420, usingFourCC: false, res: 320x240 - 320x240, frameIntervals: 333333-2000000
19:49:08:     use buffering: false - 0, fourCC: 'I420'
19:49:08:     audio device: Disable,
19:49:08:     audio device id Disabled,
19:49:08: 
19:49:08: Using directshow input
19:49:08: Using graphics capture
19:49:08: Scene buffering time set to 700
19:49:08: Found QSV hardware support
19:49:08: ------------------------------------------
19:49:08: QSV version 1.4 using MFX_IMPL_HARDWARE_ANY | MFX_IMPL_VIA_D3D9 (actual: MFX_IMPL_HARDWARE3 | MFX_IMPL_VIA_D3D9)
19:49:08: Using 10 bitstreams and 13 frame buffers
19:49:08: ------------------------------------------
19:49:08: Video Encoding: QSV
19:49:08:     fps: 60
19:49:08:     width: 1920, height: 1080
19:49:08:     target-usage: MFX_TARGETUSAGE_BEST_QUALITY
19:49:08:     CBR: yes
19:49:08:     CFR: yes
19:49:08:     max bitrate: 3500
19:49:08: ------------------------------------------
19:49:08: MMDeviceAudioSource: Frequency for device 'Speakers (Creative SB X-Fi)' is 1152000, samples per sec is 48000
19:49:08: MMDeviceAudioSource: Frequency for device 'Microphone (Blue Snowball)' is 176400, samples per sec is 44100
19:49:08: SharedTexCapture hooked
19:49:10: Using RTMP service: Twitch / Justin.tv
19:49:10:   Server selection: rtmp://live.justin.tv/app
19:49:10:   Interface: Realtek PCIe GBE Family Controller (ethernet, 100 mbps)
19:49:10: Completed handshake with rtmp://live.justin.tv/app in 62 ms.
19:49:11: SO_SNDBUF was at 8192
19:49:11: SO_SNDBUF is now 65536
19:53:15: Using Monitor Capture
19:53:22: Using Monitor Capture
19:53:27: Using Monitor Capture
19:53:32: Using Monitor Capture
19:56:17: Using graphics capture
19:56:18: SharedTexCapture hooked
20:02:22: Total frames encoded: 47579, total frames duplicated: 31022 (65.20%)
20:02:22: Total frames rendered: 17679, number of late frames: 12423 (70.27%) (it's okay for some frames to be late)
20:02:23: RTMPPublisher::SocketLoop: Graceful loop exit
20:02:23: Average send payload: 1447 bytes, average send interval: 3 ms
20:02:23: Number of times waited to send: 0, Waited for a total of 0 bytes
20:02:23: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
20:02:23: Number of bytes sent: 359396822
20:02:23: 
20:02:23: Profiler time results:
20:02:23: 
20:02:23: ==============================================================
20:02:23: video thread frame - [100%] [avg time: 42.969 ms] [children: 92.2%] [unaccounted: 7.85%]
20:02:23: | scene->Preprocess - [22.4%] [avg time: 9.626 ms]
20:02:23: | GPU download and conversion - [69.8%] [avg time: 29.971 ms] [children: 1.04%] [unaccounted: 68.7%]
20:02:23: | | flush - [0.447%] [avg time: 0.192 ms]
20:02:23: | | CopyResource - [0.414%] [avg time: 0.178 ms]
20:02:23: | | conversion to 4:2:0 - [0.177%] [avg time: 0.076 ms]
20:02:23: Convert444Threads - [100%] [avg time: 1.26 ms] [children: 98.7%] [unaccounted: 1.27%]
20:02:23: | Convert444toNV12 - [98.7%] [avg time: 1.244 ms]
20:02:23: encoder thread frame - [100%] [avg time: 0.05 ms] [children: 46%] [unaccounted: 54%]
20:02:23: | ProcessEncodedFrame - [40%] [avg time: 0.02 ms]
20:02:23: | QueueEncodeTask - [6%] [avg time: 0.003 ms]
20:02:23: ==============================================================
20:02:23: 
20:02:23: 
20:02:23: Profiler CPU results:
20:02:23: 
20:02:23: ==============================================================
20:02:23: video thread frame - [cpu time: avg 1.316 ms, total 23275.3 ms] [avg calls per frame: 1]
20:02:23: | scene->Preprocess - [cpu time: avg 0.516 ms, total 9126.06 ms] [avg calls per frame: 1]
20:02:23: | GPU download and conversion - [cpu time: avg 0.12 ms, total 2137.22 ms] [avg calls per frame: 1]
20:02:23: | | flush - [cpu time: avg 0.058 ms, total 1029.61 ms] [avg calls per frame: 1]
20:02:23: | | CopyResource - [cpu time: avg 0.022 ms, total 405.603 ms] [avg calls per frame: 1]
20:02:23: | | conversion to 4:2:0 - [cpu time: avg 0.006 ms, total 109.2 ms] [avg calls per frame: 1]
20:02:23: Convert444Threads - [cpu time: avg 1.085 ms, total 38298.2 ms] [avg calls per frame: 2]
20:02:23: | Convert444toNV12 - [cpu time: avg 1.083 ms, total 38220.2 ms] [avg calls per frame: 2]
20:02:23: encoder thread frame - [cpu time: avg 0.002 ms, total 109.2 ms] [avg calls per frame: 1]
20:02:23: | ProcessEncodedFrame - [cpu time: avg 0 ms, total 31.2 ms] [avg calls per frame: 1]
20:02:23: | QueueEncodeTask - [cpu time: avg 0 ms, total 15.6 ms] [avg calls per frame: 1]
20:02:23: ==============================================================
20:02:23: 
20:02:23: =====Stream End: 2013-11-19, 20:02:23=================================================

From that it looks like the 'late frames' may be the issue? But I'm not sure what that means or how to fix it. Once again when doing a local recording only it has no issues. Also the games GPU utilization is pretty low, so not frame-rate limited on the game side. I got this same issue in the past with software encoding also, which was what cause me to switch away from OBS because X-split never got it, and assistance would be greatly appreciated so I can take advantage of Quick-sync & free my CPU from limiting my broadcast resolution to 720p.

Floatingthru · Nov 20, 2013

Can you post your whole log? It would really help quite a bit. Also is there a reason why you are using 4 monitor captures and a game capture at the same time?

Yargnit · Nov 20, 2013

Sure:

Code:

19:33:37: Browser Source plugin strings not found, dynamically loading 22 strings
19:33:37: Video Source Plugin strings not found, dynamically loading 38 strings
19:33:48: Open Broadcaster Software v0.584b - 32bit (´・ω・｀)
19:33:48: -------------------------------
19:33:48: CPU Name: Intel(R) Core(TM) i5-2500K CPU @ 3.30GHz
19:33:48: CPU Speed: 3292MHz
19:33:48: Physical Memory:  4095MB Total, 4095MB Free
19:33:48: stepping id: 7, model 42, family 6, type 0, extmodel 1, extfamily 0, HTT 1, logical cores 4, total cores 4
19:33:48: monitor 1: pos={1920, 0}, size={1920, 1200}
19:33:48: monitor 2: pos={0, 0}, size={1920, 1080}
19:33:48: monitor 3: pos={3840, 0}, size={1920, 1080}
19:33:48: Windows Version: 6.1 Build 7601 S
19:33:48: Aero is Disabled
19:33:48: -------------------------------
19:33:48: OBS Modules:
19:33:48: Base Address     Module
19:33:48: 010A0000         OBS.exe
19:33:48: 6B240000         OBSApi.dll
19:33:48: 71540000         BrowserSourcePluginWrapper.dll
19:33:48: 59B80000         DShowPlugin.dll
19:33:48: 6B140000         GraphicsCapture.dll
19:33:48: 58C90000         NoiseGate.dll
19:33:48: 58C70000         PSVPlugin.dll
19:33:48: 599C0000         VideoSourcePluginWrapper.dll
19:33:48: ------------------------------------------
19:33:48: Adapter 1
19:33:48:   Video Adapter: NVIDIA GeForce GTX 560 Ti  
19:33:48:   Video Adapter Dedicated Video Memory: 1025966080
19:33:48:   Video Adapter Shared System Memory: 3221106688
19:33:48:   Video Adapter Output 1: pos={0, 0}, size={1920, 1080}, attached=true
19:33:48:   Video Adapter Output 2: pos={1920, 0}, size={1920, 1200}, attached=true
19:33:48: ------------------------------------------
19:33:48: Adapter 2
19:33:48:   Video Adapter: Intel(R) HD Graphics 3000
19:33:48:   Video Adapter Dedicated Video Memory: 67108864
19:33:48:   Video Adapter Shared System Memory: 1711276032
19:33:48:   Video Adapter Output 1: pos={3840, 0}, size={1920, 1080}, attached=true
19:33:48: =====Stream Start: 2013-11-19, 19:33:48===============================================
19:33:48:   Multithreaded optimizations: On
19:33:48:   Base resolution: 1920x1080
19:33:48:   Output resolution: 1920x1080
19:33:48: ------------------------------------------
19:33:48: Loading up D3D10 on NVIDIA GeForce GTX 560 Ti...
19:33:48: ------------------------------------------
19:33:48: Audio Format: 48000hz
19:33:48: Playback device Default
19:33:48: ------------------------------------------
19:33:48: Using desktop audio input: Speakers (Creative SB X-Fi)
19:33:48: Using 5.1 speaker setup
19:33:48: ------------------------------------------
19:33:48: Audio Encoding: MP3
19:33:48:     bitrate: 128
19:33:48: Using graphics capture
19:33:48: Scene buffering time set to 700
19:33:48: Found QSV hardware support
19:33:48: ------------------------------------------
19:33:48: QSV version 1.4 using MFX_IMPL_HARDWARE_ANY | MFX_IMPL_VIA_D3D9 (actual: MFX_IMPL_HARDWARE3 | MFX_IMPL_VIA_D3D9)
19:33:48: Using 10 bitstreams and 13 frame buffers
19:33:48: ------------------------------------------
19:33:48: Video Encoding: QSV
19:33:48:     fps: 60
19:33:48:     width: 1920, height: 1080
19:33:48:     target-usage: MFX_TARGETUSAGE_BEST_QUALITY
19:33:48:     CBR: yes
19:33:48:     CFR: yes
19:33:48:     max bitrate: 4000
19:33:48: ------------------------------------------
19:33:48: MMDeviceAudioSource: Frequency for device 'Speakers (Creative SB X-Fi)' is 1152000, samples per sec is 48000
19:33:48: SharedTexCapture hooked
19:33:49: Total frames encoded: 0, total frames duplicated: 0 (-1.#J%)
19:33:49: Total frames rendered: 40, number of late frames: 0 (0.00%) (it's okay for some frames to be late)
19:33:49: 
19:33:49: Profiler time results:
19:33:49: 
19:33:49: ==============================================================
19:33:49: video thread frame - [100%] [avg time: 1.476 ms] [children: 3.05%] [unaccounted: 97%]
19:33:49: | scene->Preprocess - [0.0678%] [avg time: 0.001 ms]
19:33:49: | GPU download and conversion - [2.98%] [avg time: 0.044 ms] [children: 2.85%] [unaccounted: 0.136%]
19:33:49: | | flush - [2.85%] [avg time: 0.042 ms]
19:33:49: ==============================================================
19:33:49: 
19:33:49: 
19:33:49: Profiler CPU results:
19:33:49: 
19:33:49: ==============================================================
19:33:49: video thread frame - [cpu time: avg 1.17 ms, total 46.8 ms] [avg calls per frame: 1]
19:33:49: | scene->Preprocess - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:33:49: | GPU download and conversion - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:33:49: | | flush - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:33:49: ==============================================================
19:33:49: 
19:33:49: =====Stream End: 2013-11-19, 19:33:49=================================================
19:34:02: =====Stream Start: 2013-11-19, 19:34:02===============================================
19:34:02:   Multithreaded optimizations: On
19:34:02:   Base resolution: 1920x1080
19:34:02:   Output resolution: 1920x1080
19:34:02: ------------------------------------------
19:34:02: Loading up D3D10 on NVIDIA GeForce GTX 560 Ti...
19:34:02: ------------------------------------------
19:34:02: Audio Format: 48000hz
19:34:02: Playback device Default
19:34:02: ------------------------------------------
19:34:02: Using desktop audio input: Speakers (Creative SB X-Fi)
19:34:02: Using 5.1 speaker setup
19:34:02: ------------------------------------------
19:34:02: Audio Encoding: MP3
19:34:02:     bitrate: 128
19:34:02: Using graphics capture
19:34:02: Scene buffering time set to 700
19:34:02: Failed to initialize QSV hardware session
19:34:02: ------------------------------------------
19:34:02: Video Encoding: x264
19:34:02:     fps: 60
19:34:02:     width: 1920, height: 1080
19:34:02:     preset: slower
19:34:02:     profile: high
19:34:02:     keyint: 120
19:34:02:     CBR: yes
19:34:02:     CFR: yes
19:34:02:     max bitrate: 4000
19:34:02:     buffer size: 4000
19:34:02: ------------------------------------------
19:34:02: MMDeviceAudioSource: Frequency for device 'Speakers (Creative SB X-Fi)' is 1152000, samples per sec is 48000
19:34:02: SharedTexCapture hooked
19:34:04: Using Monitor Capture
19:34:05: Using bitmap image
19:34:06: Using Monitor Capture
19:34:08: Using Video Source
19:34:13: Using graphics capture
19:34:19: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:34:19:   New Scene
19:34:19: Using bitmap image
19:34:20: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:34:20:   New Scene
19:34:20: Using graphics capture
19:34:39: Total frames encoded: 823, total frames duplicated: 702 (85.30%)
19:34:39: Number of frames skipped due to encoder lag: 701 (85.18%)
19:34:39: Total frames rendered: 125, number of late frames: 1 (0.80%) (it's okay for some frames to be late)
19:34:39: 
19:34:39: Profiler time results:
19:34:39: 
19:34:39: ==============================================================
19:34:39: video thread frame - [100%] [avg time: 0.983 ms] [children: 23.9%] [unaccounted: 76.1%]
19:34:39: | scene->Preprocess - [4.78%] [avg time: 0.047 ms]
19:34:39: | GPU download and conversion - [19.1%] [avg time: 0.188 ms] [children: 9.77%] [unaccounted: 9.36%]
19:34:39: | | flush - [6.21%] [avg time: 0.061 ms]
19:34:39: | | CopyResource - [3.26%] [avg time: 0.032 ms]
19:34:39: | | conversion to 4:2:0 - [0.305%] [avg time: 0.003 ms]
19:34:39: Convert444Threads - [100%] [avg time: 0.883 ms] [children: 99.1%] [unaccounted: 0.906%]
19:34:39: | Convert444toNV12 - [99.1%] [avg time: 0.875 ms]
19:34:39: encoder thread frame - [100%] [avg time: 43.817 ms]
19:34:39: ==============================================================
19:34:39: 
19:34:39: 
19:34:39: Profiler CPU results:
19:34:39: 
19:34:39: ==============================================================
19:34:39: video thread frame - [cpu time: avg 0.499 ms, total 62.4 ms] [avg calls per frame: 1]
19:34:39: | scene->Preprocess - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:34:39: | GPU download and conversion - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:34:39: | | flush - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:34:39: | | CopyResource - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:34:39: | | conversion to 4:2:0 - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:34:39: Convert444Threads - [cpu time: avg 0.481 ms, total 78 ms] [avg calls per frame: 2]
19:34:39: | Convert444toNV12 - [cpu time: avg 0.385 ms, total 62.4 ms] [avg calls per frame: 2]
19:34:39: encoder thread frame - [cpu time: avg 1.797 ms, total 1404.01 ms] [avg calls per frame: 1]
19:34:39: ==============================================================
19:34:39: 
19:34:39: =====Stream End: 2013-11-19, 19:34:39=================================================
19:35:08: =====Stream Start: 2013-11-19, 19:35:08===============================================
19:35:08:   Multithreaded optimizations: On
19:35:08:   Base resolution: 1920x1080
19:35:08:   Output resolution: 1920x1080
19:35:08: ------------------------------------------
19:35:08: Loading up D3D10 on NVIDIA GeForce GTX 560 Ti...
19:35:08: ------------------------------------------
19:35:08: Audio Format: 48000hz
19:35:08: Playback device Default
19:35:08: ------------------------------------------
19:35:08: Using desktop audio input: Speakers (Creative SB X-Fi)
19:35:08: Using 5.1 speaker setup
19:35:08: ------------------------------------------
19:35:08: Audio Encoding: MP3
19:35:08:     bitrate: 128
19:35:08: Using graphics capture
19:35:08: Scene buffering time set to 700
19:35:09: Found QSV hardware support
19:35:09: ------------------------------------------
19:35:09: QSV version 1.4 using MFX_IMPL_HARDWARE_ANY | MFX_IMPL_VIA_D3D9 (actual: MFX_IMPL_HARDWARE3 | MFX_IMPL_VIA_D3D9)
19:35:09: Using 10 bitstreams and 13 frame buffers
19:35:09: ------------------------------------------
19:35:09: Video Encoding: QSV
19:35:09:     fps: 60
19:35:09:     width: 1920, height: 1080
19:35:09:     target-usage: MFX_TARGETUSAGE_BEST_QUALITY
19:35:09:     CBR: yes
19:35:09:     CFR: yes
19:35:09:     max bitrate: 4000
19:35:09: ------------------------------------------
19:35:09: MMDeviceAudioSource: Frequency for device 'Speakers (Creative SB X-Fi)' is 1152000, samples per sec is 48000
19:35:09: SharedTexCapture hooked
19:35:16: Using bitmap image
19:35:17: Using Video Source
19:35:23: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:35:23:   New Scene
19:35:23: Using bitmap image
19:35:24: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:35:24:   New Scene
19:35:24: Using graphics capture
19:35:24: SharedTexCapture hooked
19:36:12: Using Video Source
19:36:39: ------------------------------------------
19:36:39:     device: Logitech HD Pro Webcam C920,
19:36:39:     device id \\?\usb#vid_046d&pid_082d&mi_00#8&1ba0f2d&0&0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
19:36:39:     chosen type: I420, usingFourCC: false, res: 320x240 - 320x240, frameIntervals: 333333-2000000
19:36:39:     use buffering: false - 0, fourCC: 'I420'
19:36:39:     audio device: (null),
19:36:39:     audio device id (null),
19:36:39: 
19:36:39: Using directshow input
19:44:10: ------------------------------------------
19:44:10:     device: Logitech HD Pro Webcam C920,
19:44:10:     device id \\?\usb#vid_046d&pid_082d&mi_00#8&1ba0f2d&0&0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
19:44:10:     chosen type: I420, usingFourCC: false, res: 320x240 - 320x240, frameIntervals: 333333-2000000
19:44:10:     use buffering: false - 0, fourCC: 'I420'
19:44:10:     audio device: Disable,
19:44:10:     audio device id Disabled,
19:44:10: 
19:44:30: Total frames encoded: 33645, total frames duplicated: 143 (0.43%)
19:44:30: Total frames rendered: 33584, number of late frames: 7 (0.02%) (it's okay for some frames to be late)
19:44:31: 
19:44:31: Profiler time results:
19:44:31: 
19:44:31: ==============================================================
19:44:31: video thread frame - [100%] [avg time: 0.611 ms] [children: 23.2%] [unaccounted: 76.8%]
19:44:31: | scene->Preprocess - [6.87%] [avg time: 0.042 ms]
19:44:31: | GPU download and conversion - [16.4%] [avg time: 0.1 ms] [children: 11.8%] [unaccounted: 4.58%]
19:44:31: | | flush - [9%] [avg time: 0.055 ms]
19:44:31: | | CopyResource - [2.13%] [avg time: 0.013 ms]
19:44:31: | | conversion to 4:2:0 - [0.655%] [avg time: 0.004 ms]
19:44:31: Convert444Threads - [100%] [avg time: 1.194 ms] [children: 99.2%] [unaccounted: 0.754%]
19:44:31: | Convert444toNV12 - [99.2%] [avg time: 1.185 ms]
19:44:31: encoder thread frame - [100%] [avg time: 0.041 ms] [children: 43.9%] [unaccounted: 56.1%]
19:44:31: | ProcessEncodedFrame - [39%] [avg time: 0.016 ms]
19:44:31: | QueueEncodeTask - [4.88%] [avg time: 0.002 ms]
19:44:31: ==============================================================
19:44:31: 
19:44:31: 
19:44:31: Profiler CPU results:
19:44:31: 
19:44:31: ==============================================================
19:44:31: video thread frame - [cpu time: avg 0.026 ms, total 904.806 ms] [avg calls per frame: 1]
19:44:31: | scene->Preprocess - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:44:31: | GPU download and conversion - [cpu time: avg 0.006 ms, total 234.002 ms] [avg calls per frame: 1]
19:44:31: | | flush - [cpu time: avg 0.002 ms, total 78.001 ms] [avg calls per frame: 1]
19:44:31: | | CopyResource - [cpu time: avg 0.001 ms, total 62.401 ms] [avg calls per frame: 1]
19:44:31: | | conversion to 4:2:0 - [cpu time: avg 0 ms, total 15.6 ms] [avg calls per frame: 1]
19:44:31: Convert444Threads - [cpu time: avg 1.11 ms, total 74474.9 ms] [avg calls per frame: 2]
19:44:31: | Convert444toNV12 - [cpu time: avg 1.107 ms, total 74303.3 ms] [avg calls per frame: 2]
19:44:31: encoder thread frame - [cpu time: avg 0 ms, total 31.2 ms] [avg calls per frame: 1]
19:44:31: | ProcessEncodedFrame - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:44:31: | QueueEncodeTask - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:44:31: ==============================================================
19:44:31: 
19:44:31: =====Stream End: 2013-11-19, 19:44:31=================================================
19:48:15: =====Stream Start: 2013-11-19, 19:48:15===============================================
19:48:15:   Multithreaded optimizations: On
19:48:15:   Base resolution: 1920x1080
19:48:15:   Output resolution: 1920x1080
19:48:15: ------------------------------------------
19:48:15: Loading up D3D10 on NVIDIA GeForce GTX 560 Ti...
19:48:15: ------------------------------------------
19:48:15: Audio Format: 48000hz
19:48:15: Playback device Default
19:48:15: ------------------------------------------
19:48:15: Using desktop audio input: Speakers (Creative SB X-Fi)
19:48:15: Using 5.1 speaker setup
19:48:15: ------------------------------------------
19:48:15: Audio Encoding: MP3
19:48:15:     bitrate: 128
19:48:15: ------------------------------------------
19:48:15:     device: Logitech HD Pro Webcam C920,
19:48:15:     device id \\?\usb#vid_046d&pid_082d&mi_00#8&1ba0f2d&0&0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
19:48:15:     chosen type: I420, usingFourCC: false, res: 320x240 - 320x240, frameIntervals: 333333-2000000
19:48:15:     use buffering: false - 0, fourCC: 'I420'
19:48:15:     audio device: Disable,
19:48:15:     audio device id Disabled,
19:48:15: 
19:48:15: Using directshow input
19:48:15: Using graphics capture
19:48:15: Scene buffering time set to 700
19:48:15: Found QSV hardware support
19:48:16: ------------------------------------------
19:48:16: QSV version 1.4 using MFX_IMPL_HARDWARE_ANY | MFX_IMPL_VIA_D3D9 (actual: MFX_IMPL_HARDWARE3 | MFX_IMPL_VIA_D3D9)
19:48:16: Using 10 bitstreams and 13 frame buffers
19:48:16: ------------------------------------------
19:48:16: Video Encoding: QSV
19:48:16:     fps: 60
19:48:16:     width: 1920, height: 1080
19:48:16:     target-usage: MFX_TARGETUSAGE_BEST_QUALITY
19:48:16:     CBR: yes
19:48:16:     CFR: yes
19:48:16:     max bitrate: 3500
19:48:16: ------------------------------------------
19:48:16: MMDeviceAudioSource: Frequency for device 'Speakers (Creative SB X-Fi)' is 1152000, samples per sec is 48000
19:48:16: SharedTexCapture hooked
19:48:25: Total frames encoded: 489, total frames duplicated: 8 (1.64%)
19:48:25: Total frames rendered: 492, number of late frames: 1 (0.20%) (it's okay for some frames to be late)
19:48:26: 
19:48:26: Profiler time results:
19:48:26: 
19:48:26: ==============================================================
19:48:26: video thread frame - [100%] [avg time: 0.939 ms] [children: 28.1%] [unaccounted: 71.9%]
19:48:26: | scene->Preprocess - [5.86%] [avg time: 0.055 ms]
19:48:26: | GPU download and conversion - [22.3%] [avg time: 0.209 ms] [children: 7.14%] [unaccounted: 15.1%]
19:48:26: | | flush - [5.54%] [avg time: 0.052 ms]
19:48:26: | | CopyResource - [1.17%] [avg time: 0.011 ms]
19:48:26: | | conversion to 4:2:0 - [0.426%] [avg time: 0.004 ms]
19:48:26: Convert444Threads - [100%] [avg time: 1.393 ms] [children: 99.3%] [unaccounted: 0.718%]
19:48:26: | Convert444toNV12 - [99.3%] [avg time: 1.383 ms]
19:48:26: encoder thread frame - [100%] [avg time: 0.037 ms] [children: 54.1%] [unaccounted: 45.9%]
19:48:26: | ProcessEncodedFrame - [40.5%] [avg time: 0.015 ms]
19:48:26: | QueueEncodeTask - [13.5%] [avg time: 0.005 ms]
19:48:26: ==============================================================
19:48:26: 
19:48:26: 
19:48:26: Profiler CPU results:
19:48:26: 
19:48:26: ==============================================================
19:48:26: video thread frame - [cpu time: avg 0.095 ms, total 46.8 ms] [avg calls per frame: 1]
19:48:26: | scene->Preprocess - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:48:26: | GPU download and conversion - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:48:26: | | flush - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:48:26: | | CopyResource - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:48:26: | | conversion to 4:2:0 - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:48:26: Convert444Threads - [cpu time: avg 1.462 ms, total 1310.41 ms] [avg calls per frame: 2]
19:48:26: | Convert444toNV12 - [cpu time: avg 1.445 ms, total 1294.81 ms] [avg calls per frame: 2]
19:48:26: encoder thread frame - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:48:26: | ProcessEncodedFrame - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:48:26: | QueueEncodeTask - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:48:26: ==============================================================
19:48:26: 
19:48:26: =====Stream End: 2013-11-19, 19:48:26=================================================
19:48:58: =====Stream Start: 2013-11-19, 19:48:58===============================================
19:48:58:   Multithreaded optimizations: On
19:48:58:   Base resolution: 1920x1080
19:48:58:   Output resolution: 1920x1080
19:48:58: ------------------------------------------
19:48:58: Loading up D3D10 on NVIDIA GeForce GTX 560 Ti...
19:48:58: ------------------------------------------
19:48:58: Audio Format: 48000hz
19:48:58: Playback device Default
19:48:58: ------------------------------------------
19:48:58: Using desktop audio input: Speakers (Creative SB X-Fi)
19:48:58: Using 5.1 speaker setup
19:48:58: ------------------------------------------
19:48:58: Using auxilary audio input: Microphone (Blue Snowball)
19:48:58: ------------------------------------------
19:48:58: Audio Encoding: MP3
19:48:58:     bitrate: 128
19:48:58: ------------------------------------------
19:48:58:     device: Logitech HD Pro Webcam C920,
19:48:58:     device id \\?\usb#vid_046d&pid_082d&mi_00#8&1ba0f2d&0&0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
19:48:58:     chosen type: I420, usingFourCC: false, res: 320x240 - 320x240, frameIntervals: 333333-2000000
19:48:58:     use buffering: false - 0, fourCC: 'I420'
19:48:58:     audio device: Disable,
19:48:58:     audio device id Disabled,
19:48:58: 
19:48:58: Using directshow input
19:48:58: Using graphics capture
19:48:58: Scene buffering time set to 700
19:48:58: Found QSV hardware support
19:48:59: ------------------------------------------
19:48:59: QSV version 1.4 using MFX_IMPL_HARDWARE_ANY | MFX_IMPL_VIA_D3D9 (actual: MFX_IMPL_HARDWARE3 | MFX_IMPL_VIA_D3D9)
19:48:59: Using 10 bitstreams and 13 frame buffers
19:48:59: ------------------------------------------
19:48:59: Video Encoding: QSV
19:48:59:     fps: 60
19:48:59:     width: 1920, height: 1080
19:48:59:     target-usage: MFX_TARGETUSAGE_BEST_QUALITY
19:48:59:     CBR: yes
19:48:59:     CFR: yes
19:48:59:     max bitrate: 3500
19:48:59: ------------------------------------------
19:48:59: MMDeviceAudioSource: Frequency for device 'Speakers (Creative SB X-Fi)' is 1152000, samples per sec is 48000
19:48:59: MMDeviceAudioSource: Frequency for device 'Microphone (Blue Snowball)' is 176400, samples per sec is 44100
19:48:59: SharedTexCapture hooked
19:49:02: Total frames encoded: 154, total frames duplicated: 1 (0.65%)
19:49:02: Total frames rendered: 157, number of late frames: 2 (1.27%) (it's okay for some frames to be late)
19:49:02: 
19:49:02: Profiler time results:
19:49:02: 
19:49:02: ==============================================================
19:49:02: video thread frame - [100%] [avg time: 1.131 ms] [children: 32.4%] [unaccounted: 67.6%]
19:49:02: | scene->Preprocess - [3.98%] [avg time: 0.045 ms]
19:49:02: | GPU download and conversion - [28.5%] [avg time: 0.322 ms] [children: 19.5%] [unaccounted: 9.02%]
19:49:02: | | flush - [16.8%] [avg time: 0.19 ms]
19:49:02: | | CopyResource - [1.68%] [avg time: 0.019 ms]
19:49:02: | | conversion to 4:2:0 - [0.973%] [avg time: 0.011 ms]
19:49:02: Convert444Threads - [100%] [avg time: 1.099 ms] [children: 99.4%] [unaccounted: 0.637%]
19:49:02: | Convert444toNV12 - [99.4%] [avg time: 1.092 ms]
19:49:02: encoder thread frame - [100%] [avg time: 0.03 ms] [children: 56.7%] [unaccounted: 43.3%]
19:49:02: | ProcessEncodedFrame - [50%] [avg time: 0.015 ms]
19:49:02: | QueueEncodeTask - [6.67%] [avg time: 0.002 ms]
19:49:02: ==============================================================
19:49:02: 
19:49:02: 
19:49:02: Profiler CPU results:
19:49:02: 
19:49:02: ==============================================================
19:49:02: video thread frame - [cpu time: avg 0.198 ms, total 31.2 ms] [avg calls per frame: 1]
19:49:02: | scene->Preprocess - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:49:02: | GPU download and conversion - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:49:02: | | flush - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:49:02: | | CopyResource - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:49:02: | | conversion to 4:2:0 - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:49:02: Convert444Threads - [cpu time: avg 0.69 ms, total 156 ms] [avg calls per frame: 2]
19:49:02: | Convert444toNV12 - [cpu time: avg 0.69 ms, total 156 ms] [avg calls per frame: 2]
19:49:02: encoder thread frame - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:49:02: | ProcessEncodedFrame - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:49:02: | QueueEncodeTask - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
19:49:02: ==============================================================
19:49:02: 
19:49:02: =====Stream End: 2013-11-19, 19:49:02=================================================
19:49:08: Using automatic low latency mode
19:49:08: =====Stream Start: 2013-11-19, 19:49:08===============================================
19:49:08:   Multithreaded optimizations: On
19:49:08:   Base resolution: 1920x1080
19:49:08:   Output resolution: 1920x1080
19:49:08: ------------------------------------------
19:49:08: Loading up D3D10 on NVIDIA GeForce GTX 560 Ti...
19:49:08: ------------------------------------------
19:49:08: Audio Format: 48000hz
19:49:08: Playback device Default
19:49:08: ------------------------------------------
19:49:08: Using desktop audio input: Speakers (Creative SB X-Fi)
19:49:08: Using 5.1 speaker setup
19:49:08: ------------------------------------------
19:49:08: Using auxilary audio input: Microphone (Blue Snowball)
19:49:08: ------------------------------------------
19:49:08: Audio Encoding: MP3
19:49:08:     bitrate: 128
19:49:08: ------------------------------------------
19:49:08:     device: Logitech HD Pro Webcam C920,
19:49:08:     device id \\?\usb#vid_046d&pid_082d&mi_00#8&1ba0f2d&0&0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
19:49:08:     chosen type: I420, usingFourCC: false, res: 320x240 - 320x240, frameIntervals: 333333-2000000
19:49:08:     use buffering: false - 0, fourCC: 'I420'
19:49:08:     audio device: Disable,
19:49:08:     audio device id Disabled,
19:49:08: 
19:49:08: Using directshow input
19:49:08: Using graphics capture
19:49:08: Scene buffering time set to 700
19:49:08: Found QSV hardware support
19:49:08: ------------------------------------------
19:49:08: QSV version 1.4 using MFX_IMPL_HARDWARE_ANY | MFX_IMPL_VIA_D3D9 (actual: MFX_IMPL_HARDWARE3 | MFX_IMPL_VIA_D3D9)
19:49:08: Using 10 bitstreams and 13 frame buffers
19:49:08: ------------------------------------------
19:49:08: Video Encoding: QSV
19:49:08:     fps: 60
19:49:08:     width: 1920, height: 1080
19:49:08:     target-usage: MFX_TARGETUSAGE_BEST_QUALITY
19:49:08:     CBR: yes
19:49:08:     CFR: yes
19:49:08:     max bitrate: 3500
19:49:08: ------------------------------------------
19:49:08: MMDeviceAudioSource: Frequency for device 'Speakers (Creative SB X-Fi)' is 1152000, samples per sec is 48000
19:49:08: MMDeviceAudioSource: Frequency for device 'Microphone (Blue Snowball)' is 176400, samples per sec is 44100
19:49:08: SharedTexCapture hooked
19:49:10: Using RTMP service: Twitch / Justin.tv
19:49:10:   Server selection: rtmp://live.justin.tv/app
19:49:10:   Interface: Realtek PCIe GBE Family Controller (ethernet, 100 mbps)
19:49:10: Completed handshake with rtmp://live.justin.tv/app in 62 ms.
19:49:11: SO_SNDBUF was at 8192
19:49:11: SO_SNDBUF is now 65536
19:53:15: Using Monitor Capture
19:53:22: Using Monitor Capture
19:53:27: Using Monitor Capture
19:53:32: Using Monitor Capture
19:56:17: Using graphics capture
19:56:18: SharedTexCapture hooked
20:02:22: Total frames encoded: 47579, total frames duplicated: 31022 (65.20%)
20:02:22: Total frames rendered: 17679, number of late frames: 12423 (70.27%) (it's okay for some frames to be late)
20:02:23: RTMPPublisher::SocketLoop: Graceful loop exit
20:02:23: Average send payload: 1447 bytes, average send interval: 3 ms
20:02:23: Number of times waited to send: 0, Waited for a total of 0 bytes
20:02:23: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
20:02:23: Number of bytes sent: 359396822
20:02:23: 
20:02:23: Profiler time results:
20:02:23: 
20:02:23: ==============================================================
20:02:23: video thread frame - [100%] [avg time: 42.969 ms] [children: 92.2%] [unaccounted: 7.85%]
20:02:23: | scene->Preprocess - [22.4%] [avg time: 9.626 ms]
20:02:23: | GPU download and conversion - [69.8%] [avg time: 29.971 ms] [children: 1.04%] [unaccounted: 68.7%]
20:02:23: | | flush - [0.447%] [avg time: 0.192 ms]
20:02:23: | | CopyResource - [0.414%] [avg time: 0.178 ms]
20:02:23: | | conversion to 4:2:0 - [0.177%] [avg time: 0.076 ms]
20:02:23: Convert444Threads - [100%] [avg time: 1.26 ms] [children: 98.7%] [unaccounted: 1.27%]
20:02:23: | Convert444toNV12 - [98.7%] [avg time: 1.244 ms]
20:02:23: encoder thread frame - [100%] [avg time: 0.05 ms] [children: 46%] [unaccounted: 54%]
20:02:23: | ProcessEncodedFrame - [40%] [avg time: 0.02 ms]
20:02:23: | QueueEncodeTask - [6%] [avg time: 0.003 ms]
20:02:23: ==============================================================
20:02:23: 
20:02:23: 
20:02:23: Profiler CPU results:
20:02:23: 
20:02:23: ==============================================================
20:02:23: video thread frame - [cpu time: avg 1.316 ms, total 23275.3 ms] [avg calls per frame: 1]
20:02:23: | scene->Preprocess - [cpu time: avg 0.516 ms, total 9126.06 ms] [avg calls per frame: 1]
20:02:23: | GPU download and conversion - [cpu time: avg 0.12 ms, total 2137.22 ms] [avg calls per frame: 1]
20:02:23: | | flush - [cpu time: avg 0.058 ms, total 1029.61 ms] [avg calls per frame: 1]
20:02:23: | | CopyResource - [cpu time: avg 0.022 ms, total 405.603 ms] [avg calls per frame: 1]
20:02:23: | | conversion to 4:2:0 - [cpu time: avg 0.006 ms, total 109.2 ms] [avg calls per frame: 1]
20:02:23: Convert444Threads - [cpu time: avg 1.085 ms, total 38298.2 ms] [avg calls per frame: 2]
20:02:23: | Convert444toNV12 - [cpu time: avg 1.083 ms, total 38220.2 ms] [avg calls per frame: 2]
20:02:23: encoder thread frame - [cpu time: avg 0.002 ms, total 109.2 ms] [avg calls per frame: 1]
20:02:23: | ProcessEncodedFrame - [cpu time: avg 0 ms, total 31.2 ms] [avg calls per frame: 1]
20:02:23: | QueueEncodeTask - [cpu time: avg 0 ms, total 15.6 ms] [avg calls per frame: 1]
20:02:23: ==============================================================
20:02:23: 
20:02:23: =====Stream End: 2013-11-19, 20:02:23=================================================
20:11:35: Browser Source plugin instance deleted; removing dynamically loaded localization strings
20:11:36: Video Source Plugin instance deleted; removing dynamically loaded localization strings

They aren't all actually active. The reason you see that is because I was using the game capture most of the time. Then I swapped to monitor capture (which I have 1 set on each monitor) to show that I wasn't dropping frames or maxing my CPU or anything to stream when I was asking if anyone had any ideas. I didn't know which what monitor so I had to try both :P Then it took me a minute to remember to disable game capture. No matter which I had selected it didn't appear to make a difference in the frame-rate I was seeing from OBS though. (Note - having the OBS window in the screen being captured did lower it by a few frames consistently)

Floatingthru · Nov 20, 2013

Monitor capture in windows 7 sucks balls which is why the frames are duplicating like that, and please don't try to stream 1080p@60fps. Flash has a lot of trouble trying to decode it. Try downscaling to 720p for better performance across the board.

Yargnit · Nov 20, 2013

The monitor capture wasn't being used at the time I was seeing it. I only turned it on to show the channel what I was seeing. I use game capture during normal sessions. As for 720/1080. The channel I broadcast to has transcoding, so people who don't want to watch the full 1080 broadcast can lower their settings, but 1080 @ 60fps broadcasts are just beautiful to watch so I'd really like to get it working.

Floatingthru · Nov 20, 2013

Well you only had problems when monitor capture was being used, so the other streams should have been okay. Could you post a vod so I can see this for myself? Keep in mind the vod player will only display 40fps at Twitch, because they don't wanna fix it.

Yargnit · Nov 20, 2013

http://www.twitch.tv/yargnit/b/481369423

& I had it when the monitor capture was disabled as well as when it was enabled during the live broadcast. The recordings have always stuck right at the set 60fps regardless

At about 4 minutes i swap to screen capture from game capture.

And at just after 7 minutes I go back to game capture and disable monitor capture.

Floatingthru · Nov 20, 2013

Yeah it is a bit hard to tell if its a full 60fps when I downloaded the vod. If OBS wasn't showing 60fps without using monitor capture then the igpu on the 2500k can't really handle doing the encode at such resolution/fps. Ivy Bridge or higher is recommended since the igpu has been upgraded each generation with better qsv support. 1080p@30fps or 720p@60fps should end up being much more smoother. Of course if local recordings are fine as you say they are, then I will repeat again that flash really has a lot of trouble trying to decode 1080p@60fps no matter what software/hardware you are using. It's going to stutter no matter what.

Yargnit · Nov 20, 2013

http://www.youtube.com/watch?v=WvDv2I16L0c&

Here's a YouTube upload of a video I captured with the exact same setting except using local recording instead of live broadcast.

If the 1080p isn't done transcoding yet, just hang for a second & YouTube will evnetually stop being slow.

Floatingthru · Nov 20, 2013

Youtube only does 30fps so its gonna look smooth. 1080p@60fps is where things start to look like garbage. We have tested this time and time again. Which is why if you want a 60fps livestream we will always recommend using 720p.

Yargnit · Nov 20, 2013

The point isn't the YouTube video itself, the point it the OBS frame-rate counter in the bottom corner. You can clearly see it's recording at a smooth 60fps the entire time, vs the VoD i linked you where it was below 20, with the only difference in setting being local record vs live-stream. I just uploaded the local recording to YouTube so you could see the counter.

Floatingthru · Nov 20, 2013

Oh strange so even with monitor capture its still 60fps ok sorry. That is very weird then that changing it from local vs live stream is doing that. It really should not be the case. I have no idea what is going on then. Using the same settings theoretically should have identical performance regardless of it being in local vs live stream mode. Maybe someone else can shed some light on this.

Yargnit · Nov 20, 2013

Yes, this is why I am so confused. I'm not sure how to fix it.

dodgepong · Nov 21, 2013

Well, as floatingthru said, streaming at 1080p60 is a really bad idea anyway.

But do you have the same problem when you use x264 instead of quicksync?

Boildown · Nov 21, 2013

Maybe try compromising and use a 1.25 downscale and see if the problem goes away or is reduced. That would be 864p.

Yargnit · Nov 22, 2013

I had the same problem in the past using standard x264 as well yes. I just don't understand why it gets so many late frames when broadcasting, but they aren't a problem at all while local recording. I'm set to the same encoding settings either way

Question / Help Trying to track low broadcast frame-rate while live

Yargnit

New Member

Floatingthru

Yargnit

New Member

Floatingthru

Yargnit

New Member

Floatingthru

Yargnit

New Member

Floatingthru

Yargnit

New Member

Floatingthru

Yargnit

New Member

Floatingthru

Yargnit

New Member

dodgepong

Administrator

Boildown

Active Member

Yargnit

New Member