New issue
Advanced search Search tips
Note: Color blocks (like or ) mean that a user may not be available. Tooltip shows the reason.

Issue 703579 link

Starred by 2 users

Issue metadata

Status: Fixed
Owner:
Closed: May 2017
Cc:
Components:
EstimatedDays: ----
NextAction: ----
OS: Windows
Pri: 2
Type: Bug



Sign in to add a comment

Flaky H264 tests in WebRtcStatsPerfBrowserTest

Project Member Reported by grunell@chromium.org, Mar 21 2017

Issue description

OS: Win 8

https://build.chromium.org/p/chromium.webrtc/waterfall?builder=Win8%20Tester

I've seen failures for

WebRtcInternalsPerfBrowserTest.MANUAL_RunsAudioVideoCall60SecsAndLogsInternalMetricsH264
https://luci-logdog.appspot.com/v/?s=chromium%2Fbb%2Fchromium.webrtc%2FWin8_Tester%2F33200%2F%2B%2Frecipes%2Fsteps%2Fbrowser_tests%2F0%2Fstdout

and

WebRtcStatsPerfBrowserTest.MANUAL_RunsAudioAndVideoCallCollectingMetrics_VideoCodec_H264
https://luci-logdog.appspot.com/v/?s=chromium%2Fbb%2Fchromium.webrtc%2FWin8_Tester%2F33167%2F%2B%2Frecipes%2Fsteps%2Fbrowser_tests%2F0%2Fstdout

There's a number of errors and warnings. One thing that stands out (in the first test):

e:\b\c\b\win_builder\src\chrome\browser\media\webrtc\webrtc_browsertest_perf.cc(203): error: Value of: pc_dict.GetString( Statistic("googAvailableSendBandwidth", kBweStatsKey), &value)
  Actual: false
Expected: true

Also there's lots of

[3708:3248:0319/164554.838:WARNING:video_receive_stream.cc(500)] No decodable frame in 3000 ms, requesting keyframe.
 
Components: Blink>WebRTC>Video
Henrik can you take a look?
I've also seen flaky failures for

WebRtcWebcamBrowserTests/WebRtcWebcamBrowserTest.MANUAL_TestAcquiringAndReacquiringWebcam

Could be a generic problem either in tests or on this bot. Could be unrelated.

Comment 3 by hbos@chromium.org, Mar 22 2017

Cc: ananta@chromium.org hbos@chromium.org sprang@chromium.org
Owner: emir...@chromium.org
emircan can you take a look or reassign?

The failing tests have no H264 video sent or received:

...
[172:1532:0321/022447.901:WARNING:video_receive_stream.cc(493)] No decodable frame in 3000 ms, requesting keyframe.
[172:1532:0321/022450.901:WARNING:video_receive_stream.cc(493)] No decodable frame in 3000 ms, requesting keyframe.
[3628:488:0321/022450.901:WARNING:video_receive_stream.cc(493)] No decodable frame in 3000 ms, requesting keyframe.
...
RESULT video_H264: send_rate= 0 bytes/second
RESULT video_H264: receive_rate= 0 bytes/second
...
[172:1532:0321/022453.901:WARNING:video_receive_stream.cc(493)] No decodable frame in 3000 ms, requesting keyframe.
[172:1532:0321/022456.902:WARNING:video_receive_stream.cc(493)] No decodable frame in 3000 ms, requesting keyframe.
[172:1532:0321/022459.902:WARNING:video_receive_stream.cc(493)] No decodable frame in 3000 ms, requesting keyframe.
...

It seems that hardware H264 is not initialized properly:

