Bug Report [Solved] Twitch video glitch, on the hour.

Boildown · Feb 10, 2014

Pretty sure its a Twitch problem, not an OBS problem, because the local record doesn't have this issue. But just in case I'm wrong, I'm seeing a video glitch on the hour, or a few (2-20) seconds afterwards, where the video blanks out.

It happens at the 1:02ish, 2:04ish, 3:08ish, 4:10ish, and 5:18ish mark. Due to imprecision of Twitch's timeline, I can't tell you to the exact second that it happens, so check +/- 20 seconds: http://www.twitch.tv/boildown/b/502148408

Here's the log from that stream:

Code:

17:41:06: Open Broadcaster Software v0.60b - 32bit (´・ω・｀)
17:41:06: -------------------------------
17:41:06: CPU Name: Intel(R) Core(TM) i7-2600K CPU @ 3.40GHz
17:41:06: CPU Speed: 3400MHz
17:41:06: Physical Memory:  4095MB Total, 4095MB Free
17:41:06: stepping id: 7, model 42, family 6, type 0, extmodel 1, extfamily 0, HTT 1, logical cores 8, total cores 4
17:41:06: monitor 1: pos={0, 0}, size={1680, 1050}
17:41:06: Windows Version: 6.1 Build 7601 S
17:41:06: Aero is Disabled
17:41:06: -------------------------------
17:41:06: OBS Modules:
17:41:06: Base Address     Module
17:41:06: 00E70000         OBS.exe
17:41:06: 6DBB0000         OBSApi.dll
17:41:06: 73A10000         DatapathPlugin.dll
17:41:06: 739E0000         DShowPlugin.dll
17:41:06: 73A80000         GraphicsCapture.dll
17:41:06: 6F060000         NoiseGate.dll
17:41:06: 6F040000         PSVPlugin.dll
17:41:06: ------------------------------------------
17:41:06: Adapter 1
17:41:06:   Video Adapter: NVIDIA GeForce GTX 560 Ti  
17:41:06:   Video Adapter Dedicated Video Memory: 1025966080
17:41:06:   Video Adapter Shared System Memory: 3220779008
17:41:06:   Video Adapter Output 1: pos={0, 0}, size={1680, 1050}, attached=true
17:41:06: =====Stream Start: 2014-02-09, 17:41:06===============================================
17:41:06:   Multithreaded optimizations: On
17:41:06:   Base resolution: 1920x1080
17:41:06:   Output resolution: 1280x720
17:41:06: ------------------------------------------
17:41:06: Loading up D3D10 on NVIDIA GeForce GTX 560 Ti (Adapter 1)...
17:41:06: ------------------------------------------
17:41:06: Audio Format: 44100hz
17:41:06: Playback device Default
17:41:06: ------------------------------------------
17:41:06: Using desktop audio input: Speakers (Creative SB X-Fi)
17:41:06: ------------------------------------------
17:41:06: Using auxilary audio input: Microphone (3- USB Audio Device)
17:41:06: ------------------------------------------
17:41:06: Audio Encoding: AAC
17:41:06:     bitrate: 160
17:41:06: Scene buffering time set to 700
17:41:06: Using custom x264 settings: "opencl=true subme=5"
17:41:06: ------------------------------------------
17:41:06: Video Encoding: x264
17:41:06:     fps: 60
17:41:06:     width: 1280, height: 720
17:41:06:     preset: fast
17:41:06:     profile: main
17:41:06:     keyint: 120
17:41:06:     CBR: yes
17:41:06:     CFR: yes
17:41:06:     max bitrate: 3000
17:41:06:     buffer size: 2000
17:41:06: ------------------------------------------
17:56:09: Using RTMP service: Twitch / Justin.tv
17:56:09:   Server selection: rtmp://live-iad.justin.tv/app
17:56:09:   Interface: Intel(R) 82579V Gigabit Network Connection (ethernet, 1000 mbps)
17:56:09: Completed handshake with rtmp://live-iad.justin.tv/app in 258 ms.
17:56:10: SO_SNDBUF was at 8192
17:56:10: SO_SNDBUF is now 65536
17:56:11: RTMPPublisher::SocketLoop: Increasing send buffer to ISB 131072 (buffer: 0 / 404480)
18:04:33: GetQPCTimeNS: WTF, clock went backwards! 6992931495 < 6992932404
18:06:56: GetQPCTimeNS: WTF, clock went backwards! 7466479314 < 7466479323
18:11:52: RTMPPublisher::SocketLoop: Increasing send buffer to ISB 262144 (buffer: 0 / 404480)
18:24:59: GetQPCTimeNS: WTF, clock went backwards! 11062711938 < 11062711947
18:39:46: GetQPCTimeNS: WTF, clock went backwards! 14006870925 < 14006870935
18:44:56: GetQPCTimeNS: WTF, clock went backwards! 15036536352 < 15036536362
18:56:23: GetQPCTimeNS: WTF, clock went backwards! 17318161684 < 17318161690
19:07:53: GetQPCTimeNS: WTF, clock went backwards! 19609745652 < 19609745662
19:49:41: GetQPCTimeNS: WTF, clock went backwards! 27937029438 < 27937029446
20:05:23: GetQPCTimeNS: WTF, clock went backwards! 31065029933 < 31065029941
20:09:22: GetQPCTimeNS: WTF, clock went backwards! 31860601400 < 31860601413
20:29:47: GetQPCTimeNS: WTF, clock went backwards! 35926672158 < 35926672167
20:58:53: GetQPCTimeNS: WTF, clock went backwards! 41725123169 < 41725123179
21:58:41: GetQPCTimeNS: WTF, clock went backwards! 53639283789 < 53639283791
22:04:22: GetQPCTimeNS: WTF, clock went backwards! 54769332860 < 54769332870
22:44:06: GetQPCTimeNS: WTF, clock went backwards! 62686206638 < 62686206647
22:48:07: GetQPCTimeNS: WTF, clock went backwards! 63487757522 < 63487757530
22:58:25: Total frames encoded: 1142301, total frames duplicated: 1835 (0.16%)
22:58:25: Total frames rendered: 1142300, number of late frames: 9 (0.00%) (it's okay for some frames to be late)
23:13:27: RTMPPublisher::SocketLoop: Graceful loop exit
23:13:27: Average send payload: 6918 bytes, average send interval: 17 ms
23:13:27: Number of times waited to send: 0, Waited for a total of 0 bytes
23:13:27: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
23:13:27: Number of bytes sent: 7543370826
23:13:27: 
23:13:27: Profiler time results:
23:13:27: 
23:13:27: ==============================================================
23:13:27: video thread frame - [100%] [avg time: 1.065 ms] [children: 4.79%] [unaccounted: 95.2%]
23:13:27: | scene->Preprocess - [0.0939%] [avg time: 0.001 ms]
23:13:27: | GPU download and conversion - [4.69%] [avg time: 0.05 ms] [children: 3.94%] [unaccounted: 0.751%]
23:13:27: | | flush - [2.25%] [avg time: 0.024 ms]
23:13:27: | | CopyResource - [1.5%] [avg time: 0.016 ms]
23:13:27: | | conversion to 4:2:0 - [0.188%] [avg time: 0.002 ms]
23:13:27: Convert444Threads - [100%] [avg time: 15.446 ms] [children: 2.6%] [unaccounted: 97.4%]
23:13:27: | Convert444toNV12 - [2.6%] [avg time: 0.401 ms]
23:13:27: encoder thread frame - [100%] [avg time: 1.613 ms]
23:13:27: ==============================================================
23:13:27: 
23:13:27: 
23:13:27: Profiler CPU results:
23:13:27: 
23:13:27: ==============================================================
23:13:27: video thread frame - [cpu time: avg 0.637 ms, total 728094 ms] [avg calls per frame: 1]
23:13:27: | scene->Preprocess - [cpu time: avg 0 ms, total 31.25 ms] [avg calls per frame: 1]
23:13:27: | GPU download and conversion - [cpu time: avg 0.026 ms, total 29828.1 ms] [avg calls per frame: 1]
23:13:27: | | flush - [cpu time: avg 0.013 ms, total 15078.1 ms] [avg calls per frame: 1]
23:13:27: | | CopyResource - [cpu time: avg 0.009 ms, total 10312.5 ms] [avg calls per frame: 1]
23:13:27: | | conversion to 4:2:0 - [cpu time: avg 0.001 ms, total 1359.38 ms] [avg calls per frame: 1]
23:13:27: Convert444Threads - [cpu time: avg 0.289 ms, total 662422 ms] [avg calls per frame: 2]
23:13:27: | Convert444toNV12 - [cpu time: avg 0.288 ms, total 660047 ms] [avg calls per frame: 2]
23:13:27: encoder thread frame - [cpu time: avg 1.029 ms, total 1.17541e+006 ms] [avg calls per frame: 1]
23:13:27: ==============================================================
23:13:27: 
23:13:27: =====Stream End: 2014-02-09, 23:13:27=================================================
23:13:54: Memory Leaks Were Detected.
23:13:54:

The times do not appear to correlate with the "GetQPCTimeNS: WTF, clock went backwards!" errors. Can anyone confirm that it is Twitch that is doing this?

Lain · Feb 10, 2014

Re: Twitch video glitch, on the hour.

I did not really see what you were referring to. I checked all the times in the video. What exactly happens? Or does it just happen so fast that I can't really even notice it?

Boildown · Feb 10, 2014

Re: Twitch video glitch, on the hour.

I recorded a video of me narrating it happen while streaming it from Twitch: https://anonfiles.com/file/ed784a39a424927f9ae8595f925130ea

But after that video I verified it happens on the half hour marks as well. So the problem is with Twitch's 30 minute chunks, or whatever they're called. It doesn't buffer past those times, not an OBS issue.

Lain · Feb 10, 2014

Re: Twitch video glitch, on the hour.

Ooooh, my apologies, I kept trying 2:04:00 instead of 2:00:04, I see the problem now. Yea, I'm almost totally positive this is a twitch-side buffering issue, because if I recally they split up their vod data in to 30 minute segments. Especially true if you don't see if happen in local file output.

Boildown · Feb 10, 2014

Re: Twitch video glitch, on the hour.

Jim said:
Ooooh, my apologies, I kept trying 2:04:00 instead of 2:00:04, I see the problem now. Yea, I'm almost totally positive this is a twitch-side buffering issue, because if I recally they split up their vod data in to 30 minute segments. Especially true if you don't see if happen in local file output.

My fault for saying "1:02ish, 2:04ish, 3:08ish, 4:10ish, and 5:18ish" instead of "1:00:02, 2:00:04", etc. Thanks for your time.

UberDragon · Feb 10, 2014

That's actually sometimes a really frustrating problem with twitch, when you want to highlight something during that time frame, but yeah, nothing we can do about here.

Bug Report [Solved] Twitch video glitch, on the hour.

Boildown

Active Member

Lain

Boildown

Active Member

Lain

Boildown

Active Member

UberDragon

Member