Question / Help Feedback on my log - thanks

[its_Heisenberg]

Howdy,

I'm pretty happy with my stream, just wanted to check if there's anything that needs to be looked at or could be improved based on the results from a 2 hr stream below. I understand the frames rendered/lagged...it's the stuff below about 'CPU avg time' etc I'm not sure if the results are good, normal or poor.

Thanks in advance! :)

: Total frames rendered: 437826, number of frames that lagged: 721 (0.16%) (it's okay for some frames to lag)
17:15:18: RTMPPublisher::SocketLoop: Aborting due to bStopping
17:15:18: Number of times waited to send: 0, Waited for a total of 0 bytes
17:15:18: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
17:15:18: =====Stream End: 2013-07-19, 17:15:18=================================================
17:15:20:
17:15:20: Profiler results:
17:15:20:
17:15:20: ==============================================================
17:15:20: frame - [100%] [avg time: 1.379 ms (cpu time: avg 0.986 ms, total 431858 ms)] [avg calls per frame: 1] [children: 81.6%] [unaccounted: 18.4%]
17:15:20: | scene->Preprocess - [0.0725%] [avg time: 0.001 ms (cpu time: avg 0.001 ms, total 733.203 ms)] [avg calls per frame: 1]
17:15:20: | video encoding and uploading - [81.5%] [avg time: 1.124 ms (cpu time: avg 0.885 ms, total 387725 ms)] [avg calls per frame: 1] [children: 75.9%] [unaccounted: 5.66%]
17:15:20: | | CopyResource - [1.16%] [avg time: 0.016 ms (cpu time: avg 0.007 ms, total 3478.82 ms)] [avg calls per frame: 0]
17:15:20: | | conversion to 4:2:0 - [2.39%] [avg time: 0.033 ms (cpu time: avg 0.002 ms, total 1310.41 ms)] [avg calls per frame: 0]
17:15:20: | | call to encoder - [69.6%] [avg time: 0.96 ms (cpu time: avg 0.855 ms, total 374683 ms)] [avg calls per frame: 0]
17:15:20: | | sending stuff out - [2.68%] [avg time: 0.037 ms (cpu time: avg 0.009 ms, total 4212.03 ms)] [avg calls per frame: 0]
17:15:20: | Convert444Threads - [2.42e+003%] [avg time: 33.349 ms (cpu time: avg 0.805 ms, total 352453 ms)] [avg calls per frame: 1]

 

[hilalpro]

it's fine performance wise.

 

[Krazy]

If you really want advice on how to tweak your stream settings better you really need to post your entire log. The one you posted tells us nothing about your hardware or any of your settings, just that your frame times were low and you didn't really lag/drop any frames.

 

[its_Heisenberg]

If you really want advice on how to tweak your stream settings better you really need to post your entire log. The one you posted tells us nothing about your hardware or any of your settings, just that your frame times were low and you didn't really lag/drop any frames.

No prob - see below, thanks!

Note: It doesn't pick it up in log but my CPU is actually OC to 4.5Ghz as opposed to the 3.4Ghz it says below.

15:13:37: Open Broadcaster Software v0.541b - 64bit (　^ω^)
15:13:37: -------------------------------
15:13:37: CPU Name: Intel(R) Core(TM) i5-3570K CPU @ 3.40GHz
15:13:37: CPU Speed: 3403MHz
15:13:37: Physical Memory: 8152MB Total, 6340MB Free
15:13:37: stepping id: 9, model 10, family 6, type 0, extmodel 1, extfamily 0, HTT 1, logical cores 4, total cores 4
15:13:37: monitor 1: pos={0, 0}, size={1920, 1080}
15:13:37: Windows Version: 6.1 Build 7601 S
15:13:37: Aero is Enabled
15:13:37: -------------------------------
15:13:37: OBS Modules:
15:13:37: Base Address Module
15:13:37: 000000003FAD0000 OBS.exe
15:13:37: 00000000FA0E0000 OBSApi.dll
15:13:37: 00000000FA9C0000 DShowPlugin.dll
15:13:37: 00000000FA990000 GraphicsCapture.dll
15:13:37: 00000000FA0C0000 NoiseGate.dll
15:13:37: 00000000FA0A0000 PSVPlugin.dll
15:13:37: 00000000FA010000 WebSocketAPIPlugin.dll
15:13:37: ------------------------------------------
15:13:37: Adapter 1
15:13:37: Video Adapter: AMD Radeon HD 7800 Series
15:13:37: Video Adapter Dedicated Video Memory: 2128814080
15:13:37: Video Adapter Shared System Memory: 4005576704
15:13:37: =====Stream Start: 2013-07-19, 15:13:37===============================================
15:13:37: Multithreaded optimizations: On
15:13:37: Base resolution: 1920x1080
15:13:37: Output resolution: 1280x720
15:13:37: ------------------------------------------
15:13:37: Loading up D3D10...
15:13:37: Playback device {0.0.0.00000000}.{225a33c5-4b43-4fb1-9e87-04d855fefff7}
15:13:37: ------------------------------------------
15:13:37: Using desktop audio input: Speakers (ASUS Xonar DG Audio Device)
15:13:37: ------------------------------------------
15:13:37: Using auxilary audio input: Microphone (ASUS Xonar DG Audio Device)
15:13:37: ------------------------------------------
15:13:37: Audio Encoding: AAC
15:13:37: bitrate: 256
15:13:37: Using graphics capture
15:13:37: Using bitmap image
15:13:37: Using bitmap image
15:13:37: Using bitmap image
15:13:37: ------------------------------------------
15:13:37: Video Encoding: x264
15:13:37: fps: 60
15:13:37: width: 1280, height: 720
15:13:37: preset: faster
15:13:37: CBR: no
15:13:37: CFR: no
15:13:37: max bitrate: 3000
15:13:37: buffer size: 3300
15:13:37: quality: 8
15:13:37: ------------------------------------------
15:13:38: Using RTMP service: Twitch / Justin.tv
15:13:38: Server selection: rtmp://live-lhr.justin.tv/app
15:13:40: SO_SNDBUF was at 8192
15:13:40: SO_SNDBUF is now 65536
15:19:31: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
15:19:31: New Scene
15:19:31: Using graphics capture
15:19:31: Using bitmap image
15:19:31: Using bitmap image
15:19:31: Using bitmap image
15:19:53: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
15:19:53: New Scene
15:19:53: Using graphics capture
15:19:53: Using bitmap image
15:19:53: Using bitmap image
15:19:53: Using bitmap image
15:19:55: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
15:19:55: New Scene
15:19:55: Using graphics capture
15:19:55: Using bitmap image
15:19:55: Using bitmap image
15:19:55: Using bitmap image
15:20:17: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
15:20:17: New Scene
15:20:17: Using graphics capture
15:20:17: Using bitmap image
15:20:17: Using bitmap image
15:20:17: Using bitmap image
15:20:35: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
15:20:35: New Scene
15:20:35: Using bitmap image
15:20:35: Using bitmap image
15:20:37: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
15:20:37: New Scene
15:20:37: Using graphics capture
15:20:37: Using bitmap image
15:20:37: Using bitmap image
15:20:37: Using bitmap image
15:20:55: SharedTexCapture hooked
15:21:12: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
15:21:12: New Scene
15:21:12: Using bitmap image
15:21:12: Using bitmap image
15:21:18: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
15:21:18: New Scene
15:21:18: Using graphics capture
15:21:18: Using bitmap image
15:21:18: Using bitmap image
15:21:18: Using bitmap image
15:21:18: SharedTexCapture hooked
15:22:59: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
15:22:59: New Scene
15:22:59: Using bitmap image
15:22:59: Using bitmap image
15:23:22: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
15:23:22: New Scene
15:23:22: Using graphics capture
15:23:22: Using bitmap image
15:23:22: Using bitmap image
15:23:22: Using bitmap image
15:23:22: SharedTexCapture hooked
15:54:20: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
15:54:20: New Scene
15:54:20: Using bitmap image
15:54:20: Using bitmap image
15:54:42: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
15:54:42: New Scene
15:54:42: Using graphics capture
15:54:42: Using bitmap image
15:54:42: Using bitmap image
15:54:42: Using bitmap image
15:54:42: SharedTexCapture hooked
17:10:24: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
17:10:24: New Scene
17:10:24: Using bitmap image
17:10:24: Using bitmap image
17:15:18: Total frames rendered: 437826, number of frames that lagged: 721 (0.16%) (it's okay for some frames to lag)
17:15:18: RTMPPublisher::SocketLoop: Aborting due to bStopping
17:15:18: Number of times waited to send: 0, Waited for a total of 0 bytes
17:15:18: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
17:15:18: =====Stream End: 2013-07-19, 17:15:18=================================================
17:15:20:
17:15:20: Profiler results:
17:15:20:
17:15:20: ==============================================================
17:15:20: frame - [100%] [avg time: 1.379 ms (cpu time: avg 0.986 ms, total 431858 ms)] [avg calls per frame: 1] [children: 81.6%] [unaccounted: 18.4%]
17:15:20: | scene->Preprocess - [0.0725%] [avg time: 0.001 ms (cpu time: avg 0.001 ms, total 733.203 ms)] [avg calls per frame: 1]
17:15:20: | video encoding and uploading - [81.5%] [avg time: 1.124 ms (cpu time: avg 0.885 ms, total 387725 ms)] [avg calls per frame: 1] [children: 75.9%] [unaccounted: 5.66%]
17:15:20: | | CopyResource - [1.16%] [avg time: 0.016 ms (cpu time: avg 0.007 ms, total 3478.82 ms)] [avg calls per frame: 0]
17:15:20: | | conversion to 4:2:0 - [2.39%] [avg time: 0.033 ms (cpu time: avg 0.002 ms, total 1310.41 ms)] [avg calls per frame: 0]
17:15:20: | | call to encoder - [69.6%] [avg time: 0.96 ms (cpu time: avg 0.855 ms, total 374683 ms)] [avg calls per frame: 0]
17:15:20: | | sending stuff out - [2.68%] [avg time: 0.037 ms (cpu time: avg 0.009 ms, total 4212.03 ms)] [avg calls per frame: 0]
17:15:20: | Convert444Threads - [2.42e+003%] [avg time: 33.349 ms (cpu time: avg 0.805 ms, total 352453 ms)] [avg calls per frame: 1]
17:15:20: ==============================================================
17:15:20:

 

[alpinlol]

i'd use cbr at this bitrate and also cfr

 

[Kharay]

i'd use cbr at this bitrate and also cfr

No, not CFR, not for streaming purposes. CFR is fine for a local recording but for streaming purposes it may do more harm than good. CBR... most decidedly yes for streaming.

 

[its_Heisenberg]

Cool, thanks for the feedback...

 

[its_Heisenberg]

Howdy - I enabled CBR as per your above post and below is the results from a 5hr stream....are the results better, worse or much the same as before I enabled it? Thanks :)

18:07:29: =====Stream Start: 2013-07-26, 18:07:29===============================================
18:07:29: Multithreaded optimizations: On
18:07:29: Base resolution: 1920x1080
18:07:29: Output resolution: 1280x720
18:07:29: ------------------------------------------
18:07:29: Loading up D3D10...
18:07:29: Playback device {0.0.0.00000000}.{225a33c5-4b43-4fb1-9e87-04d855fefff7}
18:07:29: ------------------------------------------
18:07:29: Using desktop audio input: Speakers (ASUS Xonar DG Audio Device)
18:07:29: ------------------------------------------
18:07:29: Using auxilary audio input: Microphone (ASUS Xonar DG Audio Device)
18:07:29: ------------------------------------------
18:07:29: Audio Encoding: AAC
18:07:29: bitrate: 256
18:07:29: Using graphics capture
18:07:29: Using bitmap image
18:07:29: Using bitmap image
18:07:29: ------------------------------------------
18:07:29: Video Encoding: x264
18:07:29: fps: 60
18:07:29: width: 1280, height: 720
18:07:29: preset: faster
18:07:29: CBR: yes
18:07:29: CFR: no
18:07:29: max bitrate: 3000
18:07:29: ------------------------------------------
18:07:30: SharedTexCapture hooked
18:07:31: Using RTMP service: Twitch / Justin.tv
18:07:31: Server selection: rtmp://live-lhr.justin.tv/app
18:07:33: SO_SNDBUF was at 8192
18:07:33: SO_SNDBUF is now 65536
18:09:05: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:09:05: New Scene
18:09:05: Using bitmap image
18:09:05: Using bitmap image
18:09:13: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:09:13: New Scene
18:09:13: Using graphics capture
18:09:13: Using bitmap image
18:09:13: Using bitmap image
18:09:13: SharedTexCapture hooked
18:43:37: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:43:37: New Scene
18:43:37: Using bitmap image
18:43:37: Using bitmap image
18:43:52: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:43:52: New Scene
18:43:52: Using graphics capture
18:43:52: Using bitmap image
18:43:52: Using bitmap image
18:43:52: SharedTexCapture hooked
18:47:55: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:47:55: New Scene
18:47:55: Using bitmap image
18:47:55: Using bitmap image
18:48:00: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
18:48:00: New Scene
18:48:00: Using graphics capture
18:48:00: Using bitmap image
18:48:00: Using bitmap image
18:48:00: SharedTexCapture hooked
20:33:45: GraphicsCaptureSource::NewCapture: eliminating old capture
20:33:45: SharedTexCapture hooked
20:34:14: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
20:34:14: New Scene
20:34:14: Using bitmap image
20:34:14: Using bitmap image
20:34:19: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
20:34:19: New Scene
20:34:19: Using graphics capture
20:34:19: Using bitmap image
20:34:19: Using bitmap image
20:34:19: SharedTexCapture hooked
21:29:39: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:29:39: New Scene
21:29:39: Using bitmap image
21:29:39: Using bitmap image
21:29:49: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:29:49: New Scene
21:29:49: Using graphics capture
21:29:49: Using bitmap image
21:29:49: Using bitmap image
21:29:49: SharedTexCapture hooked
21:36:22: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:36:22: New Scene
21:36:22: Using bitmap image
21:36:22: Using bitmap image
21:36:26: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:36:26: New Scene
21:36:26: Using graphics capture
21:36:26: Using bitmap image
21:36:26: Using bitmap image
21:36:26: SharedTexCapture hooked
22:15:52: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
22:15:52: New Scene
22:15:52: Using bitmap image
22:15:52: Using bitmap image
22:16:13: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
22:16:13: New Scene
22:16:13: Using graphics capture
22:16:13: Using bitmap image
22:16:13: Using bitmap image
22:16:13: SharedTexCapture hooked
22:16:44: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
22:16:44: New Scene
22:16:44: Using bitmap image
22:16:44: Using bitmap image
22:17:54: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
22:17:54: New Scene
22:17:54: Using graphics capture
22:17:54: Using bitmap image
22:17:54: Using bitmap image
22:17:54: SharedTexCapture hooked
22:21:06: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
22:21:06: New Scene
22:21:06: Using bitmap image
22:21:06: Using bitmap image
22:21:55: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
22:21:55: New Scene
22:21:55: Using graphics capture
22:21:55: Using bitmap image
22:21:55: Using bitmap image
22:21:55: SharedTexCapture hooked
22:35:46: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
22:35:46: New Scene
22:35:46: Using bitmap image
22:35:46: Using bitmap image
22:35:50: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
22:35:50: New Scene
22:35:50: Using graphics capture
22:35:50: Using bitmap image
22:35:50: Using bitmap image
22:35:50: SharedTexCapture hooked
22:42:40: GraphicsCaptureSource::NewCapture: eliminating old capture
22:42:40: SharedTexCapture hooked
23:26:17: Total frames rendered: 1146637, number of frames that lagged: 4383 (0.38%) (it's okay for some frames to lag)
23:26:17: RTMPPublisher::SocketLoop: Aborting due to bStopping
23:26:17: Number of times waited to send: 0, Waited for a total of 0 bytes
23:26:17: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
23:26:17: =====Stream End: 2013-07-26, 23:26:17=================================================
23:26:18:
23:26:18: Profiler results:
23:26:18:
23:26:18: ==============================================================
23:26:18: frame - [100%] [avg time: 2.788 ms (cpu time: avg 1.023 ms, total 1.63269e+006 ms)] [avg calls per frame: 1] [children: 99.9%] [unaccounted: 0.108%]
23:26:18: | frame preprocessing and rendering - [8.9%] [avg time: 0.248 ms (cpu time: avg 0.106 ms, total 169479 ms)] [avg calls per frame: 1] [children: 0.0359%] [unaccounted: 8.86%]
23:26:18: | | scene->Preprocess - [0.0359%] [avg time: 0.001 ms (cpu time: avg 0.001 ms, total 2340.01 ms)] [avg calls per frame: 1]
23:26:18: | video encoding and uploading - [91%] [avg time: 2.537 ms (cpu time: avg 0.915 ms, total 1.46139e+006 ms)] [avg calls per frame: 1] [children: 88.8%] [unaccounted: 2.19%]
23:26:18: | | flush - [51.3%] [avg time: 1.43 ms (cpu time: avg 0.051 ms, total 82337.3 ms)] [avg calls per frame: 1]
23:26:18: | | CopyResource - [0.646%] [avg time: 0.018 ms (cpu time: avg 0.006 ms, total 10779.7 ms)] [avg calls per frame: 0]
23:26:18: | | conversion to 4:2:0 - [1%] [avg time: 0.028 ms (cpu time: avg 0.003 ms, total 5054.44 ms)] [avg calls per frame: 0]
23:26:18: | | call to encoder - [34.5%] [avg time: 0.962 ms (cpu time: avg 0.835 ms, total 1.33265e+006 ms)] [avg calls per frame: 0]
23:26:18: | | sending stuff out - [1.36%] [avg time: 0.038 ms (cpu time: avg 0.01 ms, total 16192.9 ms)] [avg calls per frame: 0]
23:26:18: | Convert444Threads - [1.2e+003%] [avg time: 33.359 ms (cpu time: avg 0.799 ms, total 1.27657e+006 ms)] [avg calls per frame: 1]
23:26:18: ==============================================================
23:26:18:

 

[Kharay]

Howdy - I enabled CBR as per your above post and below is the results from a 5hr stream....are the results better, worse or much the same as before I enabled it? Thanks :)

I'd say... you tell us -- did you watch it back? The broadcast I mean. If so, what did you think about it?

As far as the performance goes, well, it seems fine. So, how was the quality? Had any viewers? What did they think?

 

[its_Heisenberg]

Looks pretty much the same to me, didn't have much lag before but I'd say there should be none now that it can't spike much higher.
I just didn't know what all the last part of my log was like in comparison to previous and if I should switch back or not...