Question / Help Duplicate Frames

theinzane · Sep 25, 2013

Hey guys, just curious as to what would cause duplicate frames. I am using a dual PC setup with an 8350fx in the streaming PC. I've tried various presets veryfast to medium and even on medium I dont exceed 70% usage but no matter which setting I use even if its veryfast I seem to have some frames being duplicated. I try to run a 60fps stream but when 2-10% of the frames are being duplicated it loses some of its fluidity.

Here is a log incase you are curious:

Code:

18:46:11: Open Broadcaster Software v0.56.04 madness - 64bit (　^ω^)
18:46:11: -------------------------------
18:46:11: CPU Name: AMD FX(tm)-8350 Eight-Core Processor
18:46:11: CPU Speed: 4400MHz
18:46:11: Physical Memory:  4044MB Total, 2315MB Free
18:46:11: stepping id: 0, model 2, family 15, type 0, extmodel 0, extfamily 3, HTT 1, logical cores 8, total cores 4
18:46:11: monitor 1: pos={0, 0}, size={1920, 1080}
18:46:11: monitor 2: pos={1920, 0}, size={1680, 1050}
18:46:11: Windows Version: 6.1 Build 7601 S
18:46:11: Aero is Enabled
18:46:11: -------------------------------
18:46:11: OBS Modules:
18:46:11: Base Address     Module
18:46:11: 000000003F710000 OBS.exe
18:46:11: 00000000F0A00000 OBSApi.dll
18:46:11: 00000000FAC10000 DShowPlugin.dll
18:46:11: 00000000FABE0000 GraphicsCapture.dll
18:46:11: 00000000FAB20000 NoiseGate.dll
18:46:11: 00000000FAB00000 PSVPlugin.dll
18:46:11: ------------------------------------------
18:46:11: Adapter 1
18:46:11:   Video Adapter: NVIDIA GeForce GTX 560 
18:46:11:   Video Adapter Dedicated Video Memory: 1025966080
18:46:11:   Video Adapter Shared System Memory: 1851865088
18:46:11: =====Stream Start: 2013-09-23, 18:46:11===============================================
18:46:11:   Multithreaded optimizations: On
18:46:11:   Base resolution: 1280x720
18:46:11:   Output resolution: 1280x720
18:46:11: ------------------------------------------
18:46:11: Loading up D3D10 on NVIDIA GeForce GTX 560...
18:46:11: ------------------------------------------
18:46:11: Audio Format: 48000hz
18:46:11: Playback device Default
18:46:11: ------------------------------------------
18:46:11: Using desktop audio input: Speakers (USB PnP Sound Device)
18:46:11: ------------------------------------------
18:46:11: Using auxilary audio input: Microphone (USB PnP Sound Device)
18:46:11: ------------------------------------------
18:46:11: Audio Encoding: AAC
18:46:11:     bitrate: 128
18:46:11: Using text output
18:46:11: Using Window Capture
18:46:11: Using bitmap image
18:46:11: Using bitmap image
18:46:11: ------------------------------------------
18:46:11:     device: Logitech HD Pro Webcam C920,
18:46:11:     device id \\?\usb#vid_046d&pid_082d&mi_00#6&22453a1e&0&0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
18:46:11:     chosen type: I420, usingFourCC: false, res: 1920x1080 - 1920x1080, frameIntervals: 333333-2000000, fourCC: 'I420'
18:46:11:     audio device: Disable,
18:46:11:     audio device id Disabled,
18:46:11: 
18:46:11: Using directshow input
18:46:11: ------------------------------------------
18:46:11:     device: AVerMedia HD Capture C985 Bus 6,
18:46:11:     device id \\?\pci#ven_1af2&dev_a001&subsys_a0011af2&rev_00#4&16fd5669&0&0050#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{957bd672-7934-4b55-b81a-3e22b14c679a},
18:46:11:     chosen type: YV12, usingFourCC: true, res: 1280x720 - 1280x720, frameIntervals: 166666-666666, fourCC: 'YV12'
18:46:11: 
18:46:12: Using directshow input
18:46:12: ------------------------------------------
18:46:12: Video Encoding: x264
18:46:12:     fps: 60
18:46:12:     width: 1280, height: 720
18:46:12:     preset: medium
18:46:12:     CBR: yes
18:46:12:     CFR: yes
18:46:12:     max bitrate: 2600
18:46:12:     buffer size: 2600
18:46:12: ------------------------------------------
18:46:12: MMDeviceAudioSource: Frequency for device 'Speakers (USB PnP Sound Device)' is 384000, samples per sec is 48000
18:46:12: MMDeviceAudioSource: Frequency for device 'Microphone (USB PnP Sound Device)' is 192000, samples per sec is 48000
18:46:12: Syncing audio to video time (WARNING: you should not be doing this if you are just having webcam desync, that's a separate issue)
18:46:15: Using RTMP service: Twitch / Justin.tv
18:46:15:   Server selection: rtmp://live-jfk.justin.tv/app
18:46:15:   Interface: Realtek PCIe GBE Family Controller (ethernet, 1000 mbps)
18:46:16: SO_SNDBUF was at 8192
18:46:16: SO_SNDBUF is now 65536
18:46:17: RTMPPublisher::Socketloop: Increasing send buffer to ISB 131072 (buffer: 68830 / 349184)
18:46:18: RTMPPublisher::Socketloop: Increasing send buffer to ISB 262144 (buffer: 26401 / 349184)
18:46:22: RTMPPublisher::Socketloop: Increasing send buffer to ISB 524288 (buffer: 0 / 349184)
18:48:14: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:48:14:   New Scene
18:48:14: Using bitmap image
18:48:15: Using bitmap image
18:48:15: Using bitmap image
18:48:15: Using text output
18:48:15: Using text output
18:48:15: Using text output
18:48:15: Using bitmap image
18:48:15: Using bitmap image
18:48:15: Using text output
18:48:15: Using bitmap image
18:48:15: PERFORMANCE WARNING: Scene change took 534 ms, maybe some sources should be global sources?
18:48:15: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:48:15:   New Scene
18:48:15: Using text output
18:48:15: Using text output
18:48:15: Using text output
18:48:15: Using bitmap image
18:48:16: Using bitmap image
18:48:16: Using text output
18:48:16: Using Window Capture
18:48:16: Using bitmap image
18:48:18: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:48:18:   New Scene
18:48:18: Using text output
18:48:18: Using text output
18:48:18: Using text output
18:48:18: Using bitmap image
18:48:18: Using bitmap image
18:48:18: Using text output
18:48:19: Using bitmap image
18:48:21: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:48:21:   New Scene
18:48:21: Using text output
18:48:21: Using bitmap image
18:48:22: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:48:22:   New Scene
18:48:22: Using text output
18:48:22: Using text output
18:48:22: Using text output
18:48:22: Using bitmap image
18:48:22: Using bitmap image
18:48:22: Using text output
18:48:22: Using bitmap image
18:48:22: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:48:22:   New Scene
18:48:22: Using text output
18:48:23: Using bitmap image
18:48:23: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:48:23:   New Scene
18:48:23: Using text output
18:48:23: Using text output
18:48:23: Using text output
18:48:23: Using bitmap image
18:48:23: Using bitmap image
18:48:23: Using text output
18:48:23: Using bitmap image
18:48:24: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:48:24:   New Scene
18:48:24: Using text output
18:48:24: Using bitmap image
18:48:24: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:48:24:   New Scene
18:48:24: Using text output
18:48:24: Using text output
18:48:24: Using text output
18:48:25: Using bitmap image
18:48:25: Using bitmap image
18:48:25: Using text output
18:48:25: Using bitmap image
18:48:25: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:48:25:   New Scene
18:48:25: Using text output
18:48:25: Using text output
18:48:25: Using text output
18:48:25: Using bitmap image
18:48:25: Using bitmap image
18:48:25: Using text output
18:48:25: Using bitmap image
18:48:27: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:48:27:   New Scene
18:48:27: Using text output
18:48:27: Using text output
18:48:27: Using text output
18:48:27: Using bitmap image
18:48:27: Using bitmap image
18:48:27: Using text output
18:48:27: Using bitmap image
18:48:28: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:48:28:   New Scene
18:48:28: Using text output
18:48:28: Using bitmap image
19:14:32: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:14:32:   New Scene
19:14:32: Using text output
19:14:32: Using text output
19:14:32: Using text output
19:14:32: Using bitmap image
19:14:32: Using bitmap image
19:14:32: Using text output
19:14:32: Using bitmap image
19:14:37: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:14:37:   New Scene
19:14:37: Using text output
19:14:37: Using text output
19:14:37: Using text output
19:14:38: Using bitmap image
19:14:38: Using bitmap image
19:14:38: Using text output
19:14:38: Using bitmap image
19:14:42: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:14:42:   New Scene
19:14:42: Using text output
19:14:42: Using bitmap image
19:14:45: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:14:45:   New Scene
19:14:45: Using text output
19:14:45: Using text output
19:14:45: Using text output
19:14:45: Using bitmap image
19:14:45: Using bitmap image
19:14:45: Using text output
19:14:45: Using bitmap image
19:14:45: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:14:45:   New Scene
19:14:45: Using text output
19:14:45: Using text output
19:14:45: Using text output
19:14:45: Using bitmap image
19:14:45: Using bitmap image
19:14:45: Using text output
19:14:45: Using bitmap image
19:14:46: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:14:46:   New Scene
19:14:46: Using text output
19:14:46: Using bitmap image
19:14:46: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:14:46:   New Scene
19:14:46: Using text output
19:14:46: Using text output
19:14:46: Using text output
19:14:46: Using bitmap image
19:14:46: Using bitmap image
19:14:46: Using text output
19:14:46: Using bitmap image
19:14:47: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:14:47:   New Scene
19:14:47: Using text output
19:14:47: Using bitmap image
19:14:47: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:14:47:   New Scene
19:14:47: Using text output
19:14:47: Using text output
19:14:47: Using text output
19:14:47: Using bitmap image
19:14:48: Using bitmap image
19:14:48: Using text output
19:14:48: Using bitmap image
19:14:48: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:14:48:   New Scene
19:14:48: Using text output
19:14:48: Using bitmap image
19:14:57: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:14:57:   New Scene
19:14:57: Using text output
19:14:57: Using text output
19:14:57: Using text output
19:14:57: Using bitmap image
19:14:57: Using bitmap image
19:14:57: Using text output
19:14:57: Using bitmap image
19:15:04: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:15:04:   New Scene
19:15:04: Using text output
19:15:04: Using text output
19:15:04: Using text output
19:15:04: Using bitmap image
19:15:04: Using bitmap image
19:15:04: Using text output
19:15:04: Using bitmap image
19:15:05: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:15:05:   New Scene
19:15:05: Using text output
19:15:05: Using bitmap image
19:16:27: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:16:27:   New Scene
19:16:27: Using text output
19:16:27: Using text output
19:16:27: Using text output
19:16:27: Using bitmap image
19:16:28: Using bitmap image
19:16:28: Using text output
19:16:28: Using bitmap image
19:16:50: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:16:50:   New Scene
19:16:50: Using text output
19:16:50: Using bitmap image
19:41:55: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:41:55:   New Scene
19:41:55: Using text output
19:41:55: Using text output
19:41:55: Using text output
19:41:55: Using bitmap image
19:41:55: Using bitmap image
19:41:55: Using text output
19:41:55: Using bitmap image
19:46:16: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:46:16:   New Scene
19:46:16: Using text output
19:46:16: Using text output
19:46:16: Using text output
19:46:16: Using bitmap image
19:46:16: Using bitmap image
19:46:16: Using text output
19:46:16: Using bitmap image
19:48:12: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:48:12:   New Scene
19:48:12: Using text output
19:48:12: Using text output
19:48:12: Using text output
19:48:12: Using bitmap image
19:48:12: Using bitmap image
19:48:12: Using text output
19:48:12: Using bitmap image
19:48:21: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:48:21:   New Scene
19:48:21: Using text output
19:48:21: Using bitmap image
19:50:03: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:50:03:   New Scene
19:50:03: Using text output
19:50:03: Using text output
19:50:03: Using text output
19:50:03: Using bitmap image
19:50:03: Using bitmap image
19:50:03: Using text output
19:50:03: Using bitmap image
19:51:52: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:51:52:   New Scene
19:51:52: Using text output
19:51:52: Using bitmap image
19:52:53: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:52:53:   New Scene
19:52:53: Using text output
19:52:53: Using text output
19:52:53: Using text output
19:52:53: Using bitmap image
19:52:54: Using bitmap image
19:52:54: Using text output
19:52:54: Using bitmap image
19:55:42: Total frames encoded: 250123, total frames duplicated: 28387 (11.35%)
19:55:42: Number of frames skipped due to encoder lag: 231 (0.09%)
19:55:42: Total frames rendered: 249876, number of late frames: 114 (0.05%) (it's okay for some frames to be late)
19:55:42: RTMPPublisher::SocketLoop: Graceful loop exit
19:55:43: Number of times waited to send: 0, Waited for a total of 0 bytes
19:55:43: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
19:55:43: 
19:55:43: Profiler results:
19:55:43: 
19:55:43: ==============================================================
19:55:43: video thread frame - [100%] [avg time: 5.46 ms (cpu time: avg 2.711 ms, total 677622 ms)] [avg calls per frame: 1] [children: 99.5%] [unaccounted: 0.495%]
19:55:43: | frame preprocessing and rendering - [94.7%] [avg time: 5.173 ms (cpu time: avg 2.65 ms, total 662365 ms)] [avg calls per frame: 1] [children: 81.3%] [unaccounted: 13.4%]
19:55:43: | | scene->Preprocess - [81.3%] [avg time: 4.439 ms (cpu time: avg 2.132 ms, total 532743 ms)] [avg calls per frame: 1]
19:55:43: | GPU download and color conversion - [4.76%] [avg time: 0.26 ms (cpu time: avg 0.057 ms, total 14430.1 ms)] [avg calls per frame: 1] [children: 3.99%] [unaccounted: 0.769%]
19:55:43: | | flush - [3.28%] [avg time: 0.179 ms (cpu time: avg 0.023 ms, total 5787.64 ms)] [avg calls per frame: 1]
19:55:43: | | CopyResource - [0.623%] [avg time: 0.034 ms (cpu time: avg 0.013 ms, total 3478.82 ms)] [avg calls per frame: 1]
19:55:43: | | conversion to 4:2:0 - [0.0916%] [avg time: 0.005 ms (cpu time: avg 0.004 ms, total 1092.01 ms)] [avg calls per frame: 1]
19:55:43: Convert444Threads - [100%] [avg time: 0.974 ms (cpu time: avg 0.939 ms, total 469625 ms)] [avg calls per frame: 2] [children: 99.3%] [unaccounted: 0.719%]
19:55:43: | Convert444toNV12 - [99.3%] [avg time: 0.967 ms (cpu time: avg 0.935 ms, total 467457 ms)] [avg calls per frame: 2]
19:55:43: encoder thread frame - [100%] [avg time: 1.342 ms (cpu time: avg 0.884 ms, total 221085 ms)] [avg calls per frame: 1]
19:55:43: ==============================================================
19:55:43: 
19:55:43: =====Stream End: 2013-09-23, 19:55:43=================================================
19:55:45: Memory Leaks Were Detected.
19:55:45:

Just curious to what may be causing these frames to be duplicated and what steps I can take to reducing or eliminating it.

R1CH · Sep 25, 2013

You are using an old test build of OBS, please update to the newest release.

theinzane · Sep 25, 2013

I should have also stated that I have tried using both the latest release and the test build and both give me the same results with the duplicate frame issue.

theinzane · Sep 25, 2013

Here's a log from just this morning with the latest OBS release and a lower resolution. As you can see despite lowering the resolution to lighten the load on the processor the amount of duplicated frames stays within the same range (2-10%).

Code:

09:20:13: Open Broadcaster Software v0.57b - 32bit (´・ω・｀)
09:20:13: -------------------------------
09:20:13: CPU Name: AMD FX(tm)-8350 Eight-Core Processor
09:20:13: CPU Speed: 4400MHz
09:20:13: Physical Memory:  4044MB Total, 3146MB Free
09:20:13: stepping id: 0, model 2, family 15, type 0, extmodel 0, extfamily 3, HTT 1, logical cores 8, total cores 4
09:20:13: monitor 1: pos={0, 0}, size={1920, 1080}
09:20:13: monitor 2: pos={1920, 0}, size={1680, 1050}
09:20:13: Windows Version: 6.1 Build 7601 S
09:20:13: Aero is Enabled
09:20:13: -------------------------------
09:20:13: OBS Modules:
09:20:13: Base Address     Module
09:20:13: 00280000         OBS.exe
09:20:13: 719F0000         OBSApi.dll
09:20:13: 74750000         DShowPlugin.dll
09:20:13: 738F0000         GraphicsCapture.dll
09:20:13: 74730000         NoiseGate.dll
09:20:13: 738D0000         PSVPlugin.dll
09:20:13: ------------------------------------------
09:20:13: Adapter 1
09:20:13:   Video Adapter: NVIDIA GeForce GTX 560 
09:20:13:   Video Adapter Dedicated Video Memory: 1025966080
09:20:13:   Video Adapter Shared System Memory: 1851863040
09:20:13: =====Stream Start: 2013-09-25, 09:20:13===============================================
09:20:13:   Multithreaded optimizations: On
09:20:13:   Base resolution: 1920x1080
09:20:13:   Output resolution: 960x540
09:20:13: ------------------------------------------
09:20:13: Loading up D3D10 on NVIDIA GeForce GTX 560...
09:20:14: ------------------------------------------
09:20:14: Audio Format: 48000hz
09:20:14: Playback device Default
09:20:14: ------------------------------------------
09:20:14: Using desktop audio input: Speakers (USB PnP Sound Device)
09:20:14: ------------------------------------------
09:20:14: Using auxilary audio input: Microphone (USB PnP Sound Device)
09:20:14: ------------------------------------------
09:20:14: Audio Encoding: AAC
09:20:14:     bitrate: 128
09:20:14: Using text output
09:20:14: Using Window Capture
09:20:14: Using bitmap image
09:20:14: Using bitmap image
09:20:14: ------------------------------------------
09:20:14:     device: Logitech HD Pro Webcam C920,
09:20:14:     device id \\?\usb#vid_046d&pid_082d&mi_00#6&22453a1e&0&0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
09:20:14:     chosen type: I420, usingFourCC: false, res: 1920x1080 - 1920x1080, frameIntervals: 333333-2000000, fourCC: 'I420'
09:20:14:     audio device: Disable,
09:20:14:     audio device id Disabled,
09:20:14: 
09:20:14: Using directshow input
09:20:15: ------------------------------------------
09:20:15:     device: AVerMedia HD Capture C985 Bus 6,
09:20:15:     device id \\?\pci#ven_1af2&dev_a001&subsys_a0011af2&rev_00#4&16fd5669&0&0050#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{957bd672-7934-4b55-b81a-3e22b14c679a},
09:20:15:     chosen type: YV12, usingFourCC: true, res: 1280x720 - 1280x720, frameIntervals: 166666-666666, fourCC: 'YV12'
09:20:15: 
09:20:15: Using directshow input
09:20:16: ------------------------------------------
09:20:16: Video Encoding: x264
09:20:16:     fps: 60
09:20:16:     width: 960, height: 540
09:20:16:     preset: medium
09:20:16:     profile: high
09:20:16:     keyint: 120
09:20:16:     CBR: yes
09:20:16:     CFR: yes
09:20:16:     max bitrate: 2600
09:20:16:     buffer size: 3000
09:20:16: ------------------------------------------
09:20:16: MMDeviceAudioSource: Frequency for device 'Speakers (USB PnP Sound Device)' is 384000, samples per sec is 48000
09:20:16: MMDeviceAudioSource: Frequency for device 'Microphone (USB PnP Sound Device)' is 192000, samples per sec is 48000
09:20:18: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
09:20:18:   New Scene
09:20:18: Using bitmap image
09:20:18: Using bitmap image
09:20:18: Using bitmap image
09:20:18: Using text output
09:20:18: Using text output
09:20:18: Using text output
09:20:18: Using bitmap image
09:20:18: Using bitmap image
09:20:18: Using text output
09:20:18: Using bitmap image
09:20:18: Using Window Capture
09:20:20: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
09:20:20:   New Scene
09:20:20: Using text output
09:20:20: Using text output
09:20:20: Using text output
09:20:20: Using bitmap image
09:20:20: Using bitmap image
09:20:20: Using text output
09:20:22: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
09:20:22:   New Scene
09:20:22: Using text output
09:20:22: Using bitmap image
09:21:44: Total frames encoded: 5298, total frames duplicated: 349 (6.59%)
09:21:44: Total frames rendered: 5297, number of late frames: 2 (0.04%) (it's okay for some frames to be late)
09:21:45: 
09:21:45: Profiler time results:
09:21:45: 
09:21:45: ==============================================================
09:21:45: video thread frame - [100%] [avg time: 4.271 ms] [children: 82.6%] [unaccounted: 17.4%]
09:21:45: | scene->Preprocess - [82.6%] [avg time: 3.527 ms]
09:21:45: GPU download and conversion - [100%] [avg time: 0.184 ms] [children: 79.3%] [unaccounted: 20.7%]
09:21:45: | flush - [67.4%] [avg time: 0.124 ms]
09:21:45: | CopyResource - [8.7%] [avg time: 0.016 ms]
09:21:45: | conversion to 4:2:0 - [3.26%] [avg time: 0.006 ms]
09:21:45: Convert444Threads - [100%] [avg time: 0.523 ms] [children: 98.5%] [unaccounted: 1.53%]
09:21:45: | Convert444toNV12 - [98.5%] [avg time: 0.515 ms]
09:21:45: encoder thread frame - [100%] [avg time: 0.845 ms]
09:21:45: ==============================================================
09:21:45: 
09:21:45: 
09:21:45: Profiler CPU results:
09:21:45: 
09:21:45: ==============================================================
09:21:45: video thread frame - [cpu time: avg 2.482 ms, total 13150.9 ms] [avg calls per frame: 1]
09:21:45: | scene->Preprocess - [cpu time: avg 1.931 ms, total 10233.7 ms] [avg calls per frame: 1]
09:21:45: GPU download and conversion - [cpu time: avg 0.061 ms, total 327.601 ms] [avg calls per frame: 1]
09:21:45: | flush - [cpu time: avg 0.035 ms, total 187.2 ms] [avg calls per frame: 1]
09:21:45: | CopyResource - [cpu time: avg 0.011 ms, total 62.401 ms] [avg calls per frame: 1]
09:21:45: | conversion to 4:2:0 - [cpu time: avg 0.002 ms, total 15.6 ms] [avg calls per frame: 1]
09:21:45: Convert444Threads - [cpu time: avg 0.549 ms, total 5818.84 ms] [avg calls per frame: 2]
09:21:45: | Convert444toNV12 - [cpu time: avg 0.545 ms, total 5772.04 ms] [avg calls per frame: 2]
09:21:45: encoder thread frame - [cpu time: avg 0.135 ms, total 717.604 ms] [avg calls per frame: 1]
09:21:45: ==============================================================
09:21:45: 
09:21:45: =====Stream End: 2013-09-25, 09:21:45=================================================
09:21:51: =====Stream Start: 2013-09-25, 09:21:51===============================================
09:21:51:   Multithreaded optimizations: On
09:21:51:   Base resolution: 1920x1080
09:21:51:   Output resolution: 960x540
09:21:51: ------------------------------------------
09:21:51: Loading up D3D10 on NVIDIA GeForce GTX 560...
09:21:52: ------------------------------------------
09:21:52: Audio Format: 48000hz
09:21:52: Playback device Default
09:21:52: ------------------------------------------
09:21:52: Using desktop audio input: Speakers (USB PnP Sound Device)
09:21:52: ------------------------------------------
09:21:52: Using auxilary audio input: Microphone (USB PnP Sound Device)
09:21:52: ------------------------------------------
09:21:52: Audio Encoding: AAC
09:21:52:     bitrate: 128
09:21:52: Using text output
09:21:52: Using Window Capture
09:21:52: Using bitmap image
09:21:52: Using bitmap image
09:21:52: ------------------------------------------
09:21:52:     device: Logitech HD Pro Webcam C920,
09:21:52:     device id \\?\usb#vid_046d&pid_082d&mi_00#6&22453a1e&0&0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
09:21:52:     chosen type: I420, usingFourCC: false, res: 1920x1080 - 1920x1080, frameIntervals: 333333-2000000, fourCC: 'I420'
09:21:52:     audio device: Disable,
09:21:52:     audio device id Disabled,
09:21:52: 
09:21:52: Using directshow input
09:21:53: ------------------------------------------
09:21:53:     device: AVerMedia HD Capture C985 Bus 6,
09:21:53:     device id \\?\pci#ven_1af2&dev_a001&subsys_a0011af2&rev_00#4&16fd5669&0&0050#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{957bd672-7934-4b55-b81a-3e22b14c679a},
09:21:53:     chosen type: YV12, usingFourCC: true, res: 1280x720 - 1280x720, frameIntervals: 166666-666666, fourCC: 'YV12'
09:21:53: 
09:21:53: Using directshow input
09:21:53: ------------------------------------------
09:21:53: Video Encoding: x264
09:21:53:     fps: 60
09:21:53:     width: 960, height: 540
09:21:53:     preset: medium
09:21:53:     profile: high
09:21:53:     keyint: 120
09:21:53:     CBR: yes
09:21:53:     CFR: yes
09:21:53:     max bitrate: 2600
09:21:53:     buffer size: 3000
09:21:53: ------------------------------------------
09:21:53: MMDeviceAudioSource: Frequency for device 'Speakers (USB PnP Sound Device)' is 384000, samples per sec is 48000
09:21:53: MMDeviceAudioSource: Frequency for device 'Microphone (USB PnP Sound Device)' is 192000, samples per sec is 48000
09:21:54: Using RTMP service: Twitch / Justin.tv
09:21:54:   Server selection: rtmp://live-jfk.justin.tv/app
09:21:54:   Interface: Realtek PCIe GBE Family Controller (ethernet, 1000 mbps)
09:21:54: Completed handshake with rtmp://live-jfk.justin.tv/app in 367 ms.
09:21:55: SO_SNDBUF was at 8192
09:21:55: SO_SNDBUF is now 65536
09:21:57: RTMPPublisher::Socketloop: Increasing send buffer to ISB 131072 (buffer: 135470 / 349184)
09:21:57: RTMPPublisher::Socketloop: Increasing send buffer to ISB 262144 (buffer: 140650 / 349184)
09:21:59: RTMPPublisher::Socketloop: Increasing send buffer to ISB 524288 (buffer: 0 / 349184)
09:22:19: Settings::Video: Disabling Aero
09:23:03: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
09:23:03:   New Scene
09:23:03: Using bitmap image
09:23:03: Using bitmap image
09:23:03: Using bitmap image
09:23:03: Using text output
09:23:03: Using text output
09:23:03: Using text output
09:23:03: Using bitmap image
09:23:03: Using bitmap image
09:23:03: Using text output
09:23:03: Using bitmap image
09:23:03: Using Window Capture
09:23:03: PERFORMANCE WARNING: Scene change took 529 ms, maybe some sources should be global sources?
09:23:10: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
09:23:10:   New Scene
09:23:10: Using text output
09:23:10: Using text output
09:23:10: Using text output
09:23:10: Using bitmap image
09:23:10: Using bitmap image
09:23:10: Using text output
09:23:11: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
09:23:11:   New Scene
09:23:11: Using text output
09:23:11: Using bitmap image
09:23:13: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
09:23:13:   New Scene
09:23:13: Using text output
09:23:13: Using text output
09:23:13: Using text output
09:23:13: Using bitmap image
09:23:13: Using bitmap image
09:23:13: Using text output
09:23:20: Settings::Video: Enabling Aero
09:23:23: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
09:23:23:   New Scene
09:23:23: Using text output
09:23:23: Using bitmap image
09:23:24: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
09:23:24:   New Scene
09:23:24: Using text output
09:23:24: Using text output
09:23:24: Using text output
09:23:24: Using bitmap image
09:23:24: Using bitmap image
09:23:24: Using text output
09:23:39: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
09:23:39:   New Scene
09:23:39: Using text output
09:23:39: Using bitmap image
09:25:13: ------------------------------------------
09:25:13:     device: Logitech HD Pro Webcam C920,
09:25:13:     device id \\?\usb#vid_046d&pid_082d&mi_00#6&22453a1e&0&0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
09:25:13:     chosen type: I420, usingFourCC: false, res: 1920x1080 - 1920x1080, frameIntervals: 333333-2000000, fourCC: 'I420'
09:25:13:     audio device: Disable,
09:25:13:     audio device id Disabled,
09:25:13: 
12:20:56: Total frames encoded: 644569, total frames duplicated: 24536 (3.81%)
12:20:56: Total frames rendered: 643339, number of late frames: 74 (0.01%) (it's okay for some frames to be late)
12:20:57: RTMPPublisher::SocketLoop: Graceful loop exit
12:20:57: Average send payload: 5077 bytes, average send interval: 14 ms
12:20:57: Number of times waited to send: 0, Waited for a total of 0 bytes
12:20:57: Number of b-frames dropped: 1 (0.00016%), Number of p-frames dropped: 0 (0%), Total 1 (0.00016%)
12:20:57: Number of bytes sent: 3676602018
12:20:57: 
12:20:57: Profiler time results:
12:20:57: 
12:20:57: ==============================================================
12:20:57: video thread frame - [100%] [avg time: 3.834 ms] [children: 83.6%] [unaccounted: 16.4%]
12:20:57: | scene->Preprocess - [83.6%] [avg time: 3.206 ms]
12:20:57: GPU download and conversion - [100%] [avg time: 0.182 ms] [children: 79.7%] [unaccounted: 20.3%]
12:20:57: | flush - [68.1%] [avg time: 0.124 ms]
12:20:57: | CopyResource - [8.79%] [avg time: 0.016 ms]
12:20:57: | conversion to 4:2:0 - [2.75%] [avg time: 0.005 ms]
12:20:57: Convert444Threads - [100%] [avg time: 0.471 ms] [children: 98.5%] [unaccounted: 1.49%]
12:20:57: | Convert444toNV12 - [98.5%] [avg time: 0.464 ms]
12:20:57: encoder thread frame - [100%] [avg time: 0.843 ms]
12:20:57: ==============================================================
12:20:57: 
12:20:57: 
12:20:57: Profiler CPU results:
12:20:57: 
12:20:57: ==============================================================
12:20:57: video thread frame - [cpu time: avg 2.263 ms, total 1.45607e+006 ms] [avg calls per frame: 1]
12:20:57: | scene->Preprocess - [cpu time: avg 1.684 ms, total 1.08352e+006 ms] [avg calls per frame: 1]
12:20:57: GPU download and conversion - [cpu time: avg 0.055 ms, total 36005 ms] [avg calls per frame: 1]
12:20:57: | flush - [cpu time: avg 0.025 ms, total 16660.9 ms] [avg calls per frame: 1]
12:20:57: | CopyResource - [cpu time: avg 0.011 ms, total 7207.25 ms] [avg calls per frame: 1]
12:20:57: | conversion to 4:2:0 - [cpu time: avg 0.004 ms, total 2745.62 ms] [avg calls per frame: 1]
12:20:57: Convert444Threads - [cpu time: avg 0.415 ms, total 534054 ms] [avg calls per frame: 2]
12:20:57: | Convert444toNV12 - [cpu time: avg 0.411 ms, total 528875 ms] [avg calls per frame: 2]
12:20:57: encoder thread frame - [cpu time: avg 0.14 ms, total 90449.4 ms] [avg calls per frame: 1]
12:20:57: ==============================================================
12:20:57: 
12:20:57: =====Stream End: 2013-09-25, 12:20:57=================================================

Any help solving this would be wonderful.

alpinlol · Sep 25, 2013

if im not mistaken it just duplicates the frames you lose through your capture method so when you try to stream at 60 fps it might be possible that it drops to 59 and sometimes even 58 fps which you wont notice but obs just duplicates a frames to fill the 60 frames you try to encode in...

guess one of the devs will correct me if im wrong thats just what im guessing here :/

theinzane · Sep 25, 2013

Yea you are right, to maintain a constant frame rate the encoder will duplicate frames in order to maintain 60fps when needed. This way when you go to do any video editing afterwards it helps prevent a lot of issues with audio syncing and such. I've tried turning CFR off to see if it would stop duplicating frames but it will still duplicate frames even with CFR off.

I also read that if you are using a CBR and your bitrate exceeds your buffer for that frames that it will drop the frame and duplicate the previous frame in order to keep the bitrate within your specifications. I tried increasing the buffer a bit beyond my specified max bitrate but once again the duplicated frames stayed within the same range.

I am now trying to narrow down whether or not its a software or a hardware problem. I don't think its a problem with OBS because I wouldn't be the only one having this issue. I have looked at a variety of different logs that have been posted here to see if anyone else is having this problem and it doesn't seem as if they are. I also cross referenced the two logs and matched some other peoples settings who have less powerful hardware than I am using to see if perhaps I am getting to aggressive on the encoding but the problem still persits.

Lain · Sep 25, 2013

Hm, didn't really see that when testing, but I'll check to see it perhaps it's just some sort of synchronization problem or something.

theinzane · Sep 25, 2013

Thanks Jim, I am actively trying to update different drivers, change various windows settings one setting at a time so if I manage to fix it I know exactly what was causing the problem. If I do discover what is going wrong I will make sure I reply on here stating what I did.

theinzane · Sep 25, 2013

Ok, I think I have found the source of the problem. I have a lot of global sources, sources and a few different scenes setup. I decided to turn them off until I was down to the bare minimun (the avermedia input). This seemed to reduce the duplicated frames down to below 0.1% which is acceptable. One duplicated frame every 1000 frames is perfectly fine. I then began to enable each source one at a time and eventually found that once I enable my webcam I start to get a lot of duplicate frames.

I am using a Logitech C920 HD camera at 1920x1080p 30fps. Not using a webcam isn't really an option as I like to interact with my viewers and I know they appreciate being able to put a face to the voice so I messed with some settings. It seems if I use the I420 or RBG24 output format I get the frame duplicating problem but if I use MJPG output format the duplicate frames stay around 0.1%.

So I guess I solved my own problem, didn't completely remove the duplicate frames but atleast its 1/1000 instead of 1/10. I hope this information helps to fix this problem if possible or help others with a similar problem.

Lain · Sep 25, 2013

Webcam? Hm, very interesting. Thank you for pointing that out. I don't think the webcam directly is to blame, but I think the code to process the webcam data is causing the encoder thread and the capture thread to not be quite as in sync, there by increasing the change of duplicate frames. MJPG on the other hand doesn't need much decoding power.

Thank you very much for pointing this out to me and doing that extensive testing, it was very helpful. You're awesome.

Question / Help Duplicate Frames

theinzane

Member

R1CH

theinzane

Member

theinzane

Member

alpinlol

Active Member

theinzane

Member

Lain

theinzane

Member

theinzane

Member

Lain