Question / Help twitch rtmp handshake too big

penetras · Jun 25, 2014

So i'm having rtmp complete handshakes from about 800ms - 900ms. Is there a way to fix this? i know that the rtmp it is not the same has the ping but just for the record my ping between twitch london uk server is about 42ms and 2.0 jitters.
My internet connection is 120Mbt/s | 10Mbt/s Download /Upload.

Are this values normal? can i do something to improve it?

ps:.And sometimes i've some fps drop when my webcam is turned on.

Code:

13:23:30: =====Stream Start: 2014-06-25, 13:23:30===============================================
13:23:30:   Multithreaded optimizations: On
13:23:30:   Base resolution: 1920x1200
13:23:30:   Output resolution: 1280x800
13:23:30: ------------------------------------------
13:23:30: Loading up D3D10 on NVIDIA GeForce GTX 660 (Adapter 1)...
13:23:30: ------------------------------------------
13:23:30: Audio Format: 48000 Hz
13:23:30: ------------------------------------------
13:23:30: Audio Channels: 2 Ch
13:23:30: Playback device {0.0.0.00000000}.{cc214552-d932-4383-b8b8-9cf2148bb3bb}
13:23:30: ------------------------------------------
13:23:30: Using desktop audio input: Line 1 (Virtual Audio Cable)
13:23:30: Global Audio time adjust: 0
13:23:30: ------------------------------------------
13:23:30: Using auxilary audio input: Microfone (Realtek High Definition Audio)
13:23:30: Mic time offset: 0
13:23:30: ------------------------------------------
13:23:30: Audio Encoding: AAC
13:23:30:     bitrate: 128
13:23:30: Using text output
13:23:30: Using text output
13:23:30: Using text output
13:23:30: Using text output
13:23:30: Using bitmap image
13:23:30: Using bitmap image
13:23:30: Using text output
13:23:30: Using bitmap image
13:23:31: Using bitmap image
13:23:31: Using text output
13:23:31: Using text output
13:23:31: Using bitmap image
13:23:32: ------------------------------------------
13:23:32:     device: Vimicro USB PC Camera (ZC0301PL),
13:23:32:     device id \\?\usb#vid_041e&pid_403a#5&4a03a84&0&1#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\global,
13:23:32:     chosen type: RGB24, usingFourCC: false, res: 160x120 - 160x120, frameIntervals: 100000-10000000
13:23:32:     use buffering: false - 0, fourCC: 00000000
13:23:32:     audio device: Disable,
13:23:32:     audio device id Disabled,
13:23:32:     audio time offset 0,
13:23:32:
13:23:32: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
13:23:32: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
13:23:32: CLRHost:: Browser 13: Status message:
13:23:33: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
13:23:33: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
13:23:33: CLRHost:: Browser 15: Status message:
13:23:33: CLRHost:: Browser 14: Status message:
13:23:33: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
13:23:33: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
13:23:33: Using directshow input
13:23:34: Using bitmap image
13:23:34: Using bitmap image
13:23:34: Using bitmap image
13:23:34: Using graphics capture
13:23:34: Using bitmap image
13:23:34: Trying to hook process: Nosgoth.exe
13:23:34: Scene buffering time set to 700
13:23:34: 1 CUDA capable devices found
13:23:34: [ GPU #0 - < GeForce GTX 660 > has Compute SM 3.0, NVENC Available ]
13:23:34: NVENC internal init finished successfully
13:23:34: NVENC supports 8 h264 presets
13:23:34: ------------------------------------------
13:23:34: Video Encoding: NVENC
13:23:34:     fps: 25
13:23:34:     width: 1280, height: 800
13:23:34:     preset: default
13:23:34:     profile: main
13:23:34:     level: autoselect
13:23:34:     keyint: 50
13:23:34:     CBR: yes
13:23:34:     CFR: yes
13:23:34:     max bitrate: 3000
13:23:34:     avg bitrate: 3000
13:23:34:     buffer size: 3000
13:23:34: ------------------------------------------
13:23:34: SharedTexCapture hooked
13:23:34: CLRHost:: Browser 15: Connected to Socket Server @https://www.nightdev.com/hosted/donation-alert/?channel=penetras69&amount=0.01&key=MDdkYWE1NWFkYTBkODAwMjdiNDViNDA1&type=stacked&alert=https%3A%2F%2Fi.imgur.com%2FLRAPog7.png&sound=3908744
13:23:35: CLRHost:: Browser 15: Logged into server @https://www.nightdev.com/hosted/donation-alert/?channel=penetras69&amount=0.01&key=MDdkYWE1NWFkYTBkODAwMjdiNDViNDA1&type=stacked&alert=https%3A%2F%2Fi.imgur.com%2FLRAPog7.png&sound=3908754
13:23:35: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
13:23:35: Using RTMP service: Twitch / Justin.tv
13:23:35:   Server selection: rtmp://live-ams.justin.tv/app
13:23:36:   Interface: NVIDIA nForce 10/100/1000 Mbps Ethernet  (ethernet, 1000 mbps)
13:23:36: Completed handshake with rtmp://live-ams.justin.tv/app in 908 ms.
13:23:37: SO_SNDBUF was at 8192
13:23:37: SO_SNDBUF is now 65536
13:23:45: GraphicsCaptureSource::NewCapture:  eliminating old capture
13:23:45: SharedTexCapture hooked
13:24:42: GraphicsCaptureSource::NewCapture:  eliminating old capture
13:24:42: SharedTexCapture hooked
13:26:08: Capture window 0x004E063A invalid or changing, terminating capture
13:26:51: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
13:26:51: CLRHost:: Browser 16: Status message:
13:26:54: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
13:27:22: SharedTexCapture hooked
13:29:50: GraphicsCaptureSource::NewCapture:  eliminating old capture
13:29:50: SharedTexCapture hooked
13:36:50: Using bitmap image
13:39:33: RTMPPublisher::SocketLoop: Stalled for 1560 ms to write 276999 bytes (buffer: 0 / 400384), unstable connection?
13:40:24: GraphicsCaptureSource::NewCapture:  eliminating old capture
13:40:24: SharedTexCapture hooked
13:44:55: GraphicsCaptureSource::NewCapture:  eliminating old capture
13:44:55: SharedTexCapture hooked
14:05:17: GraphicsCaptureSource::NewCapture:  eliminating old capture
14:05:17: SharedTexCapture hooked
14:11:23: Using bitmap image
14:11:24: Using bitmap image
14:14:30: GraphicsCaptureSource::NewCapture:  eliminating old capture
14:14:30: SharedTexCapture hooked
14:19:29: Audio timestamp for device 'Microfone (Realtek High Definition Audio)' was behind target timestamp by 80
14:24:21: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
14:36:29: GraphicsCaptureSource::NewCapture:  eliminating old capture
14:36:29: SharedTexCapture hooked
14:36:44: GraphicsCaptureSource::NewCapture:  eliminating old capture
14:36:44: SharedTexCapture hooked
14:37:03: GraphicsCaptureSource::NewCapture:  eliminating old capture
14:37:03: SharedTexCapture hooked
14:38:08: Using bitmap image
14:39:11: GraphicsCaptureSource::NewCapture:  eliminating old capture
14:39:11: SharedTexCapture hooked
14:41:25: GraphicsCaptureSource::NewCapture:  eliminating old capture
14:41:25: SharedTexCapture hooked
15:03:24: GraphicsCaptureSource::NewCapture:  eliminating old capture
15:03:24: SharedTexCapture hooked
15:03:46: GraphicsCaptureSource::NewCapture:  eliminating old capture
15:03:46: SharedTexCapture hooked
15:23:31: Total frames encoded: 179895, total frames duplicated: 3971 (2.21%)
15:23:31: Number of frames skipped due to encoder lag: 829 (0.46%)
15:23:31: Total frames rendered: 177981, number of late frames: 491 (0.28%) (it's okay for some frames to be late)
15:23:31: RTMPPublisher::SocketLoop: Graceful loop exit
15:23:31: Average send payload: 10977 bytes, average send interval: 31 ms
15:23:31: Number of times waited to send: 0, Waited for a total of 0 bytes
15:23:31: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
15:23:31: Number of bytes sent: 2483054195
15:23:32: Encoder closed
15:23:33: NVENC deinitialized
15:23:36:
15:23:36: Profiler time results:
15:23:36:
15:23:36: ==============================================================
15:23:36: video thread frame - [100%] [avg time: 5.448 ms] [children: 77.3%] [unaccounted: 22.7%]
15:23:36: | scene->Preprocess - [5.14%] [avg time: 0.28 ms]
15:23:36: | GPU download and conversion - [72.1%] [avg time: 3.93 ms] [children: 64.7%] [unaccounted: 7.43%]
15:23:36: | | flush - [47.4%] [avg time: 2.585 ms]
15:23:36: | | CopyResource - [14.3%] [avg time: 0.78 ms]
15:23:36: | | conversion to 4:2:0 - [2.94%] [avg time: 0.16 ms]
15:23:36: Convert444Threads - [100%] [avg time: 3.805 ms] [children: 99.2%] [unaccounted: 0.815%]
15:23:36: | Convert444toNV12 - [99.2%] [avg time: 3.774 ms]
15:23:36: encoder thread frame - [100%] [avg time: 6.962 ms] [children: 1.98%] [unaccounted: 98%]
15:23:36: | sending stuff out - [1.98%] [avg time: 0.138 ms]
15:23:36: ==============================================================
15:23:36:
15:23:36:
15:23:36: Profiler CPU results:
15:23:36:
15:23:36: ==============================================================
15:23:36: video thread frame - [cpu time: avg 1.339 ms, total 238401 ms] [avg calls per frame: 1]
15:23:36: | scene->Preprocess - [cpu time: avg 0.115 ms, total 20514.1 ms] [avg calls per frame: 1]
15:23:36: | GPU download and conversion - [cpu time: avg 0.61 ms, total 108717 ms] [avg calls per frame: 1]
15:23:36: | | flush - [cpu time: avg 0.509 ms, total 90761.4 ms] [avg calls per frame: 1]
15:23:36: | | CopyResource - [cpu time: avg 0.034 ms, total 6099.65 ms] [avg calls per frame: 1]
15:23:36: | | conversion to 4:2:0 - [cpu time: avg 0.024 ms, total 4368.02 ms] [avg calls per frame: 1]
15:23:36: Convert444Threads - [cpu time: avg 3.041 ms, total 1.08246e+006 ms] [avg calls per frame: 2]
15:23:36: | Convert444toNV12 - [cpu time: avg 3.031 ms, total 1.07881e+006 ms] [avg calls per frame: 2]
15:23:36: encoder thread frame - [cpu time: avg 1.53 ms, total 275201 ms] [avg calls per frame: 1]
15:23:36: | sending stuff out - [cpu time: avg 0.079 ms, total 14227.3 ms] [avg calls per frame: 1]
15:23:36: ==============================================================

R1CH · Jun 25, 2014

Are you actually having a problem? Your log doesn't show any dropped frames.

penetras · Jun 25, 2014

in this last log it seems not, but usually yes i've the skipped/ dropped frames msg. Since it was happening everytime i was streaming i assumed this last log had the skipped frames also

R1CH · Jun 25, 2014

Skipped frames are not caused by network issues, they are due to slow sources / overworked CPU / encoder.

penetras · Jun 25, 2014

oh ok thanks for the explanation r1ch i thought it was about twitch server since it was giving me the error of a possible too slow server to switch it

Question / Help twitch rtmp handshake too big

penetras

New Member

R1CH

penetras

New Member

R1CH

penetras

New Member