[3320:3820:0321/022342.614:INFO:gpu_video_decode_accelerator_factory.cc(182)] Initializing DXVA HW decoder for windows.
[3320:3820:0321/022342.614:ERROR:mf_helpers.cc(12)] Error in dxva_video_decode_accelerator_win.cc on line 607
[3320:3820:0321/022342.614:ERROR:gpu_video_decode_accelerator.cc(375)] HW video decode not available for profile vp8
[172:3448:0321/022342.614:ERROR:gpu_video_decode_accelerator_host.cc(99)] Send(GpuCommandBufferMsg_CreateVideoDecoder()) failed
[3320:2136:0321/022342.614:ERROR:gpu_channel.cc(564)] Could not find message queue
[3320:3820:0321/022342.626:INFO:gpu_video_decode_accelerator_factory.cc(182)] Initializing DXVA HW decoder for windows.
[3320:3820:0321/022342.626:ERROR:mf_helpers.cc(12)] Error in dxva_video_decode_accelerator_win.cc on line 607
[3320:3820:0321/022342.626:ERROR:gpu_video_decode_accelerator.cc(375)] HW video decode not available for profile vp9 profile0
[172:3448:0321/022342.626:ERROR:gpu_video_decode_accelerator_host.cc(99)] Send(GpuCommandBufferMsg_CreateVideoDecoder()) failed
[3320:2136:0321/022342.627:ERROR:gpu_channel.cc(564)] Could not find message queue
[3320:3820:0321/022342.627:INFO:gpu_video_decode_accelerator_factory.cc(182)] Initializing DXVA HW decoder for windows.
[3320:3820:0321/022342.627:ERROR:mf_helpers.cc(12)] Error in dxva_video_decode_accelerator_win.cc on line 607
[3320:3820:0321/022342.627:ERROR:gpu_video_decode_accelerator.cc(375)] HW video decode not available for profile h264 main
[172:3448:0321/022342.627:ERROR:gpu_video_decode_accelerator_host.cc(99)] Send(GpuCommandBufferMsg_CreateVideoDecoder()) failed
[3320:2136:0321/022342.627:ERROR:gpu_channel.cc(564)] Could not find message queue
...
[3320:3820:0321/022342.648:INFO:gpu_video_decode_accelerator_factory.cc(182)] Initializing DXVA HW decoder for windows.
[3320:3820:0321/022342.648:ERROR:mf_helpers.cc(12)] Error in dxva_video_decode_accelerator_win.cc on line 607
[3320:3820:0321/022342.648:ERROR:gpu_video_decode_accelerator.cc(375)] HW video decode not available for profile vp8
[3628:3852:0321/022342.648:ERROR:gpu_video_decode_accelerator_host.cc(99)] Send(GpuCommandBufferMsg_CreateVideoDecoder()) failed
[3320:2136:0321/022342.648:ERROR:gpu_channel.cc(564)] Could not find message queue
[3320:3820:0321/022342.651:INFO:gpu_video_decode_accelerator_factory.cc(182)] Initializing DXVA HW decoder for windows.
[3320:3820:0321/022342.652:ERROR:mf_helpers.cc(12)] Error in dxva_video_decode_accelerator_win.cc on line 607
[3320:3820:0321/022342.652:ERROR:gpu_video_decode_accelerator.cc(375)] HW video decode not available for profile vp9 profile0
[3628:3852:0321/022342.652:ERROR:gpu_video_decode_accelerator_host.cc(99)] Send(GpuCommandBufferMsg_CreateVideoDecoder()) failed
[3320:2136:0321/022342.652:ERROR:gpu_channel.cc(564)] Could not find message queue
[3320:3820:0321/022342.663:INFO:gpu_video_decode_accelerator_factory.cc(182)] Initializing DXVA HW decoder for windows.
[3320:3820:0321/022342.664:ERROR:mf_helpers.cc(12)] Error in dxva_video_decode_accelerator_win.cc on line 607
[3320:3820:0321/022342.664:ERROR:gpu_video_decode_accelerator.cc(375)] HW video decode not available for profile h264 main
[3628:3852:0321/022342.664:ERROR:gpu_video_decode_accelerator_host.cc(99)] Send(GpuCommandBufferMsg_CreateVideoDecoder()) failed
[3320:2136:0321/022342.664:ERROR:gpu_channel.cc(564)] Could not find message queue

(Maybe it all shows up twice because there are two tabs, so the error occurs on both ends, for both of them.)

Comment 4 by hbos@chromium.org, Mar 22 2017

No idea why it tries to initialize for vp8, vp9 profile0 before h264 main in a test where H264 is negotiated.
Cc: pbos@chromium.org
Re #4, it looks like WebRTC tries to initialize for every codec profile one by one until finding a match. +cc pbos@ for his input. It boils down from this loop in WebRtcVideoChannel2::WebRtcVideoReceiveStream::ConfigureCodecs: https://cs.chromium.org/chromium/src/third_party/webrtc/media/engine/webrtcvideoengine2.cc?rcl=77eda64f71b09a58643751ee86a97fffa1ef19a9&l=2219 

ananta@ do you know why we do not have h264 decoder support for this Win 8 instance?

Re flakiness, this issue usually comes from the capture pipeline dropping all the frames when there is more than 3 in flight, see https://cs.chromium.org/chromium/src/media/capture/video/video_capture_device_client.cc?rcl=844b401fbaf5ced17e83fba254ca26fab258a86e&l=465. If there is any element that still keeps a reference to the frames, we see this issue. It can be related to WebRtcWebcamBrowserTests/WebRtcWebcamBrowserTest.MANUAL_TestAcquiringAndReacquiringWebcam for this reason. I will try to setup a Win 8 environment and try to get a repro. 

Comment 6 by pbos@chromium.org, Mar 23 2017

Cc: magjed@chromium.org
magjed@ might know more about H264 profiles. :)

Comment 7 by ananta@chromium.org, Mar 23 2017

It appears that the gl share handle is not available?
https://cs.chromium.org/chromium/src/media/gpu/dxva_video_decode_accelerator_win.cc?q=dxva_video_decode_accelerator_win.cc+package:%5Echromium$&l=607

AFAIK, the decoder copies the decoded surface/texture to a texture which is backed by the gl share handle. Please ask someone with angle/open gl background to figure out why the share handle is not created.



Cc: chfremer@chromium.org
I will wait to see if it is related to the actual camera problems, see https://bugs.chromium.org/p/chromium/issues/detail?id=676020#c14. I haven't found anything suspicious on the H264 encoder side and couldn't repro at all in my local win 10 machine. 
Status: Fixed (was: Assigned)
Webcam swap seems to have fixed this as the last 70 runs are all green. Marking it as fixed. Feel free to open if it happens again.
Wow. Look at all that green! :-)

Comment 12 by hbos@chromium.org, Mar 28 2017

