Question / Help Skipped frames problem. Logs inside.

Krxz · Oct 8, 2013

Hello community,

So as the title says I have several skipped frames problems apparently as well as slow server.

PC RIG

-i5 3750k 3.4Ghz not OCed
-8 GB G.skill RAM
-Radeon 7950HD

Testmynet results to Amsterdam: 4.7Mbps

OBS Settings:

-Downscaled 720p60fps
-2000-2800rates (I can do anything in between no issue)
-x264: veryfast
-server: NL
-JVTping: NL(42ms), GE(60ms), UK(42ms) So I guess I can pick any.

Games I stream: Payday 2, League of Legends, APB Reloaded.

I don't understand where this can come from. Is my config to low for 720p60fps? It shouldn't be. Upload wise, I think I am more then fine. I've pulled off 3500 rates and 4000 rates. People said my stream was freezing every 2 secs, even on 2000 rates. So after I checked the log analyzer I found these issues.

It also mentioned slow scenes? Almost all my scenes are global scenes except the actual game captures and 1 or 2 wallpapers.

Log1: I believe this Payday 2

Code:

20:39:20: Open Broadcaster Software v0.571b - 64bit (　^ω^)
20:39:20: -------------------------------
20:39:20: CPU Name: Intel(R) Core(TM) i5-3570K CPU @ 3.40GHz
20:39:20: CPU Speed: 3400MHz
20:39:20: Physical Memory:  8140MB Total, 5506MB Free
20:39:20: stepping id: 9, model 10, family 6, type 0, extmodel 1, extfamily 0, HTT 1, logical cores 4, total cores 4
20:39:20: monitor 1: pos={0, 0}, size={1920, 1080}
20:39:20: Windows Version: 6.2 Build 9200 
20:39:20: Aero is Enabled
20:39:20: -------------------------------
20:39:20: OBS Modules:
20:39:20: Base Address     Module
20:39:20: 000000006BD50000 OBS.exe
20:39:20: 00000000BA9A0000 OBSApi.dll
20:39:20: 00000000C5580000 DShowPlugin.dll
20:39:20: 00000000C7370000 GraphicsCapture.dll
20:39:20: 00000000C5440000 NoiseGate.dll
20:39:20: 00000000C5420000 PSVPlugin.dll
20:39:20: 00000000C1F70000 scenesw.dll
20:39:20: 00000000C1CD0000 WebSocketAPIPlugin.dll
20:39:20: ------------------------------------------
20:39:20: Adapter 1
20:39:20:   Video Adapter: AMD Radeon HD 7900 Series
20:39:20:   Video Adapter Dedicated Video Memory: 3202523136
20:39:20:   Video Adapter Shared System Memory: 3999481856
20:39:20: Using fixed low latency mode, factor 20
20:39:20: =====Stream Start: 2013-10-08, 20:39:20===============================================
20:39:20:   Multithreaded optimizations: On
20:39:20:   Base resolution: 1920x1080
20:39:20:   Output resolution: 1280x720
20:39:20: ------------------------------------------
20:39:20: Loading up D3D10 on AMD Radeon HD 7900 Series...
20:39:20: ------------------------------------------
20:39:20: Audio Format: 48000hz
20:39:20: Playback device {0.0.0.00000000}.{15192ab2-8b4c-4aef-bf0b-c0713b40d621}
20:39:20: ------------------------------------------
20:39:20: Using desktop audio input: Line 1 (Virtual Audio Cable)
20:39:20: ------------------------------------------
20:39:20: Using auxilary audio input: Microphone (Logitech G35 Headset)
20:39:20: ------------------------------------------
20:39:20: Audio Encoding: AAC
20:39:20:     bitrate: 128
20:39:20: Using text output
20:39:20: Using text output
20:39:20: Using text output
20:39:20: Using text output
20:39:20: Using bitmap image
20:39:20: Using bitmap image
20:39:20: Using bitmap image
20:39:21: Using bitmap image
20:39:21: Using bitmap image
20:39:21: Using bitmap image
20:39:21: Using Window Capture
20:39:21: Using Window Capture
20:39:21: Using bitmap image
20:39:21: Using bitmap image
20:39:21: ------------------------------------------
20:39:21: Video Encoding: x264
20:39:21:     fps: 60
20:39:21:     width: 1280, height: 720
20:39:21:     preset: veryfast
20:39:21:     profile: high
20:39:21:     keyint: 120
20:39:21:     CBR: yes
20:39:21:     CFR: no
20:39:21:     max bitrate: 2800
20:39:21:     buffer size: 2800
20:39:21: ------------------------------------------
20:39:21: MMDeviceAudioSource: Frequency for device 'Line 1 (Virtual Audio Cable)' is 352800, samples per sec is 44100
20:39:21: MMDeviceAudioSource: Frequency for device 'Microphone (Logitech G35 Headset)' is 176400, samples per sec is 44100
20:39:22: Using RTMP service: Twitch / Justin.tv
20:39:22:   Server selection: rtmp://live-ams.justin.tv/app
20:39:22:   Interface: Qualcomm Atheros AR8161 PCI-E Gigabit Ethernet Controller (NDIS 6.30) (ethernet, 100 mbps)
20:39:23: Completed handshake with rtmp://live-ams.justin.tv/app in 430 ms.
20:39:24: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
20:39:24:   New Scene
20:39:24: Using graphics capture
20:39:24: SharedTexCapture hooked
20:39:24: SO_SNDBUF was at 81920
20:39:24: SO_SNDBUF is now 65536
20:40:36: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
20:40:36:   New Scene
20:40:36: PERFORMANCE WARNING: Scene change took 714 ms, maybe some sources should be global sources?
20:40:47: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
20:40:47:   New Scene
20:40:47: Using graphics capture
20:40:47: SharedTexCapture hooked
20:43:34: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
20:43:34:   New Scene
20:43:42: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
20:43:42:   New Scene
20:43:42: Using graphics capture
20:43:42: SharedTexCapture hooked
20:45:05: RTMPPublisher::Socketloop: Increasing send buffer to ISB 131072 (buffer: 0 / 374784)
21:22:29: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:22:29:   New Scene
21:22:38: Total frames encoded: 155789, total frames duplicated: 12607 (8.09%)
21:22:38: Number of frames skipped due to encoder lag: 4484 (2.88%)
21:22:38: Total frames rendered: 147771, number of late frames: 4366 (2.95%) (it's okay for some frames to be late)
21:22:38: RTMPPublisher::SocketLoop: Graceful loop exit
21:22:39: Average send payload: 18874 bytes, average send interval: 51 ms
21:22:39: Number of times waited to send: 0, Waited for a total of 0 bytes
21:22:39: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
21:22:39: Number of bytes sent: 952881279
21:22:39: 
21:22:39: Profiler time results:
21:22:39: 
21:22:39: ==============================================================
21:22:39: video thread frame - [100%] [avg time: 0.958 ms] [children: 87.4%] [unaccounted: 12.6%]
21:22:39: | scene->Preprocess - [87.4%] [avg time: 0.837 ms]
21:22:39: GPU download and conversion - [100%] [avg time: 3.002 ms] [children: 40.9%] [unaccounted: 59.1%]
21:22:39: | flush - [38.4%] [avg time: 1.154 ms]
21:22:39: | CopyResource - [2.27%] [avg time: 0.068 ms]
21:22:39: | conversion to 4:2:0 - [0.233%] [avg time: 0.007 ms]
21:22:39: Convert444Threads - [100%] [avg time: 0.919 ms] [children: 98%] [unaccounted: 1.96%]
21:22:39: | Convert444toNV12 - [98%] [avg time: 0.901 ms]
21:22:39: encoder thread frame - [100%] [avg time: 3.197 ms]
21:22:39: ==============================================================
21:22:39: 
21:22:39: 
21:22:39: Profiler CPU results:
21:22:39: 
21:22:39: ==============================================================
21:22:39: video thread frame - [cpu time: avg 0.158 ms, total 23421.9 ms] [avg calls per frame: 1]
21:22:39: | scene->Preprocess - [cpu time: avg 0.115 ms, total 17046.9 ms] [avg calls per frame: 1]
21:22:39: GPU download and conversion - [cpu time: avg 0.137 ms, total 20375 ms] [avg calls per frame: 1]
21:22:39: | flush - [cpu time: avg 0.113 ms, total 16781.3 ms] [avg calls per frame: 1]
21:22:39: | CopyResource - [cpu time: avg 0.007 ms, total 1171.88 ms] [avg calls per frame: 1]
21:22:39: | conversion to 4:2:0 - [cpu time: avg 0.002 ms, total 296.875 ms] [avg calls per frame: 1]
21:22:39: Convert444Threads - [cpu time: avg 0.589 ms, total 174141 ms] [avg calls per frame: 2]
21:22:39: | Convert444toNV12 - [cpu time: avg 0.585 ms, total 172875 ms] [avg calls per frame: 2]
21:22:39: encoder thread frame - [cpu time: avg 1.031 ms, total 160703 ms] [avg calls per frame: 1]
21:22:39: ==============================================================
21:22:39: 
21:22:39: =====Stream End: 2013-10-08, 21:22:39=================================================
21:22:53: Using fixed low latency mode, factor 20
21:22:53: =====Stream Start: 2013-10-08, 21:22:53===============================================
21:22:53:   Multithreaded optimizations: On
21:22:53:   Base resolution: 1920x1080
21:22:53:   Output resolution: 1280x720
21:22:53: ------------------------------------------
21:22:53: Loading up D3D10 on AMD Radeon HD 7900 Series...
21:22:53: ------------------------------------------
21:22:53: Audio Format: 48000hz
21:22:53: Playback device {0.0.0.00000000}.{15192ab2-8b4c-4aef-bf0b-c0713b40d621}
21:22:53: ------------------------------------------
21:22:53: Using desktop audio input: Line 1 (Virtual Audio Cable)
21:22:53: ------------------------------------------
21:22:53: Using auxilary audio input: Microphone (Logitech G35 Headset)
21:22:53: ------------------------------------------
21:22:53: Audio Encoding: AAC
21:22:53:     bitrate: 128
21:22:53: Using text output
21:22:53: Using text output
21:22:53: Using text output
21:22:53: Using text output
21:22:53: Using bitmap image
21:22:53: Using bitmap image
21:22:53: Using bitmap image
21:22:53: Using bitmap image
21:22:53: Using bitmap image
21:22:53: Using bitmap image
21:22:53: Using Window Capture
21:22:53: Using Window Capture
21:22:53: Using bitmap image
21:22:53: Using bitmap image
21:22:53: ------------------------------------------
21:22:53: Video Encoding: x264
21:22:53:     fps: 60
21:22:53:     width: 1280, height: 720
21:22:53:     preset: veryfast
21:22:53:     profile: high
21:22:53:     keyint: 120
21:22:53:     CBR: yes
21:22:53:     CFR: no
21:22:53:     max bitrate: 2500
21:22:53:     buffer size: 2500
21:22:53: ------------------------------------------
21:22:53: MMDeviceAudioSource: Frequency for device 'Line 1 (Virtual Audio Cable)' is 352800, samples per sec is 44100
21:22:53: MMDeviceAudioSource: Frequency for device 'Microphone (Logitech G35 Headset)' is 176400, samples per sec is 44100
21:22:54: Using RTMP service: Twitch / Justin.tv
21:22:54:   Server selection: rtmp://live-ams.justin.tv/app
21:22:54:   Interface: Qualcomm Atheros AR8161 PCI-E Gigabit Ethernet Controller (NDIS 6.30) (ethernet, 100 mbps)
21:22:55: Completed handshake with rtmp://live-ams.justin.tv/app in 442 ms.
21:22:56: SO_SNDBUF was at 81920
21:22:56: SO_SNDBUF is now 65536
21:23:01: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:23:01:   New Scene
21:23:01: Using graphics capture
21:23:01: SharedTexCapture hooked
21:26:10: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:26:10:   New Scene
21:26:19: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:26:19:   New Scene
21:26:19: Using graphics capture
21:26:23: SharedTexCapture hooked
21:28:15: RTMPPublisher::Socketloop: Increasing send buffer to ISB 131072 (buffer: 0 / 335872)
21:29:25: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:29:25:   New Scene
21:29:25: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:29:25:   New Scene
21:29:25: Using graphics capture
21:29:26: SharedTexCapture hooked
21:29:36: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:29:36:   New Scene
21:29:45: Total frames encoded: 24698, total frames duplicated: 579 (2.34%)
21:29:45: Number of frames skipped due to encoder lag: 124 (0.50%)
21:29:45: Total frames rendered: 24416, number of late frames: 63 (0.26%) (it's okay for some frames to be late)
21:29:45: RTMPPublisher::SocketLoop: Graceful loop exit
21:29:46: Average send payload: 16894 bytes, average send interval: 51 ms
21:29:46: Number of times waited to send: 0, Waited for a total of 0 bytes
21:29:46: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
21:29:46: Number of bytes sent: 134867722
21:29:46: 
21:29:46: Profiler time results:
21:29:46: 
21:29:46: ==============================================================
21:29:46: video thread frame - [100%] [avg time: 0.843 ms] [children: 86.8%] [unaccounted: 13.2%]
21:29:46: | scene->Preprocess - [86.8%] [avg time: 0.732 ms]
21:29:46: GPU download and conversion - [100%] [avg time: 1.289 ms] [children: 82.2%] [unaccounted: 17.8%]
21:29:46: | flush - [79.8%] [avg time: 1.029 ms]
21:29:46: | CopyResource - [2.02%] [avg time: 0.026 ms]
21:29:46: | conversion to 4:2:0 - [0.31%] [avg time: 0.004 ms]
21:29:46: Convert444Threads - [100%] [avg time: 0.884 ms] [children: 98.2%] [unaccounted: 1.81%]
21:29:46: | Convert444toNV12 - [98.2%] [avg time: 0.868 ms]
21:29:46: encoder thread frame - [100%] [avg time: 1.946 ms]
21:29:46: ==============================================================
21:29:46: 
21:29:46: 
21:29:46: Profiler CPU results:
21:29:46: 
21:29:46: ==============================================================
21:29:46: video thread frame - [cpu time: avg 0.103 ms, total 2515.63 ms] [avg calls per frame: 1]
21:29:46: | scene->Preprocess - [cpu time: avg 0.071 ms, total 1734.38 ms] [avg calls per frame: 1]
21:29:46: GPU download and conversion - [cpu time: avg 0.161 ms, total 3953.13 ms] [avg calls per frame: 1]
21:29:46: | flush - [cpu time: avg 0.143 ms, total 3515.63 ms] [avg calls per frame: 1]
21:29:46: | CopyResource - [cpu time: avg 0.003 ms, total 78.125 ms] [avg calls per frame: 1]
21:29:46: | conversion to 4:2:0 - [cpu time: avg 0.002 ms, total 62.5 ms] [avg calls per frame: 1]
21:29:46: Convert444Threads - [cpu time: avg 0.656 ms, total 32015.6 ms] [avg calls per frame: 2]
21:29:46: | Convert444toNV12 - [cpu time: avg 0.653 ms, total 31859.4 ms] [avg calls per frame: 2]
21:29:46: encoder thread frame - [cpu time: avg 0.877 ms, total 21656.3 ms] [avg calls per frame: 1]
21:29:46: ==============================================================
21:29:46: 
21:29:46: =====Stream End: 2013-10-08, 21:29:46=================================================
21:29:55: Using fixed low latency mode, factor 20
21:29:55: =====Stream Start: 2013-10-08, 21:29:55===============================================
21:29:55:   Multithreaded optimizations: On
21:29:55:   Base resolution: 1920x1080
21:29:55:   Output resolution: 1280x720
21:29:55: ------------------------------------------
21:29:55: Loading up D3D10 on AMD Radeon HD 7900 Series...
21:29:55: ------------------------------------------
21:29:55: Audio Format: 48000hz
21:29:55: Playback device {0.0.0.00000000}.{15192ab2-8b4c-4aef-bf0b-c0713b40d621}
21:29:55: ------------------------------------------
21:29:55: Using desktop audio input: Line 1 (Virtual Audio Cable)
21:29:55: ------------------------------------------
21:29:55: Using auxilary audio input: Microphone (Logitech G35 Headset)
21:29:55: ------------------------------------------
21:29:55: Audio Encoding: AAC
21:29:55:     bitrate: 128
21:29:55: Using text output
21:29:55: Using text output
21:29:55: Using text output
21:29:55: Using text output
21:29:55: Using bitmap image
21:29:55: Using bitmap image
21:29:55: Using bitmap image
21:29:55: Using bitmap image
21:29:55: Using bitmap image
21:29:55: Using bitmap image
21:29:55: Using Window Capture
21:29:55: Using Window Capture
21:29:55: Using bitmap image
21:29:56: Using bitmap image
21:29:56: ------------------------------------------
21:29:56: Video Encoding: x264
21:29:56:     fps: 60
21:29:56:     width: 1280, height: 720
21:29:56:     preset: veryfast
21:29:56:     profile: high
21:29:56:     keyint: 120
21:29:56:     CBR: yes
21:29:56:     CFR: no
21:29:56:     max bitrate: 2000
21:29:56:     buffer size: 2000
21:29:56: ------------------------------------------
21:29:56: MMDeviceAudioSource: Frequency for device 'Line 1 (Virtual Audio Cable)' is 352800, samples per sec is 44100
21:29:56: MMDeviceAudioSource: Frequency for device 'Microphone (Logitech G35 Headset)' is 176400, samples per sec is 44100
21:29:57: Using RTMP service: Twitch / Justin.tv
21:29:57:   Server selection: rtmp://live-ams.justin.tv/app
21:29:57:   Interface: Qualcomm Atheros AR8161 PCI-E Gigabit Ethernet Controller (NDIS 6.30) (ethernet, 100 mbps)
21:29:57: Completed handshake with rtmp://live-ams.justin.tv/app in 398 ms.
21:30:00: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:30:00:   New Scene
21:30:00: Using graphics capture
21:30:01: SharedTexCapture hooked
21:30:02: SO_SNDBUF was at 81920
21:30:02: SO_SNDBUF is now 65536
21:31:06: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:31:06:   New Scene
21:31:17: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:31:17:   New Scene
21:31:17: Using graphics capture
21:31:18: SharedTexCapture hooked
21:32:01: GraphicsCaptureSource::NewCapture:  eliminating old capture
21:32:01: SharedTexCapture hooked
21:32:35: GraphicsCaptureSource::NewCapture:  eliminating old capture
21:32:35: SharedTexCapture hooked
21:33:01: RTMPPublisher::Socketloop: Increasing send buffer to ISB 131072 (buffer: 0 / 272384)
21:38:46: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:38:46:   New Scene
21:39:28: Total frames encoded: 34310, total frames duplicated: 2358 (6.87%)
21:39:28: Number of frames skipped due to encoder lag: 1149 (3.35%)
21:39:28: Total frames rendered: 32997, number of late frames: 278 (0.84%) (it's okay for some frames to be late)
21:39:28: RTMPPublisher::SocketLoop: Graceful loop exit
21:39:28: Average send payload: 13722 bytes, average send interval: 51 ms
21:39:28: Number of times waited to send: 0, Waited for a total of 0 bytes
21:39:28: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
21:39:28: Number of bytes sent: 150932299
21:39:28: 
21:39:28: Profiler time results:
21:39:28: 
21:39:28: ==============================================================
21:39:28: video thread frame - [100%] [avg time: 1.032 ms] [children: 86.2%] [unaccounted: 13.8%]
21:39:28: | scene->Preprocess - [86.2%] [avg time: 0.89 ms]
21:39:28: GPU download and conversion - [100%] [avg time: 1.57 ms] [children: 79.9%] [unaccounted: 20.1%]
21:39:28: | flush - [76.8%] [avg time: 1.205 ms]
21:39:28: | CopyResource - [2.87%] [avg time: 0.045 ms]
21:39:28: | conversion to 4:2:0 - [0.255%] [avg time: 0.004 ms]
21:39:28: Convert444Threads - [100%] [avg time: 0.955 ms] [children: 98%] [unaccounted: 1.99%]
21:39:28: | Convert444toNV12 - [98%] [avg time: 0.936 ms]
21:39:28: encoder thread frame - [100%] [avg time: 3.26 ms]
21:39:28: ==============================================================
21:39:28: 
21:39:28: 
21:39:28: Profiler CPU results:
21:39:28: 
21:39:28: ==============================================================
21:39:28: video thread frame - [cpu time: avg 0.173 ms, total 5718.75 ms] [avg calls per frame: 1]
21:39:28: | scene->Preprocess - [cpu time: avg 0.123 ms, total 4062.5 ms] [avg calls per frame: 1]
21:39:28: GPU download and conversion - [cpu time: avg 0.156 ms, total 5156.25 ms] [avg calls per frame: 1]
21:39:28: | flush - [cpu time: avg 0.129 ms, total 4265.63 ms] [avg calls per frame: 1]
21:39:28: | CopyResource - [cpu time: avg 0.007 ms, total 250 ms] [avg calls per frame: 1]
21:39:28: | conversion to 4:2:0 - [cpu time: avg 0.003 ms, total 109.375 ms] [avg calls per frame: 1]
21:39:28: Convert444Threads - [cpu time: avg 0.643 ms, total 42406.3 ms] [avg calls per frame: 2]
21:39:28: | Convert444toNV12 - [cpu time: avg 0.638 ms, total 42093.8 ms] [avg calls per frame: 2]
21:39:28: encoder thread frame - [cpu time: avg 0.975 ms, total 33437.5 ms] [avg calls per frame: 1]
21:39:28: ==============================================================
21:39:28: 
21:39:28: =====Stream End: 2013-10-08, 21:39:28=================================================
21:40:02: Memory Leaks Were Detected.
21:40:02:

Thanks in advance, Log 2 and 3 following

Krxz · Oct 8, 2013

Log2: Probably more Payday and some League of legends

Code:

18:57:05: Open Broadcaster Software v0.571b - 64bit (　^ω^)
18:57:05: -------------------------------
18:57:05: CPU Name: Intel(R) Core(TM) i5-3570K CPU @ 3.40GHz
18:57:05: CPU Speed: 3400MHz
18:57:05: Physical Memory:  8140MB Total, 5636MB Free
18:57:05: stepping id: 9, model 10, family 6, type 0, extmodel 1, extfamily 0, HTT 1, logical cores 4, total cores 4
18:57:05: monitor 1: pos={0, 0}, size={1920, 1080}
18:57:05: Windows Version: 6.2 Build 9200 
18:57:05: Aero is Enabled
18:57:05: -------------------------------
18:57:05: OBS Modules:
18:57:05: Base Address     Module
18:57:05: 000000006BD50000 OBS.exe
18:57:05: 00000000BA3C0000 OBSApi.dll
18:57:05: 00000000C5420000 DShowPlugin.dll
18:57:05: 00000000C1F60000 GraphicsCapture.dll
18:57:05: 00000000C7370000 NoiseGate.dll
18:57:05: 00000000C1A80000 PSVPlugin.dll
18:57:05: 00000000C1A60000 scenesw.dll
18:57:05: 00000000C12A0000 WebSocketAPIPlugin.dll
18:57:05: ------------------------------------------
18:57:05: Adapter 1
18:57:05:   Video Adapter: AMD Radeon HD 7900 Series
18:57:05:   Video Adapter Dedicated Video Memory: 3202523136
18:57:05:   Video Adapter Shared System Memory: 3999481856
18:57:05: Using fixed low latency mode, factor 20
18:57:05: =====Stream Start: 2013-10-08, 18:57:05===============================================
18:57:05:   Multithreaded optimizations: On
18:57:05:   Base resolution: 1920x1080
18:57:05:   Output resolution: 1280x720
18:57:05: ------------------------------------------
18:57:05: Loading up D3D10 on AMD Radeon HD 7900 Series...
18:57:05: ------------------------------------------
18:57:05: Audio Format: 48000hz
18:57:05: Playback device {0.0.0.00000000}.{15192ab2-8b4c-4aef-bf0b-c0713b40d621}
18:57:05: ------------------------------------------
18:57:05: Using desktop audio input: Line 1 (Virtual Audio Cable)
18:57:05: ------------------------------------------
18:57:05: Using auxilary audio input: Microphone (Logitech G35 Headset)
18:57:05: ------------------------------------------
18:57:05: Audio Encoding: AAC
18:57:05:     bitrate: 128
18:57:05: Using text output
18:57:05: Using text output
18:57:05: Using text output
18:57:05: Using text output
18:57:05: Using bitmap image
18:57:05: Using bitmap image
18:57:05: Using bitmap image
18:57:05: Using bitmap image
18:57:05: Using bitmap image
18:57:06: Using bitmap image
18:57:06: Using Window Capture
18:57:06: Using Window Capture
18:57:06: Using bitmap image
18:57:06: Using bitmap image
18:57:06: ------------------------------------------
18:57:06: Video Encoding: x264
18:57:06:     fps: 60
18:57:06:     width: 1280, height: 720
18:57:06:     preset: veryfast
18:57:06:     profile: high
18:57:06:     keyint: 120
18:57:06:     CBR: yes
18:57:06:     CFR: no
18:57:06:     max bitrate: 2800
18:57:06:     buffer size: 2800
18:57:06: ------------------------------------------
18:57:06: MMDeviceAudioSource: Frequency for device 'Line 1 (Virtual Audio Cable)' is 352800, samples per sec is 44100
18:57:06: MMDeviceAudioSource: Frequency for device 'Microphone (Logitech G35 Headset)' is 176400, samples per sec is 44100
18:57:07: Using RTMP service: Twitch / Justin.tv
18:57:07:   Server selection: rtmp://live-ams.justin.tv/app
18:57:07:   Interface: Qualcomm Atheros AR8161 PCI-E Gigabit Ethernet Controller (NDIS 6.30) (ethernet, 100 mbps)
18:57:08: Completed handshake with rtmp://live-ams.justin.tv/app in 429 ms.
18:57:09: SO_SNDBUF was at 81920
18:57:09: SO_SNDBUF is now 65536
19:03:49: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:03:49:   New Scene
19:03:49: Using text output
19:03:49: Using bitmap image
19:03:49: Using graphics capture
19:03:52: SharedTexCapture hooked
19:04:17: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:04:17:   New Scene
19:04:33: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:04:33:   New Scene
19:04:33: Using graphics capture
19:04:33: SharedTexCapture hooked
19:04:34: GraphicsCaptureSource::NewCapture:  eliminating old capture
19:04:34: SharedTexCapture hooked
19:35:07: Exit signal received, terminating capture
19:35:08: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:35:08:   New Scene
19:42:36: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:42:36:   New Scene
19:42:36: Using graphics capture
19:42:40: SharedTexCapture hooked
19:42:49: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:42:49:   New Scene
19:42:55: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:42:55:   New Scene
19:42:55: Using graphics capture
Warning -- SharedTexCapture::Init: Could not open file mapping: 2
19:42:56: SharedTexCapture hooked
19:43:25: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:43:25:   New Scene
19:44:46: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:44:46:   New Scene
19:44:46: Using graphics capture
19:44:53: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:44:53:   New Scene
19:45:03: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:45:03:   New Scene
19:45:03: Using graphics capture
19:45:08: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:45:08:   New Scene
19:45:09: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:45:09:   New Scene
19:45:09: Using graphics capture
19:45:09: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:45:09:   New Scene
19:45:11: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:45:11:   New Scene
19:45:11: Using graphics capture
19:45:16: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:45:16:   New Scene
19:45:19: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:45:19:   New Scene
19:45:19: Using graphics capture
19:45:19: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:45:19:   New Scene
19:45:28: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:45:28:   New Scene
19:45:28: Using graphics capture
19:45:30: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:45:30:   New Scene
19:47:23: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:47:23:   New Scene
19:47:23: Using graphics capture
19:47:25: SharedTexCapture hooked
19:47:42: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:47:42:   New Scene
19:47:50: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:47:50:   New Scene
19:47:50: Using graphics capture
Warning -- SharedTexCapture::Init: Could not open file mapping: 2
19:47:52: SharedTexCapture hooked
19:49:55: RTMPPublisher::Socketloop: Increasing send buffer to ISB 131072 (buffer: 18191 / 374784)
19:50:51: Total frames encoded: 193498, total frames duplicated: 1344 (0.69%)
19:50:51: Number of frames skipped due to encoder lag: 110 (0.06%)
19:50:51: Total frames rendered: 193272, number of late frames: 82 (0.04%) (it's okay for some frames to be late)
19:50:51: RTMPPublisher::SocketLoop: Graceful loop exit
19:50:52: Average send payload: 18780 bytes, average send interval: 51 ms
19:50:52: Number of times waited to send: 35, Waited for a total of 130858 bytes
19:50:52: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
19:50:52: Number of bytes sent: 1183634372
19:50:52: 
19:50:52: Profiler time results:
19:50:52: 
19:50:52: ==============================================================
19:50:52: video thread frame - [100%] [avg time: 0.639 ms] [children: 81.7%] [unaccounted: 18.3%]
19:50:52: | scene->Preprocess - [81.7%] [avg time: 0.522 ms]
19:50:52: GPU download and conversion - [100%] [avg time: 1.069 ms] [children: 88.5%] [unaccounted: 11.5%]
19:50:52: | flush - [87.1%] [avg time: 0.931 ms]
19:50:52: | CopyResource - [1.12%] [avg time: 0.012 ms]
19:50:52: | conversion to 4:2:0 - [0.281%] [avg time: 0.003 ms]
19:50:52: Convert444Threads - [100%] [avg time: 0.867 ms] [children: 98.6%] [unaccounted: 1.38%]
19:50:52: | Convert444toNV12 - [98.6%] [avg time: 0.855 ms]
19:50:52: encoder thread frame - [100%] [avg time: 1.405 ms]
19:50:52: ==============================================================
19:50:52: 
19:50:52: 
19:50:52: Profiler CPU results:
19:50:52: 
19:50:52: ==============================================================
19:50:52: video thread frame - [cpu time: avg 0.075 ms, total 14640.6 ms] [avg calls per frame: 1]
19:50:52: | scene->Preprocess - [cpu time: avg 0.042 ms, total 8218.75 ms] [avg calls per frame: 1]
19:50:52: GPU download and conversion - [cpu time: avg 0.213 ms, total 41281.3 ms] [avg calls per frame: 1]
19:50:52: | flush - [cpu time: avg 0.203 ms, total 39234.4 ms] [avg calls per frame: 1]
19:50:52: | CopyResource - [cpu time: avg 0.003 ms, total 593.75 ms] [avg calls per frame: 1]
19:50:52: | conversion to 4:2:0 - [cpu time: avg 0 ms, total 171.875 ms] [avg calls per frame: 1]
19:50:52: Convert444Threads - [cpu time: avg 0.75 ms, total 290234 ms] [avg calls per frame: 2]
19:50:52: | Convert444toNV12 - [cpu time: avg 0.747 ms, total 289063 ms] [avg calls per frame: 2]
19:50:52: encoder thread frame - [cpu time: avg 0.837 ms, total 162094 ms] [avg calls per frame: 1]
19:50:52: ==============================================================
19:50:52: 
19:50:52: =====Stream End: 2013-10-08, 19:50:52=================================================
20:23:45: =====Stream Start: 2013-10-08, 20:23:45===============================================
20:23:45:   Multithreaded optimizations: On
20:23:45:   Base resolution: 1920x1080
20:23:45:   Output resolution: 1280x720
20:23:45: ------------------------------------------
20:23:45: Loading up D3D10 on AMD Radeon HD 7900 Series...
20:23:45: ------------------------------------------
20:23:45: Audio Format: 48000hz
20:23:45: Playback device {0.0.0.00000000}.{15192ab2-8b4c-4aef-bf0b-c0713b40d621}
20:23:45: ------------------------------------------
20:23:45: Using desktop audio input: Line 1 (Virtual Audio Cable)
20:23:45: ------------------------------------------
20:23:45: Using auxilary audio input: Microphone (Logitech G35 Headset)
20:23:45: ------------------------------------------
20:23:45: Audio Encoding: AAC
20:23:45:     bitrate: 128
20:23:45: ------------------------------------------
20:23:45: Video Encoding: x264
20:23:45:     fps: 60
20:23:45:     width: 1280, height: 720
20:23:45:     preset: veryfast
20:23:45:     profile: high
20:23:45:     keyint: 120
20:23:45:     CBR: yes
20:23:45:     CFR: no
20:23:45:     max bitrate: 2800
20:23:45:     buffer size: 2800
20:23:45: ------------------------------------------
20:23:45: MMDeviceAudioSource: Frequency for device 'Line 1 (Virtual Audio Cable)' is 352800, samples per sec is 44100
20:23:45: MMDeviceAudioSource: Frequency for device 'Microphone (Logitech G35 Headset)' is 176400, samples per sec is 44100
20:23:59: Using graphics capture
20:23:59: SharedTexCapture hooked
20:24:02: GraphicsCaptureSource::NewCapture:  eliminating old capture
20:24:02: SharedTexCapture hooked
20:24:22: Using Window Capture
20:24:37: GraphicsCaptureSource::NewCapture:  eliminating old capture
20:24:37: SharedTexCapture hooked
20:26:46: Using text output
20:27:02: Using text output
20:27:24: GraphicsCaptureSource::NewCapture:  eliminating old capture
20:27:24: SharedTexCapture hooked
20:33:03: GraphicsCaptureSource::NewCapture:  eliminating old capture
20:33:03: SharedTexCapture hooked
20:34:51: Total frames encoded: 39938, total frames duplicated: 2542 (6.36%)
20:34:51: Number of frames skipped due to encoder lag: 1343 (3.36%)
20:34:51: Total frames rendered: 38319, number of late frames: 337 (0.88%) (it's okay for some frames to be late)
20:34:52: 
20:34:52: Profiler time results:
20:34:52: 
20:34:52: ==============================================================
20:34:52: video thread frame - [100%] [avg time: 0.832 ms] [children: 64.7%] [unaccounted: 35.3%]
20:34:52: | scene->Preprocess - [64.7%] [avg time: 0.538 ms]
20:34:52: GPU download and conversion - [100%] [avg time: 1.119 ms] [children: 90.2%] [unaccounted: 9.83%]
20:34:52: | flush - [85.8%] [avg time: 0.96 ms]
20:34:52: | CopyResource - [3.75%] [avg time: 0.042 ms]
20:34:52: | conversion to 4:2:0 - [0.626%] [avg time: 0.007 ms]
20:34:52: Convert444Threads - [100%] [avg time: 0.865 ms] [children: 99.1%] [unaccounted: 0.925%]
20:34:52: | Convert444toNV12 - [99.1%] [avg time: 0.857 ms]
20:34:52: encoder thread frame - [100%] [avg time: 2.982 ms]
20:34:52: ==============================================================
20:34:52: 
20:34:52: 
20:34:52: Profiler CPU results:
20:34:52: 
20:34:52: ==============================================================
20:34:52: video thread frame - [cpu time: avg 0.105 ms, total 4046.88 ms] [avg calls per frame: 1]
20:34:52: | scene->Preprocess - [cpu time: avg 0.065 ms, total 2515.63 ms] [avg calls per frame: 1]
20:34:52: GPU download and conversion - [cpu time: avg 0.086 ms, total 3328.13 ms] [avg calls per frame: 1]
20:34:52: | flush - [cpu time: avg 0.072 ms, total 2765.63 ms] [avg calls per frame: 1]
20:34:52: | CopyResource - [cpu time: avg 0.004 ms, total 187.5 ms] [avg calls per frame: 1]
20:34:52: | conversion to 4:2:0 - [cpu time: avg 0.001 ms, total 46.875 ms] [avg calls per frame: 1]
20:34:52: Convert444Threads - [cpu time: avg 0.682 ms, total 52265.6 ms] [avg calls per frame: 2]
20:34:52: | Convert444toNV12 - [cpu time: avg 0.678 ms, total 51953.1 ms] [avg calls per frame: 2]
20:34:52: encoder thread frame - [cpu time: avg 1.067 ms, total 42609.4 ms] [avg calls per frame: 1]
20:34:52: ==============================================================
20:34:52: 
20:34:52: =====Stream End: 2013-10-08, 20:34:52=================================================
20:35:28: Using fixed low latency mode, factor 20
20:35:28: =====Stream Start: 2013-10-08, 20:35:28===============================================
20:35:28:   Multithreaded optimizations: On
20:35:28:   Base resolution: 1920x1080
20:35:28:   Output resolution: 1280x720
20:35:28: ------------------------------------------
20:35:28: Loading up D3D10 on AMD Radeon HD 7900 Series...
20:35:28: ------------------------------------------
20:35:28: Audio Format: 48000hz
20:35:28: Playback device {0.0.0.00000000}.{15192ab2-8b4c-4aef-bf0b-c0713b40d621}
20:35:28: ------------------------------------------
20:35:28: Using desktop audio input: Line 1 (Virtual Audio Cable)
20:35:28: ------------------------------------------
20:35:28: Using auxilary audio input: Microphone (Logitech G35 Headset)
20:35:28: ------------------------------------------
20:35:28: Audio Encoding: AAC
20:35:28:     bitrate: 128
20:35:28: Using text output
20:35:28: Using text output
20:35:28: Using Window Capture
20:35:28: Using graphics capture
20:35:29: ------------------------------------------
20:35:29: Video Encoding: x264
20:35:29:     fps: 60
20:35:29:     width: 1280, height: 720
20:35:29:     preset: veryfast
20:35:29:     profile: high
20:35:29:     keyint: 120
20:35:29:     CBR: yes
20:35:29:     CFR: no
20:35:29:     max bitrate: 2800
20:35:29:     buffer size: 2800
20:35:29: ------------------------------------------
20:35:29: MMDeviceAudioSource: Frequency for device 'Line 1 (Virtual Audio Cable)' is 352800, samples per sec is 44100
20:35:29: MMDeviceAudioSource: Frequency for device 'Microphone (Logitech G35 Headset)' is 176400, samples per sec is 44100
20:35:29: SharedTexCapture hooked
20:35:30: Using RTMP service: Twitch / Justin.tv
20:35:30:   Server selection: rtmp://live-ams.justin.tv/app
20:35:30:   Interface: Qualcomm Atheros AR8161 PCI-E Gigabit Ethernet Controller (NDIS 6.30) (ethernet, 100 mbps)
20:35:30: Completed handshake with rtmp://live-ams.justin.tv/app in 429 ms.
20:35:32: SO_SNDBUF was at 81920
20:35:32: SO_SNDBUF is now 65536
20:35:35: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
20:35:35:   New Scene
20:35:35: Using text output
20:35:35: Using text output
20:35:35: Using bitmap image
20:35:35: Using bitmap image
20:35:35: Using bitmap image
20:35:35: Using bitmap image
20:35:35: Using bitmap image
20:35:35: Using bitmap image
20:35:35: Using Window Capture
20:35:35: Using bitmap image
20:35:35: Using bitmap image
20:35:35: PERFORMANCE WARNING: Scene change took 683 ms, maybe some sources should be global sources?
20:39:15: Total frames encoded: 13534, total frames duplicated: 1932 (14.28%)
20:39:15: Total frames rendered: 11745, number of late frames: 1754 (14.93%) (it's okay for some frames to be late)
20:39:15: RTMPPublisher::SocketLoop: Graceful loop exit
20:39:15: Average send payload: 18761 bytes, average send interval: 51 ms
20:39:15: Number of times waited to send: 0, Waited for a total of 0 bytes
20:39:15: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
20:39:15: Number of bytes sent: 81479771
20:39:15: 
20:39:15: Profiler time results:
20:39:15: 
20:39:15: ==============================================================
20:39:15: video thread frame - [100%] [avg time: 0.995 ms] [children: 83.8%] [unaccounted: 16.2%]
20:39:15: | scene->Preprocess - [83.8%] [avg time: 0.834 ms]
20:39:15: GPU download and conversion - [100%] [avg time: 12.74 ms] [children: 4.58%] [unaccounted: 95.4%]
20:39:15: | flush - [2.87%] [avg time: 0.366 ms]
20:39:15: | CopyResource - [1.51%] [avg time: 0.193 ms]
20:39:15: | conversion to 4:2:0 - [0.188%] [avg time: 0.024 ms]
20:39:15: Convert444Threads - [100%] [avg time: 0.792 ms] [children: 98.5%] [unaccounted: 1.52%]
20:39:15: | Convert444toNV12 - [98.5%] [avg time: 0.78 ms]
20:39:15: encoder thread frame - [100%] [avg time: 1.346 ms]
20:39:15: ==============================================================
20:39:15: 
20:39:15: 
20:39:15: Profiler CPU results:
20:39:15: 
20:39:15: ==============================================================
20:39:15: video thread frame - [cpu time: avg 0.266 ms, total 3125 ms] [avg calls per frame: 1]
20:39:15: | scene->Preprocess - [cpu time: avg 0.182 ms, total 2140.63 ms] [avg calls per frame: 1]
20:39:15: GPU download and conversion - [cpu time: avg 0.256 ms, total 3015.63 ms] [avg calls per frame: 1]
20:39:15: | flush - [cpu time: avg 0.196 ms, total 2312.5 ms] [avg calls per frame: 1]
20:39:15: | CopyResource - [cpu time: avg 0.013 ms, total 156.25 ms] [avg calls per frame: 1]
20:39:15: | conversion to 4:2:0 - [cpu time: avg 0.002 ms, total 31.25 ms] [avg calls per frame: 1]
20:39:15: Convert444Threads - [cpu time: avg 0.736 ms, total 17250 ms] [avg calls per frame: 2]
20:39:15: | Convert444toNV12 - [cpu time: avg 0.733 ms, total 17187.5 ms] [avg calls per frame: 2]
20:39:15: encoder thread frame - [cpu time: avg 0.932 ms, total 12593.8 ms] [avg calls per frame: 1]
20:39:15: ==============================================================
20:39:15: 
20:39:15: =====Stream End: 2013-10-08, 20:39:15=================================================


Last game capture log:
2013-10-08, 20:23:59: we're booting up: 
20:23:59: D3D9 Present
20:23:59: D3D9EndScene called
20:23:59: D3DPRESENT_PARAMETERS {
20:23:59: 	BackBufferWidth: 1920
20:23:59: 	BackBufferHeight: 1080
20:23:59: 	BackBufferFormat: D3DFMT_A8R8G8B8
20:23:59: 	BackBufferCount: 1
20:23:59: 	MultiSampleType: D3DMULTISAMPLE_NONE
20:23:59: 	MultiSampleQuality: 0
20:23:59: 	SwapEffect: D3DSWAPEFFECT_FLIP
20:23:59: 	hDeviceWindow: 2821136
20:23:59: 	Windowed: false
20:23:59: 	EnableAutoDepthStencil: false
20:23:59: 	AutoDepthStencilFormat: D3DFMT_UNKNOWN
20:23:59: 	Flags: None
20:23:59: 	FullScreen_RefreshRateInHz: 50
20:23:59: 	PresentationInterval: 2147483648
20:23:59: };
20:23:59: successfully set up d3d9 hooks
20:23:59: D3D9Present called
20:23:59: (half life scientist) everything..  seems to be in order
20:23:59: DoD3D9GPUHook: success
20:23:59: D3DSURFACE_DESC {
20:23:59: 	Format: D3DFMT_A8R8G8B8
20:23:59: 	Type: D3DRTYPE_SURFACE
20:23:59: 	Usage: D3DUSAGE_RENDERTARGET 
20:23:59: 	Pool: D3DPOOL_DEFAULT
20:23:59: 	MultiSampleType: D3DMULTISAMPLE_NONE
20:23:59: 	MultiSampleQuality: 0
20:23:59: 	Width: 1920
20:23:59: 	Height: 1080
20:23:59: };
20:23:59: successfully capturing d3d9 frames via GPU
20:24:01: D3D9Reset called
20:24:01: ---------------------- Cleared D3D9 Capture ----------------------
20:24:02: successfully set up d3d9 hooks
20:24:02: D3D9EndScene called
20:24:02: D3D9Present called
20:24:02: DoD3D9GPUHook: success
20:24:02: D3DSURFACE_DESC {
20:24:02: 	Format: D3DFMT_A8R8G8B8
20:24:02: 	Type: D3DRTYPE_SURFACE
20:24:02: 	Usage: D3DUSAGE_RENDERTARGET 
20:24:02: 	Pool: D3DPOOL_DEFAULT
20:24:02: 	MultiSampleType: D3DMULTISAMPLE_NONE
20:24:02: 	MultiSampleQuality: 0
20:24:02: 	Width: 1920
20:24:02: 	Height: 1080
20:24:02: };
20:24:02: successfully capturing d3d9 frames via GPU
20:24:36: D3D9Reset called
20:24:36: ---------------------- Cleared D3D9 Capture ----------------------
20:24:37: successfully set up d3d9 hooks
20:24:37: D3D9EndScene called
20:24:37: D3D9Present called
20:24:37: DoD3D9GPUHook: success
20:24:37: D3DSURFACE_DESC {
20:24:37: 	Format: D3DFMT_A8R8G8B8
20:24:37: 	Type: D3DRTYPE_SURFACE
20:24:37: 	Usage: D3DUSAGE_RENDERTARGET 
20:24:37: 	Pool: D3DPOOL_DEFAULT
20:24:37: 	MultiSampleType: D3DMULTISAMPLE_NONE
20:24:37: 	MultiSampleQuality: 0
20:24:37: 	Width: 1920
20:24:37: 	Height: 1080
20:24:37: };
20:24:37: successfully capturing d3d9 frames via GPU
20:27:16: D3D9Reset called
20:27:16: ---------------------- Cleared D3D9 Capture ----------------------
20:27:16: successfully set up d3d9 hooks
20:27:23: D3D9EndScene called
20:27:24: D3D9Present called
20:27:24: DoD3D9GPUHook: success
20:27:24: D3DSURFACE_DESC {
20:27:24: 	Format: D3DFMT_A8R8G8B8
20:27:24: 	Type: D3DRTYPE_SURFACE
20:27:24: 	Usage: D3DUSAGE_RENDERTARGET 
20:27:24: 	Pool: D3DPOOL_DEFAULT
20:27:24: 	MultiSampleType: D3DMULTISAMPLE_NONE
20:27:24: 	MultiSampleQuality: 0
20:27:24: 	Width: 1920
20:27:24: 	Height: 1080
20:27:24: };
20:27:24: successfully capturing d3d9 frames via GPU
20:33:01: D3D9Reset called
20:33:01: ---------------------- Cleared D3D9 Capture ----------------------
20:33:01: successfully set up d3d9 hooks
20:33:03: D3D9EndScene called
20:33:03: D3D9Present called
20:33:03: DoD3D9GPUHook: success
20:33:03: D3DSURFACE_DESC {
20:33:03: 	Format: D3DFMT_A8R8G8B8
20:33:03: 	Type: D3DRTYPE_SURFACE
20:33:03: 	Usage: D3DUSAGE_RENDERTARGET 
20:33:03: 	Pool: D3DPOOL_DEFAULT
20:33:03: 	MultiSampleType: D3DMULTISAMPLE_NONE
20:33:03: 	MultiSampleQuality: 0
20:33:03: 	Width: 1920
20:33:03: 	Height: 1080
20:33:03: };
20:33:03: successfully capturing d3d9 frames via GPU
20:34:51: ---------------------- Cleared D3D9 Capture ----------------------
20:34:51: D3D9EndScene called
20:34:51: D3D9Present called
20:35:29: received restart event, capturing
20:35:29: DoD3D9GPUHook: success
20:35:29: D3DSURFACE_DESC {
20:35:29: 	Format: D3DFMT_A8R8G8B8
20:35:29: 	Type: D3DRTYPE_SURFACE
20:35:29: 	Usage: D3DUSAGE_RENDERTARGET 
20:35:29: 	Pool: D3DPOOL_DEFAULT
20:35:29: 	MultiSampleType: D3DMULTISAMPLE_NONE
20:35:29: 	MultiSampleQuality: 0
20:35:29: 	Width: 1920
20:35:29: 	Height: 1080
20:35:29: };
20:35:29: successfully capturing d3d9 frames via GPU
20:35:35: ---------------------- Cleared D3D9 Capture ----------------------
20:35:35: D3D9EndScene called
20:35:35: D3D9Present called
20:35:40: D3D9Reset called
20:35:40: ---------------------- Cleared D3D9 Capture ----------------------
20:35:41: successfully set up d3d9 hooks
20:35:42: D3D9EndScene called
20:35:42: D3D9Present called

20:39:16: Memory Leaks Were Detected.
20:39:16:

Log3: League of Legends

Code:

17:22:36: Open Broadcaster Software v0.571b - 64bit (　^ω^)
17:22:36: -------------------------------
17:22:36: CPU Name: Intel(R) Core(TM) i5-3570K CPU @ 3.40GHz
17:22:36: CPU Speed: 3400MHz
17:22:36: Physical Memory:  8140MB Total, 5503MB Free
17:22:36: stepping id: 9, model 10, family 6, type 0, extmodel 1, extfamily 0, HTT 1, logical cores 4, total cores 4
17:22:36: monitor 1: pos={0, 0}, size={1920, 1080}
17:22:36: Windows Version: 6.2 Build 9200 
17:22:36: Aero is Enabled
17:22:36: -------------------------------
17:22:36: OBS Modules:
17:22:36: Base Address     Module
17:22:36: 000000006BD50000 OBS.exe
17:22:36: 00000000BA3C0000 OBSApi.dll
17:22:36: 00000000C5420000 DShowPlugin.dll
17:22:36: 00000000C7370000 GraphicsCapture.dll
17:22:36: 00000000C1F70000 NoiseGate.dll
17:22:36: 00000000C1A80000 PSVPlugin.dll
17:22:36: 00000000C1A60000 scenesw.dll
17:22:36: 00000000C12A0000 WebSocketAPIPlugin.dll
17:22:36: ------------------------------------------
17:22:36: Adapter 1
17:22:36:   Video Adapter: AMD Radeon HD 7900 Series
17:22:36:   Video Adapter Dedicated Video Memory: 3202523136
17:22:36:   Video Adapter Shared System Memory: 3999481856
17:22:36: =====Stream Start: 2013-10-08, 17:22:36===============================================
17:22:36:   Multithreaded optimizations: On
17:22:36:   Base resolution: 1920x1080
17:22:36:   Output resolution: 1280x720
17:22:36: ------------------------------------------
17:22:36: Loading up D3D10 on AMD Radeon HD 7900 Series...
17:22:36: ------------------------------------------
17:22:36: Audio Format: 48000hz
17:22:36: Playback device {0.0.0.00000000}.{15192ab2-8b4c-4aef-bf0b-c0713b40d621}
17:22:36: ------------------------------------------
17:22:36: Using desktop audio input: Line 1 (Virtual Audio Cable)
17:22:36: ------------------------------------------
17:22:36: Using auxilary audio input: Microphone (Logitech G35 Headset)
17:22:36: ------------------------------------------
17:22:36: Audio Encoding: AAC
17:22:36:     bitrate: 128
17:22:36: Using text output
17:22:36: Using text output
17:22:36: Using text output
17:22:36: Using text output
17:22:36: Using bitmap image
17:22:36: Using bitmap image
17:22:36: Using bitmap image
17:22:36: Using bitmap image
17:22:36: Using bitmap image
17:22:36: Using bitmap image
17:22:36: Using Window Capture
17:22:36: Using Window Capture
17:22:36: Using bitmap image
17:22:36: Using bitmap image
17:22:36: ------------------------------------------
17:22:36: Video Encoding: x264
17:22:36:     fps: 60
17:22:36:     width: 1280, height: 720
17:22:36:     preset: veryfast
17:22:36:     profile: high
17:22:36:     keyint: 120
17:22:36:     CBR: yes
17:22:36:     CFR: no
17:22:36:     max bitrate: 2800
17:22:36:     buffer size: 2800
17:22:36: ------------------------------------------
17:22:36: MMDeviceAudioSource: Frequency for device 'Line 1 (Virtual Audio Cable)' is 352800, samples per sec is 44100
17:22:36: MMDeviceAudioSource: Frequency for device 'Microphone (Logitech G35 Headset)' is 176400, samples per sec is 44100
17:24:01: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:24:01:   New Scene
17:24:01: Using text output
17:24:01: Using bitmap image
17:24:01: Using graphics capture
17:26:24: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:26:24:   New Scene
17:27:01: Total frames encoded: 15829, total frames duplicated: 276 (1.74%)
17:27:01: Total frames rendered: 15813, number of late frames: 22 (0.14%) (it's okay for some frames to be late)
17:27:01: 
17:27:01: Profiler time results:
17:27:01: 
17:27:01: ==============================================================
17:27:01: video thread frame - [100%] [avg time: 1.388 ms] [children: 50.4%] [unaccounted: 49.6%]
17:27:01: | scene->Preprocess - [50.4%] [avg time: 0.699 ms]
17:27:01: GPU download and conversion - [100%] [avg time: 0.283 ms] [children: 91.5%] [unaccounted: 8.48%]
17:27:01: | flush - [76.7%] [avg time: 0.217 ms]
17:27:01: | CopyResource - [4.95%] [avg time: 0.014 ms]
17:27:01: | conversion to 4:2:0 - [9.89%] [avg time: 0.028 ms]
17:27:01: Convert444Threads - [100%] [avg time: 0.895 ms] [children: 99.4%] [unaccounted: 0.559%]
17:27:01: | Convert444toNV12 - [99.4%] [avg time: 0.89 ms]
17:27:01: encoder thread frame - [100%] [avg time: 1.408 ms]
17:27:01: ==============================================================
17:27:01: 
17:27:01: 
17:27:01: Profiler CPU results:
17:27:01: 
17:27:01: ==============================================================
17:27:01: video thread frame - [cpu time: avg 0.378 ms, total 5984.38 ms] [avg calls per frame: 1]
17:27:01: | scene->Preprocess - [cpu time: avg 0.114 ms, total 1812.5 ms] [avg calls per frame: 1]
17:27:01: GPU download and conversion - [cpu time: avg 0.155 ms, total 2453.13 ms] [avg calls per frame: 1]
17:27:01: | flush - [cpu time: avg 0.109 ms, total 1734.38 ms] [avg calls per frame: 1]
17:27:01: | CopyResource - [cpu time: avg 0.019 ms, total 312.5 ms] [avg calls per frame: 1]
17:27:01: | conversion to 4:2:0 - [cpu time: avg 0.002 ms, total 46.875 ms] [avg calls per frame: 1]
17:27:01: Convert444Threads - [cpu time: avg 0.852 ms, total 26921.9 ms] [avg calls per frame: 2]
17:27:01: | Convert444toNV12 - [cpu time: avg 0.844 ms, total 26671.9 ms] [avg calls per frame: 2]
17:27:01: encoder thread frame - [cpu time: avg 0.786 ms, total 12437.5 ms] [avg calls per frame: 1]
17:27:01: ==============================================================
17:27:01: 
17:27:01: =====Stream End: 2013-10-08, 17:27:01=================================================
17:27:02: Using fixed low latency mode, factor 20
17:27:02: =====Stream Start: 2013-10-08, 17:27:02===============================================
17:27:02:   Multithreaded optimizations: On
17:27:02:   Base resolution: 1920x1080
17:27:02:   Output resolution: 1280x720
17:27:02: ------------------------------------------
17:27:02: Loading up D3D10 on AMD Radeon HD 7900 Series...
17:27:02: ------------------------------------------
17:27:02: Audio Format: 48000hz
17:27:02: Playback device {0.0.0.00000000}.{15192ab2-8b4c-4aef-bf0b-c0713b40d621}
17:27:02: ------------------------------------------
17:27:02: Using desktop audio input: Line 1 (Virtual Audio Cable)
17:27:02: ------------------------------------------
17:27:02: Using auxilary audio input: Microphone (Logitech G35 Headset)
17:27:02: ------------------------------------------
17:27:02: Audio Encoding: AAC
17:27:02:     bitrate: 128
17:27:02: Using text output
17:27:02: Using text output
17:27:02: Using text output
17:27:02: Using text output
17:27:03: Using bitmap image
17:27:03: Using bitmap image
17:27:03: Using bitmap image
17:27:03: Using bitmap image
17:27:03: Using bitmap image
17:27:03: Using bitmap image
17:27:03: Using Window Capture
17:27:03: Using Window Capture
17:27:03: Using bitmap image
17:27:03: Using bitmap image
17:27:03: ------------------------------------------
17:27:03: Video Encoding: x264
17:27:03:     fps: 60
17:27:03:     width: 1280, height: 720
17:27:03:     preset: veryfast
17:27:03:     profile: high
17:27:03:     keyint: 120
17:27:03:     CBR: yes
17:27:03:     CFR: no
17:27:03:     max bitrate: 2800
17:27:03:     buffer size: 2800
17:27:03: ------------------------------------------
17:27:03: MMDeviceAudioSource: Frequency for device 'Line 1 (Virtual Audio Cable)' is 352800, samples per sec is 44100
17:27:03: MMDeviceAudioSource: Frequency for device 'Microphone (Logitech G35 Headset)' is 176400, samples per sec is 44100
17:27:04: Using RTMP service: Twitch / Justin.tv
17:27:04:   Server selection: rtmp://live-ams.justin.tv/app
17:27:04:   Interface: Qualcomm Atheros AR8161 PCI-E Gigabit Ethernet Controller (NDIS 6.30) (ethernet, 100 mbps)
17:27:05: Completed handshake with rtmp://live-ams.justin.tv/app in 427 ms.
17:27:06: SO_SNDBUF was at 81920
17:27:06: SO_SNDBUF is now 65536
17:27:19: Total frames encoded: 900, total frames duplicated: 0 (0.00%)
17:27:19: Total frames rendered: 908, number of late frames: 1 (0.11%) (it's okay for some frames to be late)
17:27:19: RTMPPublisher::SocketLoop: Graceful loop exit
17:27:19: Average send payload: 18779 bytes, average send interval: 51 ms
17:27:19: Number of times waited to send: 0, Waited for a total of 0 bytes
17:27:19: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
17:27:19: Number of bytes sent: 4507127
17:27:19: 
17:27:19: Profiler time results:
17:27:19: 
17:27:19: ==============================================================
17:27:19: video thread frame - [100%] [avg time: 1.341 ms] [children: 42.3%] [unaccounted: 57.7%]
17:27:19: | scene->Preprocess - [42.3%] [avg time: 0.567 ms]
17:27:19: GPU download and conversion - [100%] [avg time: 0.579 ms] [children: 85.5%] [unaccounted: 14.5%]
17:27:19: | flush - [81.3%] [avg time: 0.471 ms]
17:27:19: | CopyResource - [3.63%] [avg time: 0.021 ms]
17:27:19: | conversion to 4:2:0 - [0.518%] [avg time: 0.003 ms]
17:27:19: Convert444Threads - [100%] [avg time: 1.156 ms] [children: 98.8%] [unaccounted: 1.21%]
17:27:19: | Convert444toNV12 - [98.8%] [avg time: 1.142 ms]
17:27:19: encoder thread frame - [100%] [avg time: 1.763 ms]
17:27:19: ==============================================================
17:27:19: 
17:27:19: 
17:27:19: Profiler CPU results:
17:27:19: 
17:27:19: ==============================================================
17:27:19: video thread frame - [cpu time: avg 0.653 ms, total 593.75 ms] [avg calls per frame: 1]
17:27:19: | scene->Preprocess - [cpu time: avg 0.103 ms, total 93.75 ms] [avg calls per frame: 1]
17:27:19: GPU download and conversion - [cpu time: avg 0.068 ms, total 62.5 ms] [avg calls per frame: 1]
17:27:19: | flush - [cpu time: avg 0.068 ms, total 62.5 ms] [avg calls per frame: 1]
17:27:19: | CopyResource - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
17:27:19: | conversion to 4:2:0 - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
17:27:19: Convert444Threads - [cpu time: avg 0.979 ms, total 1718.75 ms] [avg calls per frame: 2]
17:27:19: | Convert444toNV12 - [cpu time: avg 0.97 ms, total 1703.13 ms] [avg calls per frame: 2]
17:27:19: encoder thread frame - [cpu time: avg 1.159 ms, total 1015.63 ms] [avg calls per frame: 1]
17:27:19: ==============================================================
17:27:19: 
17:27:19: =====Stream End: 2013-10-08, 17:27:19=================================================
17:28:24: Using fixed low latency mode, factor 20
17:28:24: =====Stream Start: 2013-10-08, 17:28:24===============================================
17:28:24:   Multithreaded optimizations: On
17:28:24:   Base resolution: 1920x1080
17:28:24:   Output resolution: 1920x1080
17:28:24: ------------------------------------------
17:28:24: Loading up D3D10 on AMD Radeon HD 7900 Series...
17:28:24: ------------------------------------------
17:28:24: Audio Format: 48000hz
17:28:24: Playback device {0.0.0.00000000}.{15192ab2-8b4c-4aef-bf0b-c0713b40d621}
17:28:24: ------------------------------------------
17:28:24: Using desktop audio input: Line 1 (Virtual Audio Cable)
17:28:24: ------------------------------------------
17:28:24: Using auxilary audio input: Microphone (Logitech G35 Headset)
17:28:24: ------------------------------------------
17:28:24: Audio Encoding: AAC
17:28:24:     bitrate: 128
17:28:24: Using text output
17:28:24: Using text output
17:28:24: Using text output
17:28:24: Using text output
17:28:24: Using bitmap image
17:28:24: Using bitmap image
17:28:24: Using bitmap image
17:28:24: Using bitmap image
17:28:24: Using bitmap image
17:28:24: Using bitmap image
17:28:24: Using Window Capture
17:28:24: Using Window Capture
17:28:24: Using bitmap image
17:28:24: Using bitmap image
17:28:24: ------------------------------------------
17:28:24: Video Encoding: x264
17:28:24:     fps: 30
17:28:24:     width: 1920, height: 1080
17:28:24:     preset: veryfast
17:28:24:     profile: high
17:28:24:     keyint: 60
17:28:24:     CBR: yes
17:28:24:     CFR: no
17:28:24:     max bitrate: 2800
17:28:24:     buffer size: 2800
17:28:24: ------------------------------------------
17:28:24: MMDeviceAudioSource: Frequency for device 'Line 1 (Virtual Audio Cable)' is 352800, samples per sec is 44100
17:28:24: MMDeviceAudioSource: Frequency for device 'Microphone (Logitech G35 Headset)' is 176400, samples per sec is 44100
17:28:26: Using RTMP service: Twitch / Justin.tv
17:28:26:   Server selection: rtmp://live-ams.justin.tv/app
17:28:26:   Interface: Qualcomm Atheros AR8161 PCI-E Gigabit Ethernet Controller (NDIS 6.30) (ethernet, 100 mbps)
17:28:27: Completed handshake with rtmp://live-ams.justin.tv/app in 435 ms.
17:28:28: SO_SNDBUF was at 81920
17:28:28: SO_SNDBUF is now 65536
17:29:36: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:29:36:   New Scene
17:29:36: Using text output
17:29:36: Using bitmap image
17:29:36: Using graphics capture
17:29:39: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:29:39:   New Scene
17:29:46: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:29:46:   New Scene
17:29:46: Using graphics capture
17:29:46: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:29:46:   New Scene
17:29:46: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:29:46:   New Scene
17:29:46: Using graphics capture
17:29:49: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:29:49:   New Scene
17:29:56: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:29:56:   New Scene
17:29:56: Using graphics capture
17:29:56: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:29:56:   New Scene
17:29:58: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:29:58:   New Scene
17:29:58: Using graphics capture
17:30:01: SharedTexCapture hooked
17:30:33: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:30:33:   New Scene
17:30:36: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:30:36:   New Scene
17:30:36: Using graphics capture
Warning -- SharedTexCapture::Init: Could not open file mapping: 2
17:30:36: SharedTexCapture hooked
17:30:45: Exit signal received, terminating capture
17:30:45: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:30:45:   New Scene
17:31:21: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:31:21:   New Scene
17:31:21: Using graphics capture
17:31:23: SharedTexCapture hooked
17:33:41: RTMPPublisher::Socketloop: Increasing send buffer to ISB 131072 (buffer: 0 / 374784)
17:38:08: Exit signal received, terminating capture
17:38:08: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:38:08:   New Scene
17:38:12: Total frames encoded: 17617, total frames duplicated: 86 (0.49%)
17:38:12: Total frames rendered: 17592, number of late frames: 11 (0.06%) (it's okay for some frames to be late)
17:38:13: RTMPPublisher::SocketLoop: Graceful loop exit
17:38:13: Average send payload: 18766 bytes, average send interval: 51 ms
17:38:13: Number of times waited to send: 0, Waited for a total of 0 bytes
17:38:13: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
17:38:13: Number of bytes sent: 214477253
17:38:13: 
17:38:13: Profiler time results:
17:38:13: 
17:38:13: ==============================================================
17:38:13: video thread frame - [100%] [avg time: 0.875 ms] [children: 78.4%] [unaccounted: 21.6%]
17:38:13: | scene->Preprocess - [78.4%] [avg time: 0.686 ms]
17:38:13: GPU download and conversion - [100%] [avg time: 1.585 ms] [children: 88.3%] [unaccounted: 11.7%]
17:38:13: | flush - [87.4%] [avg time: 1.385 ms]
17:38:13: | CopyResource - [0.694%] [avg time: 0.011 ms]
17:38:13: | conversion to 4:2:0 - [0.189%] [avg time: 0.003 ms]
17:38:13: Convert444Threads - [100%] [avg time: 2.141 ms] [children: 99.2%] [unaccounted: 0.794%]
17:38:13: | Convert444toNV12 - [99.2%] [avg time: 2.124 ms]
17:38:13: encoder thread frame - [100%] [avg time: 3.066 ms]
17:38:13: ==============================================================
17:38:13: 
17:38:13: 
17:38:13: Profiler CPU results:
17:38:13: 
17:38:13: ==============================================================
17:38:13: video thread frame - [cpu time: avg 0.122 ms, total 2156.25 ms] [avg calls per frame: 1]
17:38:13: | scene->Preprocess - [cpu time: avg 0.065 ms, total 1156.25 ms] [avg calls per frame: 1]
17:38:13: GPU download and conversion - [cpu time: avg 0.209 ms, total 3687.5 ms] [avg calls per frame: 1]
17:38:13: | flush - [cpu time: avg 0.198 ms, total 3484.38 ms] [avg calls per frame: 1]
17:38:13: | CopyResource - [cpu time: avg 0.003 ms, total 62.5 ms] [avg calls per frame: 1]
17:38:13: | conversion to 4:2:0 - [cpu time: avg 0 ms, total 15.625 ms] [avg calls per frame: 1]
17:38:13: Convert444Threads - [cpu time: avg 1.757 ms, total 61796.9 ms] [avg calls per frame: 2]
17:38:13: | Convert444toNV12 - [cpu time: avg 1.752 ms, total 61609.4 ms] [avg calls per frame: 2]
17:38:13: encoder thread frame - [cpu time: avg 2.342 ms, total 41234.4 ms] [avg calls per frame: 1]
17:38:13: ==============================================================
17:38:13: 
17:38:13: =====Stream End: 2013-10-08, 17:38:13=================================================
17:49:02: Using fixed low latency mode, factor 20
17:49:02: =====Stream Start: 2013-10-08, 17:49:02===============================================
17:49:02:   Multithreaded optimizations: On
17:49:02:   Base resolution: 1920x1080
17:49:02:   Output resolution: 1280x720
17:49:02: ------------------------------------------
17:49:02: Loading up D3D10 on AMD Radeon HD 7900 Series...
17:49:02: ------------------------------------------
17:49:02: Audio Format: 48000hz
17:49:02: Playback device {0.0.0.00000000}.{15192ab2-8b4c-4aef-bf0b-c0713b40d621}
17:49:02: ------------------------------------------
17:49:02: Using desktop audio input: Line 1 (Virtual Audio Cable)
17:49:02: ------------------------------------------
17:49:02: Using auxilary audio input: Microphone (Logitech G35 Headset)
17:49:02: ------------------------------------------
17:49:02: Audio Encoding: AAC
17:49:02:     bitrate: 128
17:49:02: Using text output
17:49:02: Using text output
17:49:02: Using text output
17:49:02: Using text output
17:49:02: Using bitmap image
17:49:02: Using bitmap image
17:49:02: Using bitmap image
17:49:02: Using bitmap image
17:49:02: Using bitmap image
17:49:02: Using bitmap image
17:49:02: Using Window Capture
17:49:02: Using Window Capture
17:49:03: Using bitmap image
17:49:03: Using bitmap image
17:49:03: ------------------------------------------
17:49:03: Video Encoding: x264
17:49:03:     fps: 60
17:49:03:     width: 1280, height: 720
17:49:03:     preset: veryfast
17:49:03:     profile: high
17:49:03:     keyint: 120
17:49:03:     CBR: yes
17:49:03:     CFR: no
17:49:03:     max bitrate: 2800
17:49:03:     buffer size: 2800
17:49:03: ------------------------------------------
17:49:03: MMDeviceAudioSource: Frequency for device 'Line 1 (Virtual Audio Cable)' is 352800, samples per sec is 44100
17:49:03: MMDeviceAudioSource: Frequency for device 'Microphone (Logitech G35 Headset)' is 176400, samples per sec is 44100
17:49:04: Using RTMP service: Twitch / Justin.tv
17:49:04:   Server selection: rtmp://live-ams.justin.tv/app
17:49:04:   Interface: Qualcomm Atheros AR8161 PCI-E Gigabit Ethernet Controller (NDIS 6.30) (ethernet, 100 mbps)
17:49:04: Completed handshake with rtmp://live-ams.justin.tv/app in 397 ms.
17:49:05: SO_SNDBUF was at 81920
17:49:05: SO_SNDBUF is now 65536
17:50:00: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:50:00:   New Scene
17:50:00: Using text output
17:50:00: Using bitmap image
17:50:00: Using graphics capture
17:50:02: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:50:02:   New Scene
17:50:08: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:50:08:   New Scene
17:50:08: Using graphics capture
17:50:12: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:50:12:   New Scene
17:50:18: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:50:18:   New Scene
17:50:18: Using graphics capture
17:50:23: SharedTexCapture hooked
17:50:48: Exit signal received, terminating capture
17:50:49: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:50:49:   New Scene
17:51:22: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:51:22:   New Scene
17:51:22: Using graphics capture
17:51:26: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:51:26:   New Scene
17:52:47: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:52:47:   New Scene
17:52:47: Using graphics capture
17:52:51: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:52:51:   New Scene
17:53:03: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:53:03:   New Scene
17:53:03: Using graphics capture
17:53:06: SharedTexCapture hooked
17:53:24: Capture window 0x00100866 invalid or changing, terminating capture
17:53:24: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:53:24:   New Scene
17:54:13: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:54:13:   New Scene
17:54:13: Using graphics capture
17:54:16: SharedTexCapture hooked
17:55:08: Exit signal received, terminating capture
17:55:08: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:55:08:   New Scene
17:55:10: Total frames encoded: 22025, total frames duplicated: 523 (2.37%)
17:55:10: Number of frames skipped due to encoder lag: 1 (0.00%)
17:55:10: Total frames rendered: 21884, number of late frames: 39 (0.18%) (it's okay for some frames to be late)
17:55:10: RTMPPublisher::SocketLoop: Graceful loop exit
17:55:11: Average send payload: 18790 bytes, average send interval: 51 ms
17:55:11: Number of times waited to send: 0, Waited for a total of 0 bytes
17:55:11: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
17:55:11: Number of bytes sent: 133863527
17:55:11: 
17:55:11: Profiler time results:
17:55:11: 
17:55:11: ==============================================================
17:55:11: video thread frame - [100%] [avg time: 0.906 ms] [children: 85.7%] [unaccounted: 14.3%]
17:55:11: | scene->Preprocess - [85.7%] [avg time: 0.776 ms]
17:55:11: GPU download and conversion - [100%] [avg time: 0.977 ms] [children: 87.1%] [unaccounted: 12.9%]
17:55:11: | flush - [84.7%] [avg time: 0.828 ms]
17:55:11: | CopyResource - [2.15%] [avg time: 0.021 ms]
17:55:11: | conversion to 4:2:0 - [0.205%] [avg time: 0.002 ms]
17:55:11: Convert444Threads - [100%] [avg time: 0.786 ms] [children: 98.7%] [unaccounted: 1.27%]
17:55:11: | Convert444toNV12 - [98.7%] [avg time: 0.776 ms]
17:55:11: encoder thread frame - [100%] [avg time: 1.532 ms]
17:55:11: ==============================================================
17:55:11: 
17:55:11: 
17:55:11: Profiler CPU results:
17:55:11: 
17:55:11: ==============================================================
17:55:11: video thread frame - [cpu time: avg 0.099 ms, total 2171.88 ms] [avg calls per frame: 1]
17:55:11: | scene->Preprocess - [cpu time: avg 0.064 ms, total 1406.25 ms] [avg calls per frame: 1]
17:55:11: GPU download and conversion - [cpu time: avg 0.252 ms, total 5531.25 ms] [avg calls per frame: 1]
17:55:11: | flush - [cpu time: avg 0.239 ms, total 5250 ms] [avg calls per frame: 1]
17:55:11: | CopyResource - [cpu time: avg 0.002 ms, total 46.875 ms] [avg calls per frame: 1]
17:55:11: | conversion to 4:2:0 - [cpu time: avg 0.002 ms, total 62.5 ms] [avg calls per frame: 1]
17:55:11: Convert444Threads - [cpu time: avg 0.818 ms, total 35765.6 ms] [avg calls per frame: 2]
17:55:11: | Convert444toNV12 - [cpu time: avg 0.816 ms, total 35687.5 ms] [avg calls per frame: 2]
17:55:11: encoder thread frame - [cpu time: avg 0.553 ms, total 12171.9 ms] [avg calls per frame: 1]
17:55:11: ==============================================================
17:55:11: 
17:55:11: =====Stream End: 2013-10-08, 17:55:11=================================================
18:01:15: Using fixed low latency mode, factor 20
18:01:15: =====Stream Start: 2013-10-08, 18:01:15===============================================
18:01:15:   Multithreaded optimizations: On
18:01:15:   Base resolution: 1920x1080
18:01:15:   Output resolution: 1280x720
18:01:15: ------------------------------------------
18:01:15: Loading up D3D10 on AMD Radeon HD 7900 Series...
18:01:15: ------------------------------------------
18:01:15: Audio Format: 48000hz
18:01:15: Playback device {0.0.0.00000000}.{15192ab2-8b4c-4aef-bf0b-c0713b40d621}
18:01:15: ------------------------------------------
18:01:15: Using desktop audio input: Line 1 (Virtual Audio Cable)
18:01:15: ------------------------------------------
18:01:15: Using auxilary audio input: Microphone (Logitech G35 Headset)
18:01:15: ------------------------------------------
18:01:15: Audio Encoding: AAC
18:01:15:     bitrate: 128
18:01:15: Using text output
18:01:15: Using text output
18:01:15: Using text output
18:01:15: Using text output
18:01:15: Using bitmap image
18:01:15: Using bitmap image
18:01:15: Using bitmap image
18:01:15: Using bitmap image
18:01:15: Using bitmap image
18:01:15: Using bitmap image
18:01:15: Using Window Capture
18:01:15: Using Window Capture
18:01:15: Using bitmap image
18:01:15: Using bitmap image
18:01:15: ------------------------------------------
18:01:15: Video Encoding: x264
18:01:15:     fps: 60
18:01:15:     width: 1280, height: 720
18:01:15:     preset: veryfast
18:01:15:     profile: high
18:01:15:     keyint: 120
18:01:15:     CBR: yes
18:01:15:     CFR: no
18:01:15:     max bitrate: 2800
18:01:15:     buffer size: 2800
18:01:15: ------------------------------------------
18:01:15: MMDeviceAudioSource: Frequency for device 'Line 1 (Virtual Audio Cable)' is 352800, samples per sec is 44100
18:01:15: MMDeviceAudioSource: Frequency for device 'Microphone (Logitech G35 Headset)' is 176400, samples per sec is 44100
18:01:17: Using RTMP service: Twitch / Justin.tv
18:01:17:   Server selection: rtmp://live-ams.justin.tv/app
18:01:17:   Interface: Qualcomm Atheros AR8161 PCI-E Gigabit Ethernet Controller (NDIS 6.30) (ethernet, 100 mbps)
18:01:17: Completed handshake with rtmp://live-ams.justin.tv/app in 430 ms.
18:01:19: SO_SNDBUF was at 81920
18:01:19: SO_SNDBUF is now 65536
18:08:04: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:08:04:   New Scene
18:08:04: Using text output
18:08:04: Using bitmap image
18:08:04: Using graphics capture
18:08:05: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:08:05:   New Scene
18:08:15: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:08:15:   New Scene
18:08:15: Using graphics capture
18:08:19: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:08:19:   New Scene
18:08:32: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:08:32:   New Scene
18:08:32: Using graphics capture
18:08:34: SharedTexCapture hooked
18:14:32: RTMPPublisher::Socketloop: Increasing send buffer to ISB 262144 (buffer: 11831 / 374784)
18:15:57: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:15:57:   New Scene
18:16:06: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:16:06:   New Scene
18:16:06: Using graphics capture
Warning -- SharedTexCapture::Init: Could not open file mapping: 2
18:16:07: SharedTexCapture hooked
18:19:40: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:19:40:   New Scene
18:19:48: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:19:48:   New Scene
18:19:48: Using graphics capture
Warning -- SharedTexCapture::Init: Could not open file mapping: 2
18:19:48: SharedTexCapture hooked
18:20:09: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:20:09:   New Scene
18:20:11: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:20:11:   New Scene
18:20:11: Using graphics capture
18:20:11: SharedTexCapture hooked
18:20:26: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:20:26:   New Scene
18:20:33: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:20:33:   New Scene
18:20:33: Using graphics capture
Warning -- SharedTexCapture::Init: Could not open file mapping: 2
18:20:34: SharedTexCapture hooked
18:27:59: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:27:59:   New Scene
18:28:04: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:28:04:   New Scene
18:28:04: Using graphics capture
Warning -- SharedTexCapture::Init: Could not open file mapping: 2
18:28:04: SharedTexCapture hooked
18:48:51: Exit signal received, terminating capture
18:48:51: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:48:51:   New Scene
18:54:57: Total frames encoded: 193278, total frames duplicated: 1243 (0.64%)
18:54:57: Number of frames skipped due to encoder lag: 2 (0.00%)
18:54:57: Total frames rendered: 193155, number of late frames: 46 (0.02%) (it's okay for some frames to be late)
18:54:57: RTMPPublisher::SocketLoop: Graceful loop exit
18:54:58: Average send payload: 18771 bytes, average send interval: 51 ms
18:54:58: Number of times waited to send: 0, Waited for a total of 0 bytes
18:54:58: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
18:54:58: Number of bytes sent: 1181880058
18:54:58: 
18:54:58: Profiler time results:
18:54:58: 
18:54:58: ==============================================================
18:54:58: video thread frame - [100%] [avg time: 0.656 ms] [children: 82.3%] [unaccounted: 17.7%]
18:54:58: | scene->Preprocess - [82.3%] [avg time: 0.54 ms]
18:54:58: GPU download and conversion - [100%] [avg time: 1.09 ms] [children: 88.8%] [unaccounted: 11.2%]
18:54:58: | flush - [87.5%] [avg time: 0.954 ms]
18:54:58: | CopyResource - [1.01%] [avg time: 0.011 ms]
18:54:58: | conversion to 4:2:0 - [0.275%] [avg time: 0.003 ms]
18:54:58: Convert444Threads - [100%] [avg time: 0.859 ms] [children: 98.7%] [unaccounted: 1.28%]
18:54:58: | Convert444toNV12 - [98.7%] [avg time: 0.848 ms]
18:54:58: encoder thread frame - [100%] [avg time: 1.297 ms]
18:54:58: ==============================================================
18:54:58: 
18:54:58: 
18:54:58: Profiler CPU results:
18:54:58: 
18:54:58: ==============================================================
18:54:58: video thread frame - [cpu time: avg 0.081 ms, total 15718.8 ms] [avg calls per frame: 1]
18:54:58: | scene->Preprocess - [cpu time: avg 0.045 ms, total 8875 ms] [avg calls per frame: 1]
18:54:58: GPU download and conversion - [cpu time: avg 0.211 ms, total 40937.5 ms] [avg calls per frame: 1]
18:54:58: | flush - [cpu time: avg 0.199 ms, total 38546.9 ms] [avg calls per frame: 1]
18:54:58: | CopyResource - [cpu time: avg 0.003 ms, total 625 ms] [avg calls per frame: 1]
18:54:58: | conversion to 4:2:0 - [cpu time: avg 0.001 ms, total 234.375 ms] [avg calls per frame: 1]
18:54:58: Convert444Threads - [cpu time: avg 0.731 ms, total 282422 ms] [avg calls per frame: 2]
18:54:58: | Convert444toNV12 - [cpu time: avg 0.728 ms, total 281422 ms] [avg calls per frame: 2]
18:54:58: encoder thread frame - [cpu time: avg 0.803 ms, total 155188 ms] [avg calls per frame: 1]
18:54:58: ==============================================================
18:54:58: 
18:54:58: =====Stream End: 2013-10-08, 18:54:58=================================================
18:54:58: Memory Leaks Were Detected.
18:54:58:

Thanks in advance for the help.

Here is a example of me streaming payday: http://www.twitch.tv/krxz/b/468497920
That is 2800 rates 720p60fps
I don't notice the 3second freezes every 2 seconds like a viewer said.
Hmm

I believe this: http://www.twitch.tv/krxz/b/468504800 is 2000 or 2500 rates.

Krazy · Oct 8, 2013

Well, you aren't lagging/skipping/dropping frames, so it's likely the viewer in question was simply unable to watch at your chosen bitrate. The only way to fix that is to lower bitrate until it stops, but it's up to you if you want to do that for a single viewer or not. Keep in mind that if you lower bitrate too much it will very negatively impact quality, and you will probably have to downscale to make up for it.

Krxz · Oct 8, 2013

Hmm.. The Log Analyzer gave me several errors for those logs. Especially skipped frames. On the Payday2 video I linked, I see micro freezes.

Krazy · Oct 8, 2013

Mmm, I see what you mean. It's even present in the video after it's downloaded and watched in a media player offline. Does the same thing happen with just a local recording?

FerretBomb · Oct 8, 2013

Skipped frames (A significant percentage, as in the first two logs) tend to point straight to a CPU or other encoding problem... but at Veryfast and 720@60, you probably should be able to swing that unless the games you're playing are VERY CPU-heavy (which LoL and PD2 can be at times). After all, the i5 is a mid-range CPU, and can get overwhelmed when asked to do more than gaming alone... and streaming is quite a bit more intensive.

Have you checked to ensure that your CPU is not being incorrectly throttled/frequency lowered by the OS? Have you been monitoring the CPU utilization (and process list) to ensure you are not peaking due to some background process? Have you been monitoring your CPU temps to make sure you aren't hitting a thermal-safety downclock? Have you considered using QuickSync, if your system supports it? As I understand, it's not much worse than the Veryfast preset, but can significantly lighten the CPU load.

Krxz · Oct 8, 2013

I haven't been monitoring all that much. I used to monitor all this at the start, because I wasn't sure I could stream.

Under a League of Legends game + streaming. Cpu load was 70-80% usually around 75%. More demanding games would put my cpu above the 80% of usage. I recall this being in at 30fps.

I use Real temp and I haven't seen higher then 72° under load/streaming. This is somewhat recent.

What programs could I use to check , this OS down throttling and possible thermal-safety downclock.
I know for a fact Real Temp is set to 80° alarm, but I don't think that downclocks it... I don't even hit that temp anyways.
I also disabled the CPU's turbo that would OC my cpu to 3.8ghz using the wrong value. I'm not sure what they are called, but it wasn't the multiplier being changed for the OC, and it was recommended to me to turn it off.

If anyone could suggest the program or ways for me to monitor and check all this, I would really appreciate it

Krxz · Oct 9, 2013

Bump + Update

Here is a stream session of League of Legends @ 720p60fps 2500/2500bitrates.

- Log: http://pastebin.com/qjgXL7Bq

- The video: http://www.twitch.tv/krxz/b/468690158

Things I have changed/tested/monitored since OP.

- Activated Turbo feature on my CPU, that is now running @ 3.7-3.8Ghz when needed
- I monitored CPU load/temps during the stream session.

CPU Loads: Max:85%-89% (depending on core) Avg: 55%
CPU Temps: Max: 70%-75%

I don't notice any programs hogging my CPU when I look at the Task Manager. Seeing as I don't really know how to find out if my OS is down throttling it, or if it has a thermal lock downclock as well, I can't really look more into that. If anyone can give me hints as to how to do the above...
My idea was to maybe activate the Turbo to get more CPU power and hopefully that would help. Usually I ran the i5 locked at 3.4Ghz Turbo off.

I believe there are little to 0 skipped frames in that log, however the Log Analyzer did not come back clean. I'm sure more demanding games will put my CPU load over 90%.

Can anyone with the same CPU confirm these CPU loads while streaming+gaming?

Regards,

Krxz · Oct 9, 2013

Update: APB Reloaded Session 720p48fps 2500/2500

Log: http://pastebin.com/3qWxb3eY

Video: http://www.twitch.tv/krxz/b/468741426

CPU Load: max 99.2% (this can't be normal?)
CPU Temp: max 78°

I don't know what to say...

Lain · Oct 10, 2013

It seems that you aren't having it happen all that often. First of all, let me suggest using the "process priority" option, try turning it up to "above normal" if it was already at "normal" and seeing if the improves it.

Question / Help Skipped frames problem. Logs inside.

Krxz

Member

Krxz

Member

Krazy

Town drunk

Krxz

Member

Krazy

Town drunk

FerretBomb

Active Member

Krxz

Member

Krxz

Member

Krxz

Member

Lain