Bug Report Audio Out Of Sync

wapeddell · Feb 12, 2014

http://www.twitch.tv/wapeddell/b/502713243

Lain · Feb 12, 2014

Need to see a regular OBS log. Note that if you are using a capture device such as an elgato or LGP, you need to make sure "use buffering" is enabled in its properties.

wapeddell · Feb 12, 2014

Code:

21:41:04: CLR host plugin strings not found, dynamically loading 4 strings
21:41:04: CLRHost::Initialize() attempting to load and start the .NET runtime (null)
21:41:04: CLRHost::Initialize() Found version v2.0.50727 .NET runtime
21:41:04: CLRHost::Initialize() Found version v4.0.30319 .NET runtime
21:41:04: CLRHost::Initialize() attempting to use v4.0.30319 .NET runtime
21:41:04: CLRHost::LoadInteropLibrary() load the assembly plugins\CLRHostPlugin\CLRHost.Interop.dll
21:41:04: CLRHost::LoadPlugins() attempting to load the plugin assembly CLRBrowserSourcePlugin
21:41:05: CLRHost:: Could not find/load browser settings at location C:\Users\Admin\AppData\Roaming\OBS\pluginData\browser.json
21:41:05: CLRHost::LoadPlugins() successfully added CLR plugin [Type: CLRBrowserSourcePlugin.CLRBrowserSourcePlugin, Name: CLR Browser Source Plugin]
21:41:05: Video Source Plugin strings not found, dynamically loading 38 strings
21:41:08: Open Broadcaster Software v0.60b test build 9 - 32bit (´・ω・｀)
21:41:08: -------------------------------
21:41:08: CPU Name: AMD Phenom(tm) II X6 1090T Processor
21:41:08: CPU Speed: 3812MHz
21:41:08: Physical Memory:  4095MB Total, 4095MB Free
21:41:08: stepping id: 0, model 10, family 16, type 0, extmodel 8, extfamily 0, HTT 1, logical cores 4, total cores 4
21:41:08: monitor 1: pos={0, 0}, size={1920, 1080}
21:41:08: monitor 2: pos={-1920, 0}, size={1920, 1080}
21:41:08: Windows Version: 6.1 Build 7601 S
21:41:08: Aero is Disabled
21:41:08: -------------------------------
21:41:08: OBS Modules:
21:41:08: Base Address     Module
21:41:08: 00310000         OBS.exe
21:41:08: 6F8E0000         OBSApi.dll
21:41:08: 74580000         CLRHostPlugin.dll
21:41:08: 72E90000         DShowPlugin.dll
21:41:08: 742F0000         GraphicsCapture.dll
21:41:08: 74230000         NoiseGate.dll
21:41:08: 741E0000         PSVPlugin.dll
21:41:08: 741A0000         scenesw.dll
21:41:08: 74670000         VideoSourcePluginWrapper.dll
21:41:08: ------------------------------------------
21:41:08: Adapter 1
21:41:08:   Video Adapter: AMD Radeon HD 7900 Series
21:41:08:   Video Adapter Dedicated Video Memory: 3198189568
21:41:08:   Video Adapter Shared System Memory: 1096712192
21:41:08:   Video Adapter Output 1: pos={0, 0}, size={1920, 1080}, attached=true
21:41:08:   Video Adapter Output 2: pos={-1920, 0}, size={1920, 1080}, attached=true
21:41:08: =====Stream Start: 2014-02-11, 21:41:08===============================================
21:41:08:   Multithreaded optimizations: On
21:41:08:   Base resolution: 1280x720
21:41:08:   Output resolution: 1280x720
21:41:08: ------------------------------------------
21:41:08: Loading up D3D10 on AMD Radeon HD 7900 Series (Adapter 1)...
21:41:08: ------------------------------------------
21:41:08: Audio Format: 44100hz
21:41:08: Playback device Default
21:41:08: ------------------------------------------
21:41:08: Using desktop audio input: 1 - AVERMEDIA HD  (AMD High Definition Audio Device)
21:41:08: ------------------------------------------
21:41:08: Using auxilary audio input: Microphone (2- Wireless Stereo Headset)
21:41:08: ------------------------------------------
21:41:08: Audio Encoding: AAC
21:41:08:     bitrate: 96
21:41:08: Using bitmap image
21:41:08: Using bitmap image
21:41:08: ------------------------------------------
21:41:08:     device: Logitech HD Webcam C525,
21:41:08:     device id \\?\usb#vid_046d&pid_0826&mi_02#6&adae5f7&0&0002#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
21:41:08:     chosen type: I420, usingFourCC: false, res: 1280x720 - 1280x720, frameIntervals: 333333-2000000
21:41:08:     use buffering: false - 0, fourCC: 'I420'
21:41:08:     audio device: Disable,
21:41:08:     audio device id Disabled,
21:41:08: 
21:41:08: Using directshow input
21:41:08: Using bitmap image
21:41:08: ------------------------------------------
21:41:08:     device: AVerMedia HD Capture C985 Bus 2,
21:41:08:     device id \\?\pci#ven_1af2&dev_a001&subsys_a0011af2&rev_00#4&28b85f88&0&00a9#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{957bd672-7934-4b55-b81a-3e22b14c679a},
21:41:08:     chosen type: YV12, usingFourCC: true, res: 1280x720 - 1280x720, frameIntervals: 166666-666666
21:41:08:     use buffering: false - 30000000, fourCC: 'YV12'
21:41:08: 
21:41:08:     device audio info - bits per sample: 16, channels: 2, samples per sec: 48000, block size: 4
21:41:08: Using directshow input
21:41:08: Using bitmap image
21:41:08: Using bitmap image
21:41:08: Using Video Source
21:41:08: Using Video Source
21:41:08: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
21:41:08: Scene buffering time set to 700
21:41:08: ------------------------------------------
21:41:08: Video Encoding: x264
21:41:08:     fps: 30
21:41:08:     width: 1280, height: 720
21:41:08:     preset: veryfast
21:41:08:     profile: main
21:41:08:     keyint: 250
21:41:08:     CBR: yes
21:41:08:     CFR: yes
21:41:08:     max bitrate: 2500
21:41:08:     buffer size: 2500
21:41:08: ------------------------------------------
21:41:08: Syncing audio to video time (WARNING: you should not be doing this if you are just having webcam desync, that's a separate issue)
21:41:09: Audio timestamp for device 'Microphone (2- Wireless Stereo Headset)' was behind target timestamp by 102415564
21:41:12: CLRHost:: Browser 1: Status message: 
21:41:12: CLRHost:: Browser 1: Grabbing offset: 0 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
21:41:14: CLRHost:: Browser 1: Grabbing offset: 100 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
21:41:15: Total frames encoded: 172, total frames duplicated: 12 (6.98%)
21:41:15: Total frames rendered: 178, number of late frames: 0 (0.00%) (it's okay for some frames to be late)
21:41:16: 
21:41:16: Profiler time results:
21:41:16: 
21:41:16: ==============================================================
21:41:16: video thread frame - [100%] [avg time: 12.794 ms] [children: 43.2%] [unaccounted: 56.8%]
21:41:16: | scene->Preprocess - [38.5%] [avg time: 4.93 ms]
21:41:16: | GPU download and conversion - [4.67%] [avg time: 0.598 ms] [children: 4.37%] [unaccounted: 0.305%]
21:41:16: | | flush - [4.14%] [avg time: 0.53 ms]
21:41:16: | | CopyResource - [0.149%] [avg time: 0.019 ms]
21:41:16: | | conversion to 4:2:0 - [0.0782%] [avg time: 0.01 ms]
21:41:16: Convert444Threads - [100%] [avg time: 1.154 ms] [children: 99.3%] [unaccounted: 0.693%]
21:41:16: | Convert444toNV12 - [99.3%] [avg time: 1.146 ms]
21:41:16: encoder thread frame - [100%] [avg time: 1.895 ms]
21:41:16: ==============================================================
21:41:16: 
21:41:16: 
21:41:16: Profiler CPU results:
21:41:16: 
21:41:16: ==============================================================
21:41:16: video thread frame - [cpu time: avg 5.871 ms, total 1045.21 ms] [avg calls per frame: 1]
21:41:16: | scene->Preprocess - [cpu time: avg 4.732 ms, total 842.403 ms] [avg calls per frame: 1]
21:41:16: | GPU download and conversion - [cpu time: avg 0.087 ms, total 15.6 ms] [avg calls per frame: 1]
21:41:16: | | flush - [cpu time: avg 0.087 ms, total 15.6 ms] [avg calls per frame: 1]
21:41:16: | | CopyResource - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
21:41:16: | | conversion to 4:2:0 - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
21:41:16: Convert444Threads - [cpu time: avg 0.815 ms, total 249.601 ms] [avg calls per frame: 2]
21:41:16: | Convert444toNV12 - [cpu time: avg 0.815 ms, total 249.601 ms] [avg calls per frame: 2]
21:41:16: encoder thread frame - [cpu time: avg 1.446 ms, total 218.401 ms] [avg calls per frame: 1]
21:41:16: ==============================================================
21:41:16: 
21:41:16: =====Stream End: 2014-02-11, 21:41:16=================================================
21:41:17: =====Stream Start: 2014-02-11, 21:41:17===============================================
21:41:17:   Multithreaded optimizations: On
21:41:17:   Base resolution: 1280x720
21:41:17:   Output resolution: 1280x720
21:41:17: ------------------------------------------
21:41:17: Loading up D3D10 on AMD Radeon HD 7900 Series (Adapter 1)...
21:41:17: ------------------------------------------
21:41:17: Audio Format: 44100hz
21:41:17: Playback device Default
21:41:17: ------------------------------------------
21:41:17: Using desktop audio input: 1 - AVERMEDIA HD  (AMD High Definition Audio Device)
21:41:17: ------------------------------------------
21:41:17: Using auxilary audio input: Microphone (2- Wireless Stereo Headset)
21:41:17: ------------------------------------------
21:41:17: Audio Encoding: AAC
21:41:17:     bitrate: 96
21:41:17: Using bitmap image
21:41:17: Using bitmap image
21:41:17: ------------------------------------------
21:41:17:     device: Logitech HD Webcam C525,
21:41:17:     device id \\?\usb#vid_046d&pid_0826&mi_02#6&adae5f7&0&0002#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
21:41:17:     chosen type: I420, usingFourCC: false, res: 1280x720 - 1280x720, frameIntervals: 333333-2000000
21:41:17:     use buffering: false - 0, fourCC: 'I420'
21:41:17:     audio device: Disable,
21:41:17:     audio device id Disabled,
21:41:17: 
21:41:17: Using directshow input
21:41:17: Using bitmap image
21:41:17: ------------------------------------------
21:41:17:     device: AVerMedia HD Capture C985 Bus 2,
21:41:17:     device id \\?\pci#ven_1af2&dev_a001&subsys_a0011af2&rev_00#4&28b85f88&0&00a9#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{957bd672-7934-4b55-b81a-3e22b14c679a},
21:41:17:     chosen type: YV12, usingFourCC: true, res: 1280x720 - 1280x720, frameIntervals: 166666-666666
21:41:17:     use buffering: false - 30000000, fourCC: 'YV12'
21:41:17: 
21:41:17:     device audio info - bits per sample: 16, channels: 2, samples per sec: 48000, block size: 4
21:41:17: Using directshow input
21:41:17: Using bitmap image
21:41:17: Using bitmap image
21:41:17: Using Video Source
21:41:17: Using Video Source
21:41:17: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
21:41:18: CLRHost:: Browser 2: Status message: 
21:41:18: Scene buffering time set to 700
21:41:18: ------------------------------------------
21:41:18: Video Encoding: x264
21:41:18:     fps: 30
21:41:18:     width: 1280, height: 720
21:41:18:     preset: veryfast
21:41:18:     profile: main
21:41:18:     keyint: 250
21:41:18:     CBR: yes
21:41:18:     CFR: yes
21:41:18:     max bitrate: 2500
21:41:18:     buffer size: 2500
21:41:18: ------------------------------------------
21:41:18: Syncing audio to video time (WARNING: you should not be doing this if you are just having webcam desync, that's a separate issue)
21:41:18: 
21:41:18: Profiler result for the last frame:
21:41:18: ==============================================================
21:41:18: video thread frame - [time: 29.981 ms (cpu time: 15.6 ms)]
21:41:18: | scene->Preprocess - [time: 0.035 ms (cpu time: 0 ms)]
21:41:18: | GPU download and conversion - [time: 1.942 ms (cpu time: 0 ms)]
21:41:18: | | flush - [time: 0.031 ms (cpu time: 0 ms)]
21:41:18: ==============================================================
21:41:18: 
21:41:18: CLRHost:: Browser 2: Grabbing offset: 0 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
21:41:18: Audio timestamp for device 'Microphone (2- Wireless Stereo Headset)' was behind target timestamp by 102424714
21:41:19: CLRHost:: Browser 2: Grabbing offset: 100 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
21:41:20: Using RTMP service: Twitch / Justin.tv
21:41:20:   Server selection: rtmp://live-iad.justin.tv/app
21:41:20:   Interface: Realtek RTL8139/810x Family Fast Ethernet NIC (ethernet, 100 mbps)
21:41:21: Completed handshake with rtmp://live-iad.justin.tv/app in 532 ms.
21:41:21: CLRHost:: Browser 2: Grabbing offset: 200 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
21:41:21: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:41:21:   New Scene
21:41:22: SO_SNDBUF was at 8192
21:41:22: SO_SNDBUF is now 65536
21:41:22: Using text output
21:41:22: Using bitmap image
21:41:22: Using bitmap image
21:41:22: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
21:41:22: CLRHost:: Browser 3: Status message: 
21:41:22: CLRHost:: Browser 3: Grabbing offset: 0 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
21:41:22: Using bitmap image
21:41:22: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
21:41:23: Using bitmap image
21:41:23: Using bitmap image
21:41:23: Using bitmap image
21:41:23: Using bitmap image
21:41:23: Using Video Source
21:41:23: Using Video Source
21:41:23: PERFORMANCE WARNING: Scene change took 1773 ms, maybe some sources should be global sources?
21:41:24: CLRHost:: Browser 3: Grabbing offset: 100 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
21:41:24: RTMPPublisher::SocketLoop: Increasing send buffer to ISB 131072 (buffer: 1623 / 331776)
21:41:24: 
21:41:24: Profiler result for the last frame:
21:41:24: ==============================================================
21:41:24: video thread frame - [time: 5.365 ms (cpu time: 0 ms)]
21:41:24: | scene->Preprocess - [time: 0.048 ms (cpu time: 0 ms)]
21:41:24: | GPU download and conversion - [time: 0.172 ms (cpu time: 0 ms)]
21:41:24: | | flush - [time: 0.115 ms (cpu time: 0 ms)]
21:41:24: | | CopyResource - [time: 0.011 ms (cpu time: 0 ms)]
21:41:24: | | conversion to 4:2:0 - [time: 0.015 ms (cpu time: 0 ms)]
21:41:24: Convert444Threads - [time: 1.031 ms (cpu time: 0 ms)]
21:41:24: | Convert444toNV12 - [time: 1.02 ms (cpu time: 0 ms)]
21:41:24: encoder thread frame - [time: 1.418 ms (cpu time: 0 ms)]
21:41:24: ==============================================================
21:41:24: 
21:41:25: 
21:41:25: Profiler result for the last frame:
21:41:25: ==============================================================
21:41:25: video thread frame - [time: 7.96 ms (cpu time: 0 ms)]
21:41:25: | scene->Preprocess - [time: 0.044 ms (cpu time: 0 ms)]
21:41:25: | GPU download and conversion - [time: 1.193 ms (cpu time: 0 ms)]
21:41:25: | | flush - [time: 1.162 ms (cpu time: 0 ms)]
21:41:25: | | CopyResource - [time: 0.012 ms (cpu time: 0 ms)]
21:41:25: | | conversion to 4:2:0 - [time: 0.004 ms (cpu time: 0 ms)]
21:41:25: Convert444Threads - [time: 1.056 ms (cpu time: 0 ms)]
21:41:25: | Convert444toNV12 - [time: 1.049 ms (cpu time: 0 ms)]
21:41:25: encoder thread frame - [time: 1.434 ms (cpu time: 0 ms)]
21:41:25: ==============================================================
21:41:25: 
21:41:25: 
21:41:25: Profiler result for the last frame:
21:41:25: ==============================================================
21:41:25: video thread frame - [time: 8.15 ms (cpu time: 0 ms)]
21:41:25: | scene->Preprocess - [time: 0.022 ms (cpu time: 0 ms)]
21:41:25: | GPU download and conversion - [time: 0.556 ms (cpu time: 0 ms)]
21:41:25: | | flush - [time: 0.521 ms (cpu time: 0 ms)]
21:41:25: | | CopyResource - [time: 0.01 ms (cpu time: 0 ms)]
21:41:25: | | conversion to 4:2:0 - [time: 0.003 ms (cpu time: 0 ms)]
21:41:25: Convert444Threads - [time: 0.903 ms (cpu time: 0 ms)]
21:41:25: | Convert444toNV12 - [time: 0.897 ms (cpu time: 0 ms)]
21:41:25: encoder thread frame - [time: 1.377 ms (cpu time: 0 ms)]
21:41:25: ==============================================================
21:41:25: 
21:41:25: 
21:41:25: Profiler result for the last frame:
21:41:25: ==============================================================
21:41:25: video thread frame - [time: 8.643 ms (cpu time: 0 ms)]
21:41:25: | scene->Preprocess - [time: 0.043 ms (cpu time: 0 ms)]
21:41:25: | GPU download and conversion - [time: 0.103 ms (cpu time: 0 ms)]
21:41:25: | | flush - [time: 0.078 ms (cpu time: 0 ms)]
21:41:25: | | CopyResource - [time: 0.009 ms (cpu time: 0 ms)]
21:41:25: | | conversion to 4:2:0 - [time: 0.003 ms (cpu time: 0 ms)]
21:41:25: Convert444Threads - [time: 0.966 ms (cpu time: 0 ms)]
21:41:25: | Convert444toNV12 - [time: 0.961 ms (cpu time: 0 ms)]
21:41:25: encoder thread frame - [time: 1.513 ms (cpu time: 0 ms)]
21:41:25: ==============================================================
21:41:25: 
21:41:25: CLRHost:: Browser 3: Grabbing offset: 200 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
21:41:36: Audio timestamp for device 'Microphone (2- Wireless Stereo Headset)' was behind target timestamp by 90
21:41:36: Audio timestamp for device 'Microphone (2- Wireless Stereo Headset)' was behind target timestamp by 110
21:41:36: Audio timestamp for device 'Microphone (2- Wireless Stereo Headset)' was behind target timestamp by 120
21:41:39: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:41:39:   New Scene
21:41:39: Using bitmap image
21:41:39: Using bitmap image
21:41:39: ------------------------------------------
21:41:39:     device: Logitech HD Webcam C525,
21:41:39:     device id \\?\usb#vid_046d&pid_0826&mi_02#6&adae5f7&0&0002#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
21:41:39:     chosen type: I420, usingFourCC: false, res: 1280x720 - 1280x720, frameIntervals: 333333-2000000
21:41:39:     use buffering: false - 0, fourCC: 'I420'
21:41:39:     audio device: Disable,
21:41:39:     audio device id Disabled,
21:41:39: 
21:41:39: Using directshow input
21:41:39: Using bitmap image
21:41:39: ------------------------------------------
21:41:39:     device: AVerMedia HD Capture C985 Bus 2,
21:41:39:     device id \\?\pci#ven_1af2&dev_a001&subsys_a0011af2&rev_00#4&28b85f88&0&00a9#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{957bd672-7934-4b55-b81a-3e22b14c679a},
21:41:39:     chosen type: YV12, usingFourCC: true, res: 1280x720 - 1280x720, frameIntervals: 166666-666666
21:41:39:     use buffering: false - 30000000, fourCC: 'YV12'
21:41:39: 
21:41:39:     device audio info - bits per sample: 16, channels: 2, samples per sec: 48000, block size: 4
21:41:40: Using directshow input
21:41:40: Using bitmap image
21:41:40: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
21:41:40: Using bitmap image
21:41:40: Using Video Source
21:41:40: Using Video Source
21:41:40: CLRHost:: Browser 4: Status message: 
21:41:40: PERFORMANCE WARNING: Scene change took 775 ms, maybe some sources should be global sources?
21:41:40: CLRHost:: Browser 4: Grabbing offset: 0 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
21:41:42: CLRHost:: Browser 4: Grabbing offset: 100 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
21:41:43: CLRHost:: Browser 4: Grabbing offset: 200 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
21:48:30: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:48:30:   New Scene
21:48:32: Using text output
21:48:32: Using bitmap image
21:48:32: Using bitmap image
21:48:32: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
21:48:32: Using bitmap image
21:48:32: CLRHost:: Browser 5: Status message: 
21:48:32: CLRHost:: Browser 5: Uncaught ReferenceError: $ is not defined @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248290
21:48:32: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
21:48:32: Using bitmap image
21:48:32: Using bitmap image
21:48:32: Using bitmap image
21:48:33: Using bitmap image
21:48:33: Using Video Source
21:48:33: Using Video Source
21:48:33: PERFORMANCE WARNING: Scene change took 2768 ms, maybe some sources should be global sources?
21:52:36: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
21:52:36:   New Scene
21:52:36: Using bitmap image
21:52:36: Using bitmap image
21:52:36: ------------------------------------------
21:52:36:     device: Logitech HD Webcam C525,
21:52:36:     device id \\?\usb#vid_046d&pid_0826&mi_02#6&adae5f7&0&0002#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
21:52:36:     chosen type: I420, usingFourCC: false, res: 1280x720 - 1280x720, frameIntervals: 333333-2000000
21:52:36:     use buffering: false - 0, fourCC: 'I420'
21:52:36:     audio device: Disable,
21:52:36:     audio device id Disabled,
21:52:36: 
21:52:36: Using directshow input
21:52:36: Using bitmap image
21:52:36: ------------------------------------------
21:52:36:     device: AVerMedia HD Capture C985 Bus 2,
21:52:36:     device id \\?\pci#ven_1af2&dev_a001&subsys_a0011af2&rev_00#4&28b85f88&0&00a9#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{957bd672-7934-4b55-b81a-3e22b14c679a},
21:52:36:     chosen type: YV12, usingFourCC: true, res: 1280x720 - 1280x720, frameIntervals: 166666-666666
21:52:36:     use buffering: false - 30000000, fourCC: 'YV12'
21:52:36: 
21:52:36:     device audio info - bits per sample: 16, channels: 2, samples per sec: 48000, block size: 4
21:52:36: Using directshow input
21:52:36: Using bitmap image
21:52:36: Using bitmap image
21:52:36: Using Video Source
21:52:36: Using Video Source
21:52:36: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
21:52:37: PERFORMANCE WARNING: Scene change took 798 ms, maybe some sources should be global sources?
21:52:37: CLRHost:: Browser 6: Status message: 
21:52:37: CLRHost:: Browser 6: Grabbing offset: 0 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
21:52:39: CLRHost:: Browser 6: Grabbing offset: 100 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
21:52:41: CLRHost:: Browser 6: Grabbing offset: 200 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
21:55:59: Audio timestamp for device 'AVerMedia HD Capture C985 Bus 2' was behind target timestamp by 70
22:08:40: Audio timestamp for device 'Microphone (2- Wireless Stereo Headset)' was behind target timestamp by 140
22:14:09: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
22:14:09: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
22:15:28: Audio timestamp for device 'Microphone (2- Wireless Stereo Headset)' was behind target timestamp by 150
22:15:33: Total frames encoded: 61636, total frames duplicated: 2827 (4.59%)
22:15:33: Number of frames skipped due to encoder lag: 35 (0.06%)
22:15:33: Total frames rendered: 61282, number of late frames: 60 (0.10%) (it's okay for some frames to be late)
22:15:34: RTMPPublisher::SocketLoop: Graceful loop exit
22:15:34: Average send payload: 7540 bytes, average send interval: 23 ms
22:15:34: Number of times waited to send: 0, Waited for a total of 0 bytes
22:15:34: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
22:15:34: Number of bytes sent: 667602114
22:15:34: 
22:15:34: Profiler time results:
22:15:34: 
22:15:34: ==============================================================
22:15:34: video thread frame - [100%] [avg time: 7.647 ms] [children: 39.8%] [unaccounted: 60.2%]
22:15:34: | scene->Preprocess - [32.9%] [avg time: 2.513 ms]
22:15:34: | GPU download and conversion - [6.98%] [avg time: 0.534 ms] [children: 6.04%] [unaccounted: 0.942%]
22:15:34: | | flush - [5.49%] [avg time: 0.42 ms]
22:15:34: | | CopyResource - [0.484%] [avg time: 0.037 ms]
22:15:34: | | conversion to 4:2:0 - [0.0654%] [avg time: 0.005 ms]
22:15:34: Convert444Threads - [100%] [avg time: 1.215 ms] [children: 99%] [unaccounted: 0.988%]
22:15:34: | Convert444toNV12 - [99%] [avg time: 1.203 ms]
22:15:34: encoder thread frame - [100%] [avg time: 2.122 ms]
22:15:34: ==============================================================
22:15:34: 
22:15:34: 
22:15:34: Profiler CPU results:
22:15:34: 
22:15:34: ==============================================================
22:15:34: video thread frame - [cpu time: avg 2.763 ms, total 169370 ms] [avg calls per frame: 1]
22:15:34: | scene->Preprocess - [cpu time: avg 1.806 ms, total 110730 ms] [avg calls per frame: 1]
22:15:34: | GPU download and conversion - [cpu time: avg 0.078 ms, total 4820.44 ms] [avg calls per frame: 1]
22:15:34: | | flush - [cpu time: avg 0.05 ms, total 3088.83 ms] [avg calls per frame: 1]
22:15:34: | | CopyResource - [cpu time: avg 0.01 ms, total 655.204 ms] [avg calls per frame: 1]
22:15:34: | | conversion to 4:2:0 - [cpu time: avg 0.003 ms, total 234.001 ms] [avg calls per frame: 1]
22:15:34: Convert444Threads - [cpu time: avg 0.955 ms, total 117079 ms] [avg calls per frame: 2]
22:15:34: | Convert444toNV12 - [cpu time: avg 0.951 ms, total 116548 ms] [avg calls per frame: 2]
22:15:34: encoder thread frame - [cpu time: avg 1.359 ms, total 83741.3 ms] [avg calls per frame: 1]
22:15:34: ==============================================================
22:15:34: 
22:15:34: =====Stream End: 2014-02-11, 22:15:34=================================================
22:15:35: =====Stream Start: 2014-02-11, 22:15:35===============================================
22:15:35:   Multithreaded optimizations: On
22:15:35:   Base resolution: 1280x720
22:15:35:   Output resolution: 1280x720
22:15:35: ------------------------------------------
22:15:35: Loading up D3D10 on AMD Radeon HD 7900 Series (Adapter 1)...
22:15:35: ------------------------------------------
22:15:35: Audio Format: 44100hz
22:15:35: Playback device Default
22:15:35: ------------------------------------------
22:15:35: Using desktop audio input: Speakers (2- Wireless Stereo Headset)
22:15:35: ------------------------------------------
22:15:35: Using auxilary audio input: Microphone (2- Wireless Stereo Headset)
22:15:35: ------------------------------------------
22:15:35: Audio Encoding: AAC
22:15:35:     bitrate: 96
22:15:35: Using bitmap image
22:15:35: Using bitmap image
22:15:35: ------------------------------------------
22:15:35:     device: Logitech HD Webcam C525,
22:15:35:     device id \\?\usb#vid_046d&pid_0826&mi_02#6&adae5f7&0&0002#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
22:15:35:     chosen type: I420, usingFourCC: false, res: 1280x720 - 1280x720, frameIntervals: 333333-2000000
22:15:35:     use buffering: false - 0, fourCC: 'I420'
22:15:35:     audio device: Disable,
22:15:35:     audio device id Disabled,
22:15:35: 
22:15:36: Using directshow input
22:15:36: Using bitmap image
22:15:36: ------------------------------------------
22:15:36:     device: AVerMedia HD Capture C985 Bus 2,
22:15:36:     device id \\?\pci#ven_1af2&dev_a001&subsys_a0011af2&rev_00#4&28b85f88&0&00a9#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{957bd672-7934-4b55-b81a-3e22b14c679a},
22:15:36:     chosen type: YV12, usingFourCC: true, res: 1280x720 - 1280x720, frameIntervals: 166666-666666
22:15:36:     use buffering: false - 30000000, fourCC: 'YV12'
22:15:36: 
22:15:36:     device audio info - bits per sample: 16, channels: 2, samples per sec: 48000, block size: 4
22:15:36: Using directshow input
22:15:36: Using bitmap image
22:15:36: Using bitmap image
22:15:36: Using Video Source
22:15:36: Using Video Source
22:15:36: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
22:15:36: Scene buffering time set to 700
22:15:36: ------------------------------------------
22:15:36: Video Encoding: x264
22:15:36:     fps: 30
22:15:36:     width: 1280, height: 720
22:15:36:     preset: veryfast
22:15:36:     profile: main
22:15:36:     keyint: 250
22:15:36:     CBR: yes
22:15:36:     CFR: yes
22:15:36:     max bitrate: 2500
22:15:36:     buffer size: 2500
22:15:36: ------------------------------------------
22:15:36: Syncing audio to video time (WARNING: you should not be doing this if you are just having webcam desync, that's a separate issue)
22:15:36: CLRHost:: Browser 7: Status message: 
22:15:36: CLRHost:: Browser 7: Uncaught ReferenceError: $ is not defined @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248290
22:15:37: Audio timestamp for device 'Microphone (2- Wireless Stereo Headset)' was behind target timestamp by 104482986
22:15:37: 
22:15:37: Profiler result for the last frame:
22:15:37: ==============================================================
22:15:37: video thread frame - [time: 1181.48 ms (cpu time: 1076.41 ms)]
22:15:37: | scene->Preprocess - [time: 0.041 ms (cpu time: 0 ms)]
22:15:37: | GPU download and conversion - [time: 0.16 ms (cpu time: 0 ms)]
22:15:37: | | flush - [time: 0.136 ms (cpu time: 0 ms)]
22:15:37: | | CopyResource - [time: 0.008 ms (cpu time: 0 ms)]
22:15:37: ==============================================================
22:15:37: 
22:15:37: 
22:15:37: Profiler result for the last frame:
22:15:37: ==============================================================
22:15:37: video thread frame - [time: 18.905 ms (cpu time: 15.601 ms)]
22:15:37: | scene->Preprocess - [time: 13.952 ms (cpu time: 15.601 ms)]
22:15:37: | GPU download and conversion - [time: 0.326 ms (cpu time: 0 ms)]
22:15:37: | | flush - [time: 0.296 ms (cpu time: 0 ms)]
22:15:37: | | CopyResource - [time: 0.002 ms (cpu time: 0 ms)]
22:15:37: | | conversion to 4:2:0 - [time: 0.003 ms (cpu time: 0 ms)]
22:15:37: ==============================================================
22:15:37: 
22:15:37: Total frames encoded: 1, total frames duplicated: 0 (0.00%)
22:15:37: Total frames rendered: 5, number of late frames: 2 (40.00%) (it's okay for some frames to be late)
22:15:38: Number of times waited to send: 0, Waited for a total of 0 bytes
22:15:38: Number of b-frames dropped: 0 (0%), Number of p-frames dropped: 0 (0%), Total 0 (0%)
22:15:38: Number of bytes sent: 0
22:15:38: 
22:15:38: Profiler time results:
22:15:38: 
22:15:38: ==============================================================
22:15:38: video thread frame - [100%] [avg time: 251.347 ms] [children: 2.41%] [unaccounted: 97.6%]
22:15:38: | scene->Preprocess - [2.34%] [avg time: 5.892 ms]
22:15:38: | GPU download and conversion - [0.0684%] [avg time: 0.172 ms] [children: 0.0617%] [unaccounted: 0.00676%]
22:15:38: | | flush - [0.0577%] [avg time: 0.145 ms]
22:15:38: | | CopyResource - [0.00278%] [avg time: 0.007 ms]
22:15:38: | | conversion to 4:2:0 - [0.00119%] [avg time: 0.003 ms]
22:15:38: Convert444Threads - [100%] [avg time: 3.095 ms] [children: 99.6%] [unaccounted: 0.355%]
22:15:38: | Convert444toNV12 - [99.6%] [avg time: 3.084 ms]
22:15:38: encoder thread frame - [100%] [avg time: 0.03 ms]
22:15:38: ==============================================================
22:15:38: 
22:15:38: 
22:15:38: Profiler CPU results:
22:15:38: 
22:15:38: ==============================================================
22:15:38: video thread frame - [cpu time: avg 221.521 ms, total 1107.61 ms] [avg calls per frame: 1]
22:15:38: | scene->Preprocess - [cpu time: avg 6.24 ms, total 31.201 ms] [avg calls per frame: 1]
22:15:38: | GPU download and conversion - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
22:15:38: | | flush - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
22:15:38: | | CopyResource - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
22:15:38: | | conversion to 4:2:0 - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
22:15:38: Convert444Threads - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 2]
22:15:38: | Convert444toNV12 - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 2]
22:15:38: encoder thread frame - [cpu time: avg 0 ms, total 0 ms] [avg calls per frame: 1]
22:15:38: ==============================================================
22:15:38: 
22:15:38: =====Stream End: 2014-02-11, 22:15:38=================================================
22:15:39: =====Stream Start: 2014-02-11, 22:15:39===============================================
22:15:39:   Multithreaded optimizations: On
22:15:39:   Base resolution: 1280x720
22:15:39:   Output resolution: 1280x720
22:15:39: ------------------------------------------
22:15:39: Loading up D3D10 on AMD Radeon HD 7900 Series (Adapter 1)...
22:15:39: ------------------------------------------
22:15:39: Audio Format: 44100hz
22:15:39: Playback device Default
22:15:39: ------------------------------------------
22:15:39: Using desktop audio input: Speakers (2- Wireless Stereo Headset)
22:15:39: ------------------------------------------
22:15:39: Using auxilary audio input: Microphone (2- Wireless Stereo Headset)
22:15:39: ------------------------------------------
22:15:39: Audio Encoding: AAC
22:15:39:     bitrate: 96
22:15:39: Using bitmap image
22:15:39: Using bitmap image
22:15:39: ------------------------------------------
22:15:39:     device: Logitech HD Webcam C525,
22:15:39:     device id \\?\usb#vid_046d&pid_0826&mi_02#6&adae5f7&0&0002#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
22:15:39:     chosen type: I420, usingFourCC: false, res: 1280x720 - 1280x720, frameIntervals: 333333-2000000
22:15:39:     use buffering: false - 0, fourCC: 'I420'
22:15:39:     audio device: Disable,
22:15:39:     audio device id Disabled,
22:15:39: 
22:15:39: Using directshow input
22:15:39: Using bitmap image
22:15:39: ------------------------------------------
22:15:39:     device: AVerMedia HD Capture C985 Bus 2,
22:15:39:     device id \\?\pci#ven_1af2&dev_a001&subsys_a0011af2&rev_00#4&28b85f88&0&00a9#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{957bd672-7934-4b55-b81a-3e22b14c679a},
22:15:39:     chosen type: YV12, usingFourCC: true, res: 1280x720 - 1280x720, frameIntervals: 166666-666666
22:15:39:     use buffering: false - 30000000, fourCC: 'YV12'
22:15:39: 
22:15:39:     device audio info - bits per sample: 16, channels: 2, samples per sec: 48000, block size: 4
22:15:39: Using directshow input
22:15:39: Using bitmap image
22:15:39: Using bitmap image
22:15:39: Using Video Source
22:15:39: Using Video Source
22:15:39: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
22:15:39: CLRHost:: Browser 8: Status message: 
22:15:40: Scene buffering time set to 700
22:15:40: ------------------------------------------
22:15:40: Video Encoding: x264
22:15:40:     fps: 30
22:15:40:     width: 1280, height: 720
22:15:40:     preset: veryfast
22:15:40:     profile: main
22:15:40:     keyint: 250
22:15:40:     CBR: yes
22:15:40:     CFR: yes
22:15:40:     max bitrate: 2500
22:15:40:     buffer size: 2500
22:15:40: ------------------------------------------
22:15:40: Syncing audio to video time (WARNING: you should not be doing this if you are just having webcam desync, that's a separate issue)
22:15:40: CLRHost:: Browser 8: Grabbing offset: 0 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
22:15:41: 
22:15:41: Profiler result for the last frame:
22:15:41: ==============================================================
22:15:41: video thread frame - [time: 32.284 ms (cpu time: 0 ms)]
22:15:41: | scene->Preprocess - [time: 6.291 ms (cpu time: 0 ms)]
22:15:41: | GPU download and conversion - [time: 2.061 ms (cpu time: 0 ms)]
22:15:41: | | flush - [time: 2.025 ms (cpu time: 0 ms)]
22:15:41: | | CopyResource - [time: 0.003 ms (cpu time: 0 ms)]
22:15:41: | | conversion to 4:2:0 - [time: 0.004 ms (cpu time: 0 ms)]
22:15:41: Convert444Threads - [time: 1.505 ms (cpu time: 0 ms)]
22:15:41: | Convert444toNV12 - [time: 1.497 ms (cpu time: 0 ms)]
22:15:41: ==============================================================
22:15:41: 
22:15:42: CLRHost:: Browser 8: Grabbing offset: 100 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
22:15:42: Using RTMP service: Twitch / Justin.tv
22:15:42:   Server selection: rtmp://live-iad.justin.tv/app
22:15:42:   Interface: Realtek RTL8139/810x Family Fast Ethernet NIC (ethernet, 100 mbps)
22:15:43: Completed handshake with rtmp://live-iad.justin.tv/app in 475 ms.
22:15:43: CLRHost:: Browser 8: Grabbing offset: 200 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
22:15:43: SO_SNDBUF was at 8192
22:15:43: SO_SNDBUF is now 65536
22:15:45: Audio timestamp for device 'AVerMedia HD Capture C985 Bus 2' was behind target timestamp by 90
22:15:45: RTMPPublisher::SocketLoop: Increasing send buffer to ISB 131072 (buffer: 34060 / 331776)
22:21:03: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
22:21:03:   New Scene
22:21:04: Using text output
22:21:04: Using bitmap image
22:21:04: Using bitmap image
22:21:04: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
22:21:05: CLRHost:: Browser 9: Status message: 
22:21:05: Using bitmap image
22:21:05: CLRHost:: Browser 9: Grabbing offset: 0 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
22:21:05: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
22:21:05: Using bitmap image
22:21:05: Using bitmap image
22:21:05: Using bitmap image
22:21:05: Using bitmap image
22:21:05: Using Video Source
22:21:05: Using Video Source
22:21:05: PERFORMANCE WARNING: Scene change took 1889 ms, maybe some sources should be global sources?
22:21:05: CLRHost:: Browser 9: Grabbing offset: 100 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
22:21:07: CLRHost:: Browser 9: Grabbing offset: 200 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
22:24:08: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
22:24:08:   New Scene
22:24:09: Using bitmap image
22:24:09: Using bitmap image
22:24:09: ------------------------------------------
22:24:09:     device: Logitech HD Webcam C525,
22:24:09:     device id \\?\usb#vid_046d&pid_0826&mi_02#6&adae5f7&0&0002#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
22:24:09:     chosen type: I420, usingFourCC: false, res: 1280x720 - 1280x720, frameIntervals: 333333-2000000
22:24:09:     use buffering: false - 0, fourCC: 'I420'
22:24:09:     audio device: Disable,
22:24:09:     audio device id Disabled,
22:24:09: 
22:24:09: Using directshow input
22:24:09: Using bitmap image
22:24:09: ------------------------------------------
22:24:09:     device: AVerMedia HD Capture C985 Bus 2,
22:24:09:     device id \\?\pci#ven_1af2&dev_a001&subsys_a0011af2&rev_00#4&28b85f88&0&00a9#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{957bd672-7934-4b55-b81a-3e22b14c679a},
22:24:09:     chosen type: YV12, usingFourCC: true, res: 1280x720 - 1280x720, frameIntervals: 166666-666666
22:24:09:     use buffering: false - 30000000, fourCC: 'YV12'
22:24:09: 
22:24:09:     device audio info - bits per sample: 16, channels: 2, samples per sec: 48000, block size: 4
22:24:09: Using directshow input
22:24:09: Using bitmap image
22:24:09: Using bitmap image
22:24:09: Using Video Source
22:24:09: Using Video Source
22:24:09: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
22:24:09: PERFORMANCE WARNING: Scene change took 590 ms, maybe some sources should be global sources?
22:24:09: CLRHost:: Browser 10: Status message: 
22:24:09: CLRHost:: Browser 10: Uncaught ReferenceError: $ is not defined @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248290
22:27:02: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
22:27:02:   New Scene
22:27:03: Using text output
22:27:03: Using bitmap image
22:27:03: Using bitmap image
22:27:03: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
22:27:04: CLRHost:: Browser 11: Status message: 
22:27:04: Using bitmap image
22:27:04: Using bitmap image
22:27:04: Using bitmap image
22:27:04: Using bitmap image
22:27:04: Using bitmap image
22:27:04: Using Video Source
22:27:04: Using Video Source
22:27:04: PERFORMANCE WARNING: Scene change took 1792 ms, maybe some sources should be global sources?
22:27:04: CLRHost:: Browser 11: Grabbing offset: 0 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
22:27:06: CLRHost:: Browser 11: Grabbing offset: 100 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
22:27:07: CLRHost:: Browser 11: Grabbing offset: 200 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
22:29:01: Audio timestamp for device 'Microphone (2- Wireless Stereo Headset)' was behind target timestamp by 100
22:29:02: ++++++++++++++++++++++++++++++++++++++++++++++++++++++
22:29:02:   New Scene
22:29:02: Using bitmap image
22:29:02: Using bitmap image
22:29:02: ------------------------------------------
22:29:02:     device: Logitech HD Webcam C525,
22:29:02:     device id \\?\usb#vid_046d&pid_0826&mi_02#6&adae5f7&0&0002#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
22:29:02:     chosen type: I420, usingFourCC: false, res: 1280x720 - 1280x720, frameIntervals: 333333-2000000
22:29:02:     use buffering: false - 0, fourCC: 'I420'
22:29:02:     audio device: Disable,
22:29:02:     audio device id Disabled,
22:29:02: 
22:29:02: Using directshow input
22:29:02: Using bitmap image
22:29:02: ------------------------------------------
22:29:02:     device: AVerMedia HD Capture C985 Bus 2,
22:29:02:     device id \\?\pci#ven_1af2&dev_a001&subsys_a0011af2&rev_00#4&28b85f88&0&00a9#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{957bd672-7934-4b55-b81a-3e22b14c679a},
22:29:02:     chosen type: YV12, usingFourCC: true, res: 1280x720 - 1280x720, frameIntervals: 166666-666666
22:29:02:     use buffering: false - 30000000, fourCC: 'YV12'
22:29:02: 
22:29:02:     device audio info - bits per sample: 16, channels: 2, samples per sec: 48000, block size: 4
22:29:03: Using directshow input
22:29:03: Using bitmap image
22:29:03: Using bitmap image
22:29:03: Using Video Source
22:29:03: Using Video Source
22:29:03: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
22:29:03: PERFORMANCE WARNING: Scene change took 605 ms, maybe some sources should be global sources?
22:29:03: CLRHost:: Browser 12: Status message: 
22:29:03: CLRHost:: Browser 12: Grabbing offset: 0 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
22:29:03: CLRHost:: Browser 12: Grabbing offset: 100 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
22:29:05: CLRHost:: Browser 12: Grabbing offset: 200 @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248213
22:31:47: Audio timestamp for device 'Microphone (2- Wireless Stereo Headset)' was behind target timestamp by 120
22:31:47: Audio timestamp for device 'AVerMedia HD Capture C985 Bus 2' was behind target timestamp by 80
22:39:19: RTMPPublisher::SocketLoop: Stalled for 1837 ms to write 328984 bytes (buffer: 0 / 331776), unstable connection?
23:07:26: RTMPPublisher::SocketLoop: Stalled for 1627 ms to write 331434 bytes (buffer: 0 / 331776), unstable connection?
23:07:28: RTMPPublisher::SocketLoop: Stalled for 2533 ms to write 328978 bytes (buffer: 0 / 331776), unstable connection?
23:07:31: RTMPPublisher::SocketLoop: Stalled for 2665 ms to write 330826 bytes (buffer: 0 / 331776), unstable connection?
23:09:05: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
23:09:05: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
23:41:16: RTMPPublisher::SocketLoop: Stalled for 2099 ms to write 330855 bytes (buffer: 0 / 331776), unstable connection?
23:41:23: RTMPPublisher::SocketLoop: Stalled for 4764 ms to write 331167 bytes (buffer: 0 / 331776), unstable connection?
23:48:06: RTMPPublisher::SocketLoop: Stalled for 1784 ms to write 328998 bytes (buffer: 0 / 331776), unstable connection?
23:56:13: RTMPPublisher::SocketLoop: Received FD_CLOSE, 49821 ms since last send (buffer: 328366 / 331776)
23:56:13: RTMPPublisher::SocketLoop: Aborting due to FD_CLOSE, error 0
23:56:14: Total frames encoded: 180998, total frames duplicated: 7137 (3.94%)
23:56:14: Number of frames skipped due to encoder lag: 8 (0.00%)
23:56:14: Total frames rendered: 180559, number of late frames: 101 (0.06%) (it's okay for some frames to be late)
23:56:15: Average send payload: 7569 bytes, average send interval: 23 ms
23:56:15: Number of times waited to send: 34, Waited for a total of 123755 bytes
23:56:15: Number of b-frames dropped: 208 (0.11%), Number of p-frames dropped: 297 (0.16%), Total 505 (0.28%)
23:56:15: Number of bytes sent: 1940873653
23:56:15: 
23:56:15: Profiler time results:
23:56:15: 
23:56:15: ==============================================================
23:56:15: video thread frame - [100%] [avg time: 8.603 ms] [children: 46.8%] [unaccounted: 53.2%]
23:56:15: | scene->Preprocess - [29.6%] [avg time: 2.545 ms]
23:56:15: | GPU download and conversion - [17.2%] [avg time: 1.477 ms] [children: 16.5%] [unaccounted: 0.663%]
23:56:15: | | flush - [16.2%] [avg time: 1.394 ms]
23:56:15: | | CopyResource - [0.244%] [avg time: 0.021 ms]
23:56:15: | | conversion to 4:2:0 - [0.0581%] [avg time: 0.005 ms]
23:56:15: Convert444Threads - [100%] [avg time: 1.049 ms] [children: 99%] [unaccounted: 1.05%]
23:56:15: | Convert444toNV12 - [99%] [avg time: 1.038 ms]
23:56:15: encoder thread frame - [100%] [avg time: 2.13 ms]
23:56:15: ==============================================================
23:56:15: 
23:56:15: 
23:56:15: Profiler CPU results:
23:56:15: 
23:56:15: ==============================================================
23:56:15: video thread frame - [cpu time: avg 3.101 ms, total 559981 ms] [avg calls per frame: 1]
23:56:15: | scene->Preprocess - [cpu time: avg 2.021 ms, total 365011 ms] [avg calls per frame: 1]
23:56:15: | GPU download and conversion - [cpu time: avg 0.115 ms, total 20904.1 ms] [avg calls per frame: 1]
23:56:15: | | flush - [cpu time: avg 0.081 ms, total 14757.7 ms] [avg calls per frame: 1]
23:56:15: | | CopyResource - [cpu time: avg 0.013 ms, total 2449.22 ms] [avg calls per frame: 1]
23:56:15: | | conversion to 4:2:0 - [cpu time: avg 0.005 ms, total 1029.61 ms] [avg calls per frame: 1]
23:56:15: Convert444Threads - [cpu time: avg 0.926 ms, total 334700 ms] [avg calls per frame: 2]
23:56:15: | Convert444toNV12 - [cpu time: avg 0.922 ms, total 333062 ms] [avg calls per frame: 2]
23:56:15: encoder thread frame - [cpu time: avg 1.402 ms, total 253798 ms] [avg calls per frame: 1]
23:56:15: ==============================================================
23:56:15: 
23:56:15: =====Stream End: 2014-02-11, 23:56:15=================================================
23:57:30: =====Stream Start: 2014-02-11, 23:57:30===============================================
23:57:30:   Multithreaded optimizations: On
23:57:30:   Base resolution: 1280x720
23:57:30:   Output resolution: 1280x720
23:57:30: ------------------------------------------
23:57:30: Loading up D3D10 on AMD Radeon HD 7900 Series (Adapter 1)...
23:57:30: ------------------------------------------
23:57:30: Audio Format: 44100hz
23:57:30: Playback device Default
23:57:30: ------------------------------------------
23:57:30: Using desktop audio input: Speakers (2- Wireless Stereo Headset)
23:57:30: ------------------------------------------
23:57:30: Using auxilary audio input: Microphone (2- Wireless Stereo Headset)
23:57:30: ------------------------------------------
23:57:30: Audio Encoding: AAC
23:57:30:     bitrate: 96
23:57:30: Using bitmap image
23:57:30: Using bitmap image
23:57:30: ------------------------------------------
23:57:30:     device: Logitech HD Webcam C525,
23:57:30:     device id \\?\usb#vid_046d&pid_0826&mi_02#6&adae5f7&0&0002#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{bbefb6c7-2fc4-4139-bb8b-a58bba724083},
23:57:30:     chosen type: I420, usingFourCC: false, res: 1280x720 - 1280x720, frameIntervals: 333333-2000000
23:57:30:     use buffering: false - 0, fourCC: 'I420'
23:57:30:     audio device: Disable,
23:57:30:     audio device id Disabled,
23:57:30: 
23:57:30: Using directshow input
23:57:30: Using bitmap image
23:57:30: ------------------------------------------
23:57:30:     device: AVerMedia HD Capture C985 Bus 2,
23:57:30:     device id \\?\pci#ven_1af2&dev_a001&subsys_a0011af2&rev_00#4&28b85f88&0&00a9#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\{957bd672-7934-4b55-b81a-3e22b14c679a},
23:57:30:     chosen type: YV12, usingFourCC: true, res: 1280x720 - 1280x720, frameIntervals: 166666-666666
23:57:30:     use buffering: false - 30000000, fourCC: 'YV12'
23:57:30: 
23:57:30:     device audio info - bits per sample: 16, channels: 2, samples per sec: 48000, block size: 4
23:57:31: Using directshow input
23:57:31: Using bitmap image
23:57:31: Using bitmap image
23:57:31: Using Video Source
23:57:31: Using Video Source
23:57:31: CLRHost:: BrowserRenderHandler::OnPaint failed to create texture
23:57:31: Scene buffering time set to 700
23:57:31: ------------------------------------------
23:57:31: Video Encoding: x264
23:57:31:     fps: 30
23:57:31:     width: 1280, height: 720
23:57:31:     preset: veryfast
23:57:31:     profile: main
23:57:31:     keyint: 250
23:57:31:     CBR: yes
23:57:31:     CFR: yes
23:57:31:     max bitrate: 2500
23:57:31:     buffer size: 2500
23:57:31: ------------------------------------------
23:57:31: Syncing audio to video time (WARNING: you should not be doing this if you are just having webcam desync, that's a separate issue)
23:57:32: 
23:57:32: Profiler result for the last frame:
23:57:32: ==============================================================
23:57:32: video thread frame - [time: 1168.11 ms (cpu time: 1060.81 ms)]
23:57:32: | scene->Preprocess - [time: 0.037 ms (cpu time: 0 ms)]
23:57:32: | GPU download and conversion - [time: 0.543 ms (cpu time: 0 ms)]
23:57:32: | | flush - [time: 0.52 ms (cpu time: 0 ms)]
23:57:32: | | CopyResource - [time: 0.007 ms (cpu time: 0 ms)]
23:57:32: ==============================================================
23:57:32: 
23:57:32: 
23:57:32: Profiler result for the last frame:
23:57:32: ==============================================================
23:57:32: video thread frame - [time: 20.835 ms (cpu time: 15.6 ms)]
23:57:32: | scene->Preprocess - [time: 7.332 ms (cpu time: 15.6 ms)]
23:57:32: | GPU download and conversion - [time: 1.721 ms (cpu time: 0 ms)]
23:57:32: | | flush - [time: 1.658 ms (cpu time: 0 ms)]
23:57:32: | | CopyResource - [time: 0.035 ms (cpu time: 0 ms)]
23:57:32: | | conversion to 4:2:0 - [time: 0.004 ms (cpu time: 0 ms)]
23:57:32: Convert444Threads - [time: 1.278 ms (cpu time: 0 ms)]
23:57:32: | Convert444toNV12 - [time: 1.268 ms (cpu time: 0 ms)]
23:57:32: ==============================================================
23:57:32: 
23:57:33: CLRHost:: Browser 13: Status message: 
23:57:33: CLRHost:: Browser 13: Uncaught ReferenceError: $ is not defined @http://www.nightdev.com/hosted/follower-alert/?channel=wapeddell&type=custom-centered&alert=http%3A%2F%2Fi.imgur.com%2F0j8JSek.png&sound=248290
23:57:33: Using RTMP service: Twitch / Justin.tv
23:57:33:   Server selection: rtmp://live-iad.justin.tv/app
23:57:34:   Interface: Realtek RTL8139/810x Family Fast Ethernet NIC (ethernet, 100 mbps)
23:57:34: Completed handshake with rtmp://live-iad.justin.tv/app in 514 ms.
23:57:35: SO_SNDBUF was at 8192
23:57:35: SO_SNDBUF is now 65536
23:57:36: RTMPPublisher::SocketLoop: Increasing send buffer to ISB 131072 (buffer: 838 / 331776)
23:57:42: Audio timestamp for device 'AVerMedia HD Capture C985 Bus 2' was behind target timestamp by 10
23:57:47: Audio timestamp for device 'Microphone (2- Wireless Stereo Headset)' was behind target timestamp by 90
23:57:48: Audio timestamp for device 'Microphone (2- Wireless Stereo Headset)' was behind target timestamp by 110
23:57:48: Audio timestamp for device 'AVerMedia HD Capture C985 Bus 2' was behind target timestamp by 110
23:58:01: RTMPPublisher::SocketLoop: Stalled for 4469 ms to write 329062 bytes (buffer: 0 / 331776), unstable connection?
00:09:05: RTMPPublisher::SocketLoop: Stalled for 1662 ms to write 329636 bytes (buffer: 0 / 331776), unstable connection?
00:09:08: RTMPPublisher::SocketLoop: Stalled for 3022 ms to write 329031 bytes (buffer: 0 / 331776), unstable connection?
00:09:11: RTMPPublisher::SocketLoop: Stalled for 1571 ms to write 256451 bytes (buffer: 0 / 331776), unstable connection?
00:10:53: RTMPPublisher::SocketLoop: Stalled for 3919 ms to write 330422 bytes (buffer: 0 / 331776), unstable connection?
00:17:17: RTMPPublisher::SocketLoop: Stalled for 2088 ms to write 330267 bytes (buffer: 0 / 331776), unstable connection?
00:34:49: RTMPPublisher::SocketLoop: Stalled for 2009 ms to write 331709 bytes (buffer: 0 / 331776), unstable connection?
00:40:06: RTMPPublisher::SocketLoop: Stalled for 1941 ms to write 330052 bytes (buffer: 0 / 331776), unstable connection?
00:44:01: Total frames encoded: 83662, total frames duplicated: 1882 (2.25%)
00:44:01: Number of frames skipped due to encoder lag: 38 (0.05%)
00:44:01: Total frames rendered: 83593, number of late frames: 12 (0.01%) (it's okay for some frames to be late)
00:44:02: RTMPPublisher::SocketLoop: Graceful loop exit
00:44:02: Average send payload: 8072 bytes, average send interval: 24 ms
00:44:02: Number of times waited to send: 25, Waited for a total of 87640 bytes
00:44:02: Number of b-frames dropped: 205 (0.25%), Number of p-frames dropped: 276 (0.33%), Total 481 (0.58%)
00:44:02: Number of bytes sent: 901229972
00:44:02: 
00:44:02: Profiler time results:
00:44:02: 
00:44:02: ==============================================================
00:44:02: video thread frame - [100%] [avg time: 7.628 ms] [children: 41.5%] [unaccounted: 58.5%]
00:44:02: | scene->Preprocess - [35.2%] [avg time: 2.688 ms]
00:44:02: | GPU download and conversion - [6.24%] [avg time: 0.476 ms] [children: 5.34%] [unaccounted: 0.905%]
00:44:02: | | flush - [5.05%] [avg time: 0.385 ms]
00:44:02: | | CopyResource - [0.236%] [avg time: 0.018 ms]
00:44:02: | | conversion to 4:2:0 - [0.0524%] [avg time: 0.004 ms]
00:44:02: Convert444Threads - [100%] [avg time: 1.249 ms] [children: 99%] [unaccounted: 1.04%]
00:44:02: | Convert444toNV12 - [99%] [avg time: 1.236 ms]
00:44:02: encoder thread frame - [100%] [avg time: 2.257 ms]
00:44:02: ==============================================================
00:44:02: 
00:44:02: 
00:44:02: Profiler CPU results:
00:44:02: 
00:44:02: ==============================================================
00:44:02: video thread frame - [cpu time: avg 2.96 ms, total 247496 ms] [avg calls per frame: 1]
00:44:02: | scene->Preprocess - [cpu time: avg 2.063 ms, total 172475 ms] [avg calls per frame: 1]
00:44:02: | GPU download and conversion - [cpu time: avg 0.078 ms, total 6520.85 ms] [avg calls per frame: 1]
00:44:02: | | flush - [cpu time: avg 0.047 ms, total 3962.43 ms] [avg calls per frame: 1]
00:44:02: | | CopyResource - [cpu time: avg 0.013 ms, total 1107.61 ms] [avg calls per frame: 1]
00:44:02: | | conversion to 4:2:0 - [cpu time: avg 0.002 ms, total 234.002 ms] [avg calls per frame: 1]
00:44:02: Convert444Threads - [cpu time: avg 0.925 ms, total 154737 ms] [avg calls per frame: 2]
00:44:02: | Convert444toNV12 - [cpu time: avg 0.919 ms, total 153786 ms] [avg calls per frame: 2]
00:44:02: encoder thread frame - [cpu time: avg 1.461 ms, total 122211 ms] [avg calls per frame: 1]
00:44:02: ==============================================================
00:44:02: 
00:44:02: =====Stream End: 2014-02-12, 00:44:02=================================================
00:44:14: CLR host plugin instance deleted; removing dynamically loaded localization strings
00:44:14: Video Source Plugin instance deleted; removing dynamically loaded localization strings

Lain · Feb 13, 2014

Ah I see, you are indeed using a capture device. Try out "use buffering" in its settings and see if it helps at all.

wapeddell · Feb 13, 2014

Jim said:
Ah I see, you are indeed using a capture device. Try out "use buffering" in its settings and see if it helps at all.

Don't work I count to 5 and the audio is already playing before the video http://www.twitch.tv/wapeddell/b/502926523

When I clap it's delayed and when I make a sound with my mouth it's delayed http://www.twitch.tv/wapeddell/b/502931083

wapeddell · Feb 13, 2014

Log File

hilalpro · Feb 13, 2014

If you've tried with "use buffering" enabled then also make sure that the value is set to 0

wapeddell · Feb 13, 2014

hilalpro said:
If you've tried with "use buffering" enabled then also make sure that the value is set to 0

Yea did that already still didn't work.

wapeddell · Feb 13, 2014

Krazy · Feb 13, 2014

Can you change to the latest non test build version and try again?

wapeddell · Feb 13, 2014

Krazy said:
Can you change to the latest non test build version and try again?

Ok going to give it a try will post in a few.

wapeddell · Feb 13, 2014

Doesn't work http://www.twitch.tv/wapeddell/b/503115292 as you can see I used Fl Studio to show when the sound starts I set the metronome 4 clicks before the recording and the video is playing the sound way before the sound has even came in.

wapeddell · Feb 14, 2014

http://www.twitch.tv/wapeddell/b/503218397 I've tried everything from what people posted on this thread to other and obs is not syncing audio I've change scenes everything I've ran out of ideas.

Lain · Feb 14, 2014

You are using an elgato to capture a PC? That feels wrong somehow. You shouldn't need to use a capture device to capture a computer, especially if you're not gaming on that computer.

You're using devices all over the place left and right it seems like. First, make sure that the devices are all using "use buffering".

Secondly, If you still feel like it's off, in the properties of each device, there's an "audio time offset". For the mic, it's "Mic audio time offset" in OBS' main audio settings.

That option will allow you to manually sync you audio to your video. In milliseconds, and it can be negative numbers.

wapeddell · Feb 14, 2014

Jim said:
You are using an elgato to capture a PC? That feels wrong somehow. You shouldn't need to use a capture device to capture a computer, especially if you're not gaming on that computer.

You're using devices all over the place left and right it seems like. First, make sure that the devices are all using "use buffering".

Secondly, If you still feel like it's off, in the properties of each device, there's an "audio time offset". For the mic, it's "Mic audio time offset" in OBS' main audio settings.

That option will allow you to manually sync you audio to your video. In milliseconds, and it can be negative numbers.

No I'm use Avermedia Live Gamer HD to Capture PC and the Elgato to capture PS3 they are all on the same scene.

Bug Report Audio Out Of Sync

wapeddell

Member

Lain

wapeddell

Member

Lain

wapeddell

Member

wapeddell

Member

Attachments

hilalpro

Member

wapeddell

Member

wapeddell

Member

Krazy

Town drunk

wapeddell

Member

wapeddell

Member

wapeddell

Member

Lain

wapeddell

Member