Mmmm delicious green :)))
Status: Assigned (was: Fixed)
Reopening this as the tests started failing again: https://build.chromium.org/p/chromium.webrtc/builders/Win8%20Tester/builds/33372
Cc: maxmorin@chromium.org
Here's a fresh log:
[ RUN      ] WebRtcInternalsPerfBrowserTest.MANUAL_RunsAudioVideoCall60SecsAndLogsInternalMetricsH264
[3104:1120:0425/033250.099:WARNING:chrome_browser_main_win.cc(462)] Command line too long for RegisterApplicationRestart:  --gtest_also_run_disabled_tests --gtest_filter=WebRtcInternalsPerfBrowserTest.MANUAL_RunsAudioVideoCall60SecsAndLogsInternalMetricsH264 --run-manual --single_process --test-launcher-bot-mode --test-launcher-jobs=1 --test-launcher-print-test-stdio=always --ui-test-action-max-timeout=350000 --user-data-dir="C:\Users\CHROME~1.LAB\AppData\Local\Temp\scoped_dir3116_29085\d3116_11807" --disable-offline-auto-reload --use-file-for-fake-video-capture="C:\b\c\b\Win8_Tester\src\chrome\test\data\webrtc/resources\reference_video_640x360_30fps.y4m" --use-fake-device-for-media-stream --disable-features=NetworkPrediction --no-first-run --no-default-browser-check --enable-logging=stderr --log-level=0 --safebrowsing-disable-auto-update --disable-default-apps --wm-window-animations-disabled --disable-component-update --test-type=browser --disable-zero-browsers-open-for-tests --ipc-connection-timeout=350 --allow-file-access-from-files --dom-automation --log-gpu-control-list-decisions --disable-backgrounding-occluded-windows --override-use-software-gl-for-tests --flag-switches-begin --flag-switches-end --restore-last-session about:blank
[3772:856:0425/033250.341:ERROR:media_foundation_video_encode_accelerator_win.cc(478)] Couldn't set output media type, HRESULT: 0xc00d36b4
[3772:856:0425/033250.341:ERROR:mf_helpers.cc(12)] Error in dxva_video_decode_accelerator_win.cc on line 478
[3104:1120:0425/033252.181:INFO:CONSOLE(71)] "This appears to be Chrome", source: http://127.0.0.1:55339/webrtc/adapter.js (71)
[3104:1120:0425/033252.285:INFO:CONSOLE(13)] "Requesting doGetUserMedia: constraints: {"audio":true,"video":true}", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:3264:0425/033252.313:WARNING:embedded_test_server.cc(219)] Request not handled. Returning 404: /favicon.ico
[3104:1120:0425/033252.324:INFO:CONSOLE(13)] "Returning request-callback-granted to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033252.328:INFO:CONSOLE(13)] "Returning ok-got-stream to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:2076:0425/033253.490:ERROR:process_win.cc(140)] Unable to terminate process: Access is denied. (0x5)
[3104:1120:0425/033253.505:INFO:CONSOLE(71)] "This appears to be Chrome", source: http://127.0.0.1:55339/webrtc/adapter.js (71)
[3104:1120:0425/033253.533:INFO:CONSOLE(13)] "Requesting doGetUserMedia: constraints: {"audio":true,"video":true}", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.543:INFO:CONSOLE(13)] "Returning request-callback-granted to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.653:INFO:CONSOLE(13)] "Returning ok-got-stream to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.660:INFO:CONSOLE(13)] "Returning ok-peerconnection-created to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.809:INFO:CONSOLE(13)] "Added local stream.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.809:INFO:CONSOLE(13)] "Returning ok-added to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.815:INFO:CONSOLE(13)] "Returning ok-peerconnection-created to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.853:INFO:CONSOLE(13)] "Added local stream.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.854:INFO:CONSOLE(13)] "Returning ok-added to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.855:INFO:CONSOLE(13)] "Returning ok to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.856:INFO:CONSOLE(13)] "Returning ok to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[752:2780:0425/033253.856:WARNING:mediasession.cc(343)] Duplicate id found. Reassigning from 104 to 127
[752:2780:0425/033253.857:WARNING:mediasession.cc(343)] Duplicate id found. Reassigning from 103 to 125
[3104:1120:0425/033253.858:INFO:CONSOLE(13)] "createOffer(): success.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[752:3464:0425/033253.859:WARNING:delay_based_bwe.cc(351)] BWE Setting start bitrate to: 300000
[752:3464:0425/033253.860:WARNING:audio_send_stream.cc(350)] SetSendCodec() failed.
[752:3464:0425/033253.860:ERROR:audio_send_stream.cc(103)] Failed to set up send codec state.
[3104:1120:0425/033253.861:INFO:CONSOLE(13)] "Returning ok-{"type":"offer","sdp":"v=0\r\no=- 1874224784206941300 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE audio video\r\na=msid-semantic: WMS H27IrUp5vHDIGynpsjyyAq0UrFsIbBpboq4R\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:Gs1S\r\na=ice-pwd:DHUxShCD2uRwfMy8ouV4C3PW\r\na=ice-options:trickle\r\na=fingerprint:sha-256 F7:1F:35:82:9D:35:5B:D8:A4:60:90:1D:73:69:00:96:9E:A2:5D:5F:F7:05:65:EA:28:11:20:BD:00:CB:54:60\r\na=setup:actpass\r\na=mid:audio\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=sendrecv\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\na=ssrc:2730256325 cname:Mc2ImGUaQ4HLGM42\r\na=ssrc:2730256325 msid:H27IrUp5vHDIGynpsjyyAq0UrFsIbBpboq4R 24e582a8-7922-4661-92f0-fbeb566b2ed0\r\na=ssrc:2730256325 mslabel:H27IrUp5vHDIGynpsjyyAq0UrFsIbBpboq4R\r\na=ssrc:2730256325 label:24e582a8-7922-4661-92f0-fbeb566b2ed0\r\nm=video 9 UDP/TLS/RTP/SAVPF 100 96 98 102 127 97 99 101 125\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:Gs1S\r\na=ice-pwd:DHUxShCD2uRwfMy8ouV4C3PW\r\na=ice-options:trickle\r\na=fingerprint:sha-256 F7:1F:35:82:9D:35:5B:D8:A4:60:90:1D:73:69:00:96:9E:A2:5D:5F:F7:05:65:EA:28:11:20:BD:00:CB:54:60\r\na=setup:actpass\r\na=mid:video\r\na=extmap:2 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:4 urn:3gpp:video-orientation\r\na=extmap:5 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:6 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=sendrecv\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtpmap:100 H264/90000\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=fmtp:100 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f\r\na=rtpmap:102 red/90000\r\na=rtpmap:127 ulpfec/90000\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:125 rtx/90000\r\na=fmtp:125 apt=102\r\na=ssrc-group:FID 1154903742 1632895245\r\na=ssrc:1154903742 cname:Mc2ImGUaQ4HLGM42\r\na=ssrc:1154903742 msid:H27IrUp5vHDIGynpsjyyAq0UrFsIbBpboq4R 5934eee0-e79e-4c74-8639-40dad39f3b8d\r\na=ssrc:1154903742 mslabel:H27IrUp5vHDIGynpsjyyAq0UrFsIbBpboq4R\r\na=ssrc:1154903742 label:5934eee0-e79e-4c74-8639-40dad39f3b8d\r\na=ssrc:1632895245 cname:Mc2ImGUaQ4HLGM42\r\na=ssrc:1632895245 msid:H27IrUp5vHDIGynpsjyyAq0UrFsIbBpboq4R 5934eee0-e79e-4c74-8639-40dad39f3b8d\r\na=ssrc:1632895245 mslabel:H27IrUp5vHDIGynpsjyyAq0UrFsIbBpboq4R\r\na=ssrc:1632895245 label:5934eee0-e79e-4c74-8639-40dad39f3b8d\r\n"} to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.862:INFO:CONSOLE(13)] "setLocalDescription(): success.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[2320:3864:0425/033253.864:WARNING:delay_based_bwe.cc(351)] BWE Setting start bitrate to: 300000
[3772:856:0425/033253.866:INFO:gpu_video_decode_accelerator_factory.cc(182)] Initializing DXVA HW decoder for windows.
[3772:856:0425/033253.866:ERROR:mf_helpers.cc(12)] Error in dxva_video_decode_accelerator_win.cc on line 607
[3772:856:0425/033253.866:ERROR:gpu_video_decode_accelerator.cc(375)] HW video decode not available for profile vp8
[2320:3268:0425/033253.866:ERROR:gpu_video_decode_accelerator_host.cc(99)] Send(GpuCommandBufferMsg_CreateVideoDecoder()) failed
[3772:856:0425/033253.866:INFO:gpu_video_decode_accelerator_factory.cc(182)] Initializing DXVA HW decoder for windows.
[3772:856:0425/033253.866:ERROR:mf_helpers.cc(12)] Error in dxva_video_decode_accelerator_win.cc on line 607
[3772:856:0425/033253.866:ERROR:gpu_video_decode_accelerator.cc(375)] HW video decode not available for profile vp9 profile0
[2320:3268:0425/033253.867:ERROR:gpu_video_decode_accelerator_host.cc(99)] Send(GpuCommandBufferMsg_CreateVideoDecoder()) failed
[3772:856:0425/033253.867:INFO:gpu_video_decode_accelerator_factory.cc(182)] Initializing DXVA HW decoder for windows.
[3772:856:0425/033253.867:ERROR:mf_helpers.cc(12)] Error in dxva_video_decode_accelerator_win.cc on line 607
[3772:856:0425/033253.867:ERROR:gpu_video_decode_accelerator.cc(375)] HW video decode not available for profile h264 main
[2320:3268:0425/033253.867:ERROR:gpu_video_decode_accelerator_host.cc(99)] Send(GpuCommandBufferMsg_CreateVideoDecoder()) failed
[3104:1120:0425/033253.869:INFO:CONSOLE(13)] "setRemoteDescription(): success.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.869:INFO:CONSOLE(13)] "createAnswer(): success.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.871:INFO:CONSOLE(13)] "Returning ok-{"type":"answer","sdp":"v=0\r\no=- 7287771121153029072 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE audio video\r\na=msid-semantic: WMS AEDUOawAEsUcymsaMPiu1TPMqTFlSJTMAWRy\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:X+0R\r\na=ice-pwd:yeSGph7eGK8foCSVlEEoWQiK\r\na=ice-options:trickle\r\na=fingerprint:sha-256 19:10:D0:A8:37:01:31:3F:BA:A3:85:61:76:CB:91:89:6A:1A:39:86:DD:F4:B3:CC:B2:8B:85:EA:58:D0:15:13\r\na=setup:active\r\na=mid:audio\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=sendrecv\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\na=ssrc:2491633075 cname:/6i2d8unpV4WpN4H\r\na=ssrc:2491633075 msid:AEDUOawAEsUcymsaMPiu1TPMqTFlSJTMAWRy 3aff578a-40d1-48b9-b94f-fd5d9f442a2b\r\na=ssrc:2491633075 mslabel:AEDUOawAEsUcymsaMPiu1TPMqTFlSJTMAWRy\r\na=ssrc:2491633075 label:3aff578a-40d1-48b9-b94f-fd5d9f442a2b\r\nm=video 9 UDP/TLS/RTP/SAVPF 100 96 98 102 127 97 99 101 125\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:X+0R\r\na=ice-pwd:yeSGph7eGK8foCSVlEEoWQiK\r\na=ice-options:trickle\r\na=fingerprint:sha-256 19:10:D0:A8:37:01:31:3F:BA:A3:85:61:76:CB:91:89:6A:1A:39:86:DD:F4:B3:CC:B2:8B:85:EA:58:D0:15:13\r\na=setup:active\r\na=mid:video\r\na=extmap:2 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:4 urn:3gpp:video-orientation\r\na=extmap:5 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:6 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=sendrecv\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:100 H264/90000\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=fmtp:100 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtpmap:102 red/90000\r\na=rtpmap:127 ulpfec/90000\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:125 rtx/90000\r\na=fmtp:125 apt=102\r\na=ssrc-group:FID 3482541876 3759664657\r\na=ssrc:3482541876 cname:/6i2d8unpV4WpN4H\r\na=ssrc:3482541876 msid:AEDUOawAEsUcymsaMPiu1TPMqTFlSJTMAWRy e3768da5-97ca-4e68-800a-7f59508bc03d\r\na=ssrc:3482541876 mslabel:AEDUOawAEsUcymsaMPiu1TPMqTFlSJTMAWRy\r\na=ssrc:3482541876 label:e3768da5-97ca-4e68-800a-7f59508bc03d\r\na=ssrc:3759664657 cname:/6i2d8unpV4WpN4H\r\na=ssrc:3759664657 msid:AEDUOawAEsUcymsaMPiu1TPMqTFlSJTMAWRy e3768da5-97ca-4e68-800a-7f59508bc03d\r\na=ssrc:3759664657 mslabel:AEDUOawAEsUcymsaMPiu1TPMqTFlSJTMAWRy\r\na=ssrc:3759664657 label:e3768da5-97ca-4e68-800a-7f59508bc03d\r\n"} to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.871:INFO:CONSOLE(13)] "Receiving remote stream...", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[2320:1400:0425/033253.872:WARNING:video_send_stream.cc(1092)] Transmitting payload type without picture ID using NACK+ULPFEC is a waste of bandwidth since ULPFEC packets also have to be retransmitted. Disabling ULPFEC.
[3104:1120:0425/033253.874:INFO:CONSOLE(13)] "Returning ok-verified to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.876:INFO:CONSOLE(13)] "setLocalDescription(): success.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[752:1676:0425/033253.877:WARNING:video_send_stream.cc(1092)] Transmitting payload type without picture ID using NACK+ULPFEC is a waste of bandwidth since ULPFEC packets also have to be retransmitted. Disabling ULPFEC.
[3772:856:0425/033253.880:INFO:gpu_video_decode_accelerator_factory.cc(182)] Initializing DXVA HW decoder for windows.
[3772:856:0425/033253.880:ERROR:mf_helpers.cc(12)] Error in dxva_video_decode_accelerator_win.cc on line 607
[3772:856:0425/033253.880:ERROR:gpu_video_decode_accelerator.cc(375)] HW video decode not available for profile vp8
[752:2740:0425/033253.881:ERROR:gpu_video_decode_accelerator_host.cc(99)] Send(GpuCommandBufferMsg_CreateVideoDecoder()) failed
[3772:856:0425/033253.881:INFO:gpu_video_decode_accelerator_factory.cc(182)] Initializing DXVA HW decoder for windows.
[3772:856:0425/033253.881:ERROR:mf_helpers.cc(12)] Error in dxva_video_decode_accelerator_win.cc on line 607
[3772:856:0425/033253.881:ERROR:gpu_video_decode_accelerator.cc(375)] HW video decode not available for profile vp9 profile0
[752:2740:0425/033253.881:ERROR:gpu_video_decode_accelerator_host.cc(99)] Send(GpuCommandBufferMsg_CreateVideoDecoder()) failed
[3772:856:0425/033253.881:INFO:gpu_video_decode_accelerator_factory.cc(182)] Initializing DXVA HW decoder for windows.
[3772:856:0425/033253.881:ERROR:mf_helpers.cc(12)] Error in dxva_video_decode_accelerator_win.cc on line 607
[3772:856:0425/033253.881:ERROR:gpu_video_decode_accelerator.cc(375)] HW video decode not available for profile h264 main
[752:2740:0425/033253.882:ERROR:gpu_video_decode_accelerator_host.cc(99)] Send(GpuCommandBufferMsg_CreateVideoDecoder()) failed
[3104:1120:0425/033253.883:INFO:CONSOLE(13)] "setRemoteDescription(): success.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.884:INFO:CONSOLE(13)] "Returning ok-accepted-answer to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.884:INFO:CONSOLE(13)] "Receiving remote stream...", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033253.885:INFO:CONSOLE(326)] "Still ICE gathering - waiting...", source: http://127.0.0.1:55339/webrtc/peerconnection.js (326)
[752:3464:0425/033253.964:WARNING:p2ptransportchannel.cc(528)] Jingle:Port[0C08ED98:audio:1:0:local:Net[{E792D967-DFD2-4946-85F0-104C9F342A7B}:192.168.195.x/24:Ethernet]]: SetOption(1, 65536) failed: 0
[752:3464:0425/033253.964:WARNING:p2ptransportchannel.cc(528)] Jingle:Port[0C08ED98:audio:1:0:local:Net[{E792D967-DFD2-4946-85F0-104C9F342A7B}:192.168.195.x/24:Ethernet]]: SetOption(2, 65536) failed: 0
[752:3464:0425/033253.964:WARNING:p2ptransportchannel.cc(528)] Jingle:Port[0C08ED98:audio:1:0:local:Net[{E792D967-DFD2-4946-85F0-104C9F342A7B}:192.168.195.x/24:Ethernet]]: SetOption(5, 0) failed: 0
[2320:3864:0425/033253.974:WARNING:p2ptransportchannel.cc(528)] Jingle:Port[0F4F3CA8:audio:1:0:local:Net[{E792D967-DFD2-4946-85F0-104C9F342A7B}:192.168.195.x/24:Ethernet]]: SetOption(1, 65536) failed: 0
[2320:3864:0425/033253.974:WARNING:p2ptransportchannel.cc(528)] Jingle:Port[0F4F3CA8:audio:1:0:local:Net[{E792D967-DFD2-4946-85F0-104C9F342A7B}:192.168.195.x/24:Ethernet]]: SetOption(2, 65536) failed: 0
[2320:3864:0425/033253.974:WARNING:p2ptransportchannel.cc(528)] Jingle:Port[0F4F3CA8:audio:1:0:local:Net[{E792D967-DFD2-4946-85F0-104C9F342A7B}:192.168.195.x/24:Ethernet]]: SetOption(5, 0) failed: 0
[3104:1120:0425/033253.985:INFO:CONSOLE(326)] "Still ICE gathering - waiting...", source: http://127.0.0.1:55339/webrtc/peerconnection.js (326)
[3104:1120:0425/033254.086:INFO:CONSOLE(13)] "Returning [{"candidate":"candidate:278816273 1 udp 2122260223 192.168.195.147 62999 typ host generation 0 ufrag X+0R network-id 1","sdpMid":"audio","sdpMLineIndex":0},{"candidate":"candidate:1578998497 1 tcp 1518280447 192.168.195.147 9 typ host tcptype active generation 0 ufrag X+0R network-id 1","sdpMid":"audio","sdpMLineIndex":0}] to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033254.088:INFO:CONSOLE(13)] "Returning ok-received-candidates to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033254.089:INFO:CONSOLE(13)] "Returning [{"candidate":"candidate:278816273 1 udp 2122260223 192.168.195.147 62997 typ host generation 0 ufrag Gs1S network-id 1","sdpMid":"audio","sdpMLineIndex":0},{"candidate":"candidate:278816273 1 udp 2122260223 192.168.195.147 62998 typ host generation 0 ufrag Gs1S network-id 1","sdpMid":"video","sdpMLineIndex":1},{"candidate":"candidate:1578998497 1 tcp 1518280447 192.168.195.147 9 typ host tcptype active generation 0 ufrag Gs1S network-id 1","sdpMid":"audio","sdpMLineIndex":0}] to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033254.094:INFO:CONSOLE(13)] "Returning ok-received-candidates to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033254.096:INFO:CONSOLE(13)] "Returning ok-started to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033254.097:INFO:CONSOLE(13)] "Returning ok-started to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033254.099:INFO:CONSOLE(13)] "Returning video-not-playing to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033254.351:INFO:CONSOLE(13)] "Returning video-not-playing to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033254.606:INFO:CONSOLE(13)] "Returning video-playing to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[3104:1120:0425/033254.607:INFO:CONSOLE(13)] "Returning video-playing to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[752:3076:0425/033306.592:WARNING:rtp_rtcp_impl.cc(175)] webrtc::ModuleRtpRtcpImpl::Process: Timeout: No increase in RTCP RR extended highest sequence number.
[2320:2936:0425/033306.656:WARNING:rtp_rtcp_impl.cc(173)] webrtc::ModuleRtpRtcpImpl::Process: Timeout: No RTCP RR received.
[2320:2936:0425/033306.661:WARNING:rtp_rtcp_impl.cc(175)] webrtc::ModuleRtpRtcpImpl::Process: Timeout: No increase in RTCP RR extended highest sequence number.
[752:3076:0425/033306.897:WARNING:rtp_rtcp_impl.cc(173)] webrtc::ModuleRtpRtcpImpl::Process: Timeout: No RTCP RR received.
[2320:3720:0425/033307.136:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033307.136:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[3104:3756:0425/033309.231:ERROR:video_capture_device_client.cc(417)] error@ OnIncomingCapturedData@e:\b\c\b\win_builder\src\media\capture\video\video_capture_device_client.cc:195, Too many frames dropped, OS message: The operation completed successfully. (0x0)
[2320:3720:0425/033310.137:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033310.137:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033313.138:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033313.138:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033316.139:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033316.139:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3960:0425/033316.540:WARNING:rtp_rtcp_impl.cc(175)] webrtc::ModuleRtpRtcpImpl::Process: Timeout: No increase in RTCP RR extended highest sequence number.
[2320:3960:0425/033318.656:WARNING:rtp_rtcp_impl.cc(173)] webrtc::ModuleRtpRtcpImpl::Process: Timeout: No RTCP RR received.
[752:2768:0425/033318.897:WARNING:rtp_rtcp_impl.cc(173)] webrtc::ModuleRtpRtcpImpl::Process: Timeout: No RTCP RR received.
[752:2768:0425/033318.902:WARNING:rtp_rtcp_impl.cc(175)] webrtc::ModuleRtpRtcpImpl::Process: Timeout: No increase in RTCP RR extended highest sequence number.
[2320:3720:0425/033319.141:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033319.141:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033322.142:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033322.142:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033325.143:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033325.143:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033328.145:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033328.145:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033331.146:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033331.146:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033334.147:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033334.147:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033337.148:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033337.148:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033340.150:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033340.150:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033343.151:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033343.151:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033346.152:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033346.152:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033349.154:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033349.154:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033352.155:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033352.155:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033355.156:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033355.156:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[3772:856:0425/033355.646:ERROR:gles2_cmd_decoder.cc(17682)] [.DisplayCompositor-119F3118]GL ERROR :GL_INVALID_OPERATION : glCreateAndConsumeTextureCHROMIUM: invalid mailbox name
[3772:856:0425/033355.646:ERROR:gles2_cmd_decoder.cc(9716)] [.DisplayCompositor-119F3118]RENDER WARNING: texture bound to texture unit 0 is not renderable. It maybe non-power-of-2 and have incompatible texture filtering.
[3772:856:0425/033355.662:ERROR:gles2_cmd_decoder.cc(17682)] [.DisplayCompositor-119F3118]GL ERROR :GL_INVALID_OPERATION : glCreateAndConsumeTextureCHROMIUM: invalid mailbox name
[3772:856:0425/033355.662:ERROR:gles2_cmd_decoder.cc(9716)] [.DisplayCompositor-119F3118]RENDER WARNING: texture bound to texture unit 0 is not renderable. It maybe non-power-of-2 and have incompatible texture filtering.
[3772:856:0425/033355.678:ERROR:gles2_cmd_decoder.cc(17682)] [.DisplayCompositor-119F3118]GL ERROR :GL_INVALID_OPERATION : glCreateAndConsumeTextureCHROMIUM: invalid mailbox name
[3772:856:0425/033355.678:ERROR:gles2_cmd_decoder.cc(9716)] [.DisplayCompositor-119F3118]RENDER WARNING: texture bound to texture unit 0 is not renderable. It maybe non-power-of-2 and have incompatible texture filtering.
[752:3120:0425/033358.157:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033358.157:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033401.159:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033401.159:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033404.160:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[752:3120:0425/033404.160:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
e:\b\c\b\win_builder\src\chrome\browser\media\webrtc\webrtc_browsertest_perf.cc(203): error: Value of: pc_dict.GetString( Statistic("googAvailableSendBandwidth", kBweStatsKey), &value)
  Actual: false
Expected: true
[752:1676:0425/033405.467:WARNING:rtp_rtcp_impl.cc(355)] Failed to send RTCP BYE
[3104:1120:0425/033405.472:INFO:CONSOLE(13)] "Returning ok-call-hung-up to test.", source: http://127.0.0.1:55339/webrtc/test_functions.js (13)
[2320:3720:0425/033407.161:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033410.163:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033413.164:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033416.165:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033419.166:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033422.168:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033425.169:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033428.170:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033431.172:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033434.173:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033437.174:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033440.175:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033443.177:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033446.178:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033449.179:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033452.181:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033455.182:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033458.183:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033501.184:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033504.186:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033507.187:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033510.188:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033513.190:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033516.191:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033519.192:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033522.194:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033525.195:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033528.196:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033531.197:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033534.199:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033537.200:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033540.201:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033543.202:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033546.203:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033549.205:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033552.206:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033555.207:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033558.208:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033601.209:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033604.210:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033607.212:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033610.213:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033613.214:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033616.215:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033619.216:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033622.217:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033625.219:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033628.220:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033631.221:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033634.222:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033637.223:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033640.225:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033643.226:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033646.227:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033649.228:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033652.229:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033655.230:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033658.232:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033701.233:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033704.234:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033707.235:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033710.236:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033713.237:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033716.239:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033719.240:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033722.241:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033725.242:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033728.243:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033731.244:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033734.246:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033737.247:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033740.248:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033743.249:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033746.250:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033749.251:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033752.253:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033755.254:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033758.255:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033801.256:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033804.257:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033807.259:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033810.260:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033813.261:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033816.262:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033819.263:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033822.264:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033825.266:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033828.267:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033831.268:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033834.269:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3720:0425/033837.270:WARNING:video_receive_stream.cc(485)] No decodable frame in 3000 ms, requesting keyframe.
[2320:3268:0425/033840.011:ERROR:gpu_video_encode_accelerator_host.cc(226)] Error from OnChannelError(e:\b\c\b\win_builder\src\media\gpu\ipc\client\gpu_video_encode_accelerator_host.cc:69) OnChannelError() (error = 2)
[2320:3268:0425/033840.011:ERROR:gpu_video_encode_accelerator_host.cc(293)] OnNotifyError error=2
[76/76] WebRtcInternalsPerfBrowserTest.MANUAL_RunsAudioVideoCall60SecsAndLogsInternalMetricsH264 (TIMED OUT)


I'll go ahead and disable this test.

Comment 17 by hbos@chromium.org, Apr 25 2017

Is the above ("No decodable frame in 3000 ms, requesting keyframe.") only in flaky runs?

When investigating https://bugs.chromium.org/p/webrtc/issues/detail?id=7505 I got these warnings consistently every run!

How to repro: Open up two tabs and go to https://meet.jit.si/AnyStringIdentifyingTheRoom
Project Member

Comment 19 by bugdroid1@chromium.org, Apr 25 2017

The following revision refers to this bug:
  https://chromium.googlesource.com/chromium/src.git/+/774479d06817e48e69fdce1d464cc2299c81e01d

commit 774479d06817e48e69fdce1d464cc2299c81e01d
Author: maxmorin <maxmorin@chromium.org>
Date: Tue Apr 25 13:25:12 2017

Disable flaky WebRtcInternalsPerfBrowserTest on Windows.

WebRtcInternalsPerfBrowserTest.MANUAL_RunsAudioVideoCall60SecsAndLogsInternalMetricsH264
is flaky on Windows.

BUG= 703579 
TBR=hbos@chromium.org

Review-Url: https://codereview.chromium.org/2842643003
Cr-Commit-Position: refs/heads/master@{#466964}

[modify] https://crrev.com/774479d06817e48e69fdce1d464cc2299c81e01d/chrome/browser/media/webrtc/webrtc_internals_perf_browsertest.cc

I couldn't reproduce a failed run locally on my Lenovo ThinkPad P50 of this flaky test when I tried, see #9. I am thinking it might be basically because of the hardware difference between bots and my machine, so I am waiting for a lower end device to test. 

There hasn't been any significant changes in media_foundation_video_encode_accelerator_win.* in the time frame when this started failing either.
https://chromium.googlesource.com/chromium/src/+log/302ecd60a8d39c4684bb148fbead2097d9b989d9/media/gpu/media_foundation_video_encode_accelerator_win.cc
It looks like the underlying issue for this is the same as:
https://bugs.chromium.org/p/chromium/issues/detail?id=722746#c20

On #16, you can see the below logs which point to the same error pointing to the frames dropped.
[3104:3756:0425/033309.231:ERROR:video_capture_device_client.cc(417)] error@ OnIncomingCapturedData@e:\b\c\b\win_builder\src\media\capture\video\video_capture_device_client.cc:195, Too many frames dropped, OS message: The operation completed successfully. (0x0)

I will re-enable the tests and monitor.
Project Member

Comment 22 by bugdroid1@chromium.org, May 23 2017

The following revision refers to this bug:
  https://chromium.googlesource.com/chromium/src.git/+/778f69d41ab8539ec7d45d7e24ed58582133d00e

commit 778f69d41ab8539ec7d45d7e24ed58582133d00e
Author: emircan <emircan@chromium.org>
Date: Tue May 23 21:14:18 2017

Revert of Disable flaky WebRtcInternalsPerfBrowserTest on Windows. (patchset #2 id:20001 of https://codereview.chromium.org/2842643003/ )

Reason for revert:
This test should pass after the fixes on  issue webrtc:7532 . See the explanation https://bugs.chromium.org/p/chromium/issues/detail?id=722746#c21 for how they are related.

Original issue's description:
> Disable flaky WebRtcInternalsPerfBrowserTest on Windows.
>
> WebRtcInternalsPerfBrowserTest.MANUAL_RunsAudioVideoCall60SecsAndLogsInternalMetricsH264
> is flaky on Windows.
>
> BUG= 703579 
> TBR=hbos@chromium.org
>
> Review-Url: https://codereview.chromium.org/2842643003
> Cr-Commit-Position: refs/heads/master@{#466964}
> Committed: https://chromium.googlesource.com/chromium/src/+/774479d06817e48e69fdce1d464cc2299c81e01d

TBR=maxmorin@chromium.org
# Not skipping CQ checks because original CL landed more than 1 days ago.
BUG= 703579 

Review-Url: https://codereview.chromium.org/2899103002
Cr-Commit-Position: refs/heads/master@{#474054}

[modify] https://crrev.com/778f69d41ab8539ec7d45d7e24ed58582133d00e/chrome/browser/media/webrtc/webrtc_internals_perf_browsertest.cc

Status: Fixed (was: Assigned)

Sign in to add a comment