WebRTC: Audio is "Inactive" when Responding to SDP Offer from Browser

Luke McGartland luke.mcgartland at gmail.com
Sat Oct 24 15:28:51 UTC 2020


I'm working on learning webrtcbin and gstreamer in Rust. I've successfully
been able to send a video stream to the web browser client. However,
getting the audio to come through continues to baffle me. Any and all help
would be greatly appreciated.

In general, I'm following
https://github.com/centricular/gstwebrtc-demos/blob/master/sendrecv/ as an
example and using the Rust backend. Instead of making the offer from the
server, I actually initiate a recvonly SDP offer from the client (Chrome).

I'm using v0.15 of the Rust Bindings (v0.16 did not solve this either) and
running v1.18 of GStreamer in a container (I've also tried v1.16 and that
did not solve things, but would welcome advice on which release to pair
with the bindings properly).

(Full logs with GST_DEBUG=3,webrtc*:7 on are attached at bottom).

*In summary, here is the pipeline:*

videotestsrc pattern=ball is-live=true ! vp8enc deadline=1 ! rtpvp8pay
pt=96 ! webrtcbin. \
audiotestsrc is-live=true ! opusenc ! rtpopuspay pt=97 ! webrtcbin. \
webrtcbin name=webrtcbin

*Here is my SDP offer that I send from Chrome:*

v=0
o=- 450776629876255560 3 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE 0 1
a=msid-semantic: WMS
m=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:JU/P
a=ice-pwd:TwQ3gTRoX/4EeBLk/VEd3DuU
a=ice-options:trickle
a=fingerprint:sha-256
C1:FF:37:89:F0:84:B7:8D:AF:69:24:C4:1D:77:E0:B7:11:A2:B0:8A:75:AB:D9:F6:67:3A:BD:43:85:74:CB:BA
a=setup:actpass
a=mid:0
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
a=recvonly
a=rtcp-mux
a=rtpmap:111 opus/48000/2
a=rtcp-fb:111 transport-cc
a=fmtp:111 minptime=10;useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:104 ISAC/32000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:110 telephone-event/48000
a=rtpmap:112 telephone-event/32000
a=rtpmap:113 telephone-event/16000
a=rtpmap:126 telephone-event/8000
m=video 9 UDP/TLS/RTP/SAVPF 96 97 98 99 100 101 122 102 121 127 120 125 107
108 109 124 119 123 118 114 115 116
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:JU/P
a=ice-pwd:TwQ3gTRoX/4EeBLk/VEd3DuU
a=ice-options:trickle
a=fingerprint:sha-256
C1:FF:37:89:F0:84:B7:8D:AF:69:24:C4:1D:77:E0:B7:11:A2:B0:8A:75:AB:D9:F6:67:3A:BD:43:85:74:CB:BA
a=setup:actpass
a=mid:1
a=extmap:14 urn:ietf:params:rtp-hdrext:toffset
a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=extmap:13 urn:3gpp:video-orientation
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay
a=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type
a=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing
a=extmap:8 http://www.webrtc.org/experiments/rtp-hdrext/color-space
a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
a=recvonly
a=rtcp-mux
a=rtcp-rsize
a=rtpmap:96 VP8/90000
a=rtcp-fb:96 goog-remb
a=rtcp-fb:96 transport-cc
a=rtcp-fb:96 ccm fir
a=rtcp-fb:96 nack
a=rtcp-fb:96 nack pli
a=rtpmap:97 rtx/90000
a=fmtp:97 apt=96
a=rtpmap:98 VP9/90000
a=rtcp-fb:98 goog-remb
a=rtcp-fb:98 transport-cc
a=rtcp-fb:98 ccm fir
a=rtcp-fb:98 nack
a=rtcp-fb:98 nack pli
a=fmtp:98 profile-id=0
a=rtpmap:99 rtx/90000
a=fmtp:99 apt=98
a=rtpmap:100 VP9/90000
a=rtcp-fb:100 goog-remb
a=rtcp-fb:100 transport-cc
a=rtcp-fb:100 ccm fir
a=rtcp-fb:100 nack
a=rtcp-fb:100 nack pli
a=fmtp:100 profile-id=2
a=rtpmap:101 rtx/90000
a=fmtp:101 apt=100
a=rtpmap:122 VP9/90000
a=rtcp-fb:122 goog-remb
a=rtcp-fb:122 transport-cc
a=rtcp-fb:122 ccm fir
a=rtcp-fb:122 nack
a=rtcp-fb:122 nack pli
a=fmtp:122 profile-id=1
a=rtpmap:102 H264/90000
a=rtcp-fb:102 goog-remb
a=rtcp-fb:102 transport-cc
a=rtcp-fb:102 ccm fir
a=rtcp-fb:102 nack
a=rtcp-fb:102 nack pli
a=fmtp:102
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42001f
a=rtpmap:121 rtx/90000
a=fmtp:121 apt=102
a=rtpmap:127 H264/90000
a=rtcp-fb:127 goog-remb
a=rtcp-fb:127 transport-cc
a=rtcp-fb:127 ccm fir
a=rtcp-fb:127 nack
a=rtcp-fb:127 nack pli
a=fmtp:127
level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42001f
a=rtpmap:120 rtx/90000
a=fmtp:120 apt=127
a=rtpmap:125 H264/90000
a=rtcp-fb:125 goog-remb
a=rtcp-fb:125 transport-cc
a=rtcp-fb:125 ccm fir
a=rtcp-fb:125 nack
a=rtcp-fb:125 nack pli
a=fmtp:125
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
a=rtpmap:107 rtx/90000
a=fmtp:107 apt=125
a=rtpmap:108 H264/90000
a=rtcp-fb:108 goog-remb
a=rtcp-fb:108 transport-cc
a=rtcp-fb:108 ccm fir
a=rtcp-fb:108 nack
a=rtcp-fb:108 nack pli
a=fmtp:108
level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42e01f
a=rtpmap:109 rtx/90000
a=fmtp:109 apt=108
a=rtpmap:124 H264/90000
a=rtcp-fb:124 goog-remb
a=rtcp-fb:124 transport-cc
a=rtcp-fb:124 ccm fir
a=rtcp-fb:124 nack
a=rtcp-fb:124 nack pli
a=fmtp:124
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=4d0033
a=rtpmap:119 rtx/90000
a=fmtp:119 apt=124
a=rtpmap:123 H264/90000
a=rtcp-fb:123 goog-remb
a=rtcp-fb:123 transport-cc
a=rtcp-fb:123 ccm fir
a=rtcp-fb:123 nack
a=rtcp-fb:123 nack pli
a=fmtp:123
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=640033
a=rtpmap:118 rtx/90000
a=fmtp:118 apt=123
a=rtpmap:114 red/90000
a=rtpmap:115 rtx/90000
a=fmtp:115 apt=114
a=rtpmap:116 ulpfec/90000

*In response, I get back this SDP answer:*

v=0
o=- 450776629876255560 3 IN IP4 0.0.0.0
s=-
t=0 0
a=group:BUNDLE 0 1
m=audio 9 UDP/TLS/RTP/SAVPF 111
c=IN IP4 0.0.0.0
a=ice-ufrag:bXg2gVATyBFsqyxzOR1ewruZUwQwUOQ5
a=ice-pwd:ivjkDOOiymRHUA0wkgYVs9cJEK88zoVi
a=mid:0
a=rtcp-mux
a=setup:active
a=rtpmap:111 OPUS/48000/2
a=fmtp:111 minptime=10;useinbandfec=1
a=inactive
a=fingerprint:sha-256
C7:D4:3C:CA:67:8D:E5:59:47:0C:21:7D:9E:DE:53:1E:F3:08:16:52:7A:10:9C:EE:EB:BB:73:0A:72:6C:DF:6E
m=video 9 UDP/TLS/RTP/SAVPF 96
c=IN IP4 0.0.0.0
a=ice-ufrag:bXg2gVATyBFsqyxzOR1ewruZUwQwUOQ5
a=ice-pwd:ivjkDOOiymRHUA0wkgYVs9cJEK88zoVi
a=mid:1
a=rtcp-mux
a=setup:active
a=rtpmap:96 VP8/90000
a=rtcp-fb:96 nack pli
a=rtcp-fb:96 ccm fir
a=framerate:30
a=ssrc:1290502017 msid:user2307279746 at host-5afdf240 webrtctransceiver0
a=ssrc:1290502017 cname:user2307279746 at host-5afdf240
a=sendonly
a=fingerprint:sha-256
C7:D4:3C:CA:67:8D:E5:59:47:0C:21:7D:9E:DE:53:1E:F3:08:16:52:7A:10:9C:EE:EB:BB:73:0A:72:6C:DF:6E

I can confirm in the browser that a video track is added to my remote media
stream and plays back in a <video> element. However, the audio track never
arrives.

I see that audio track is set to "inactive" instead of "sendonly" in the
response unlike the video track. The audio track also appears to be missing
the ssrc attributes.

What's strange is that if I send another SDP offer from the same browser
session after the video is already playing, I get this SDP answer back from
the server:

v=0
o=- 3882210396617362357 3 IN IP4 0.0.0.0
s=-
t=0 0
a=group:BUNDLE 0 1
m=video 9 UDP/TLS/RTP/SAVPF 96
c=IN IP4 0.0.0.0
a=ice-ufrag:TwzdS7bSlrKTWJhUpcj0+ypaa92FQnLp
a=ice-pwd:GXOUkZtGt+JGPyZmaQn2ZWg9+UUdrtL6
a=mid:0
a=rtcp-mux
a=setup:active
a=rtpmap:96 VP8/90000
a=framerate:30
a=ssrc:422817870 msid:user2542828131 at host-8f3a122d webrtctransceiver0
a=ssrc:422817870 cname:user2542828131 at host-8f3a122d
a=sendonly
a=fingerprint:sha-256
6F:CE:BF:8A:1A:92:34:4F:D1:26:C9:13:78:79:AB:AE:1B:28:C1:02:B5:5F:66:C1:9B:B5:BB:E4:77:22:1E:90
m=audio 9 UDP/TLS/RTP/SAVPF 97
c=IN IP4 0.0.0.0
a=ice-ufrag:TwzdS7bSlrKTWJhUpcj0+ypaa92FQnLp
a=ice-pwd:GXOUkZtGt+JGPyZmaQn2ZWg9+UUdrtL6
a=mid:1
a=rtcp-mux
a=setup:active
a=rtpmap:97 OPUS/48000/2
a=fmtp:97 sprop-maxcapturerate=48000;sprop-stereo=0
a=ssrc:4187272436 msid:user2542828131 at host-8f3a122d webrtctransceiver1
a=ssrc:4187272436 cname:user2542828131 at host-8f3a122d
a=sendonly
a=fingerprint:sha-256
6F:CE:BF:8A:1A:92:34:4F:D1:26:C9:13:78:79:AB:AE:1B:28:C1:02:B5:5F:66:C1:9B:B5:BB:E4:77:22:1E:90

However, this triggers an error in Chrome because the m-lines are in the
opposite order.

setRemoteDescription DOMException: Failed to execute 'setRemoteDescription'
on 'RTCPeerConnection': Failed to set remote answer sdp: The order of
m-lines in answer doesn't match order in offer. Rejecting answer.


Any help would be greatly appreciated. I'm new to this community but I see
a lot of potential with this library and would love to continue to learn
more and contribute in the future (especially on the Rust side)!

----------

Here is an example set of logs with (apologies for my terribly formatted
println!() debugging messages mixed in)

Checking for required gstreamer plugins...
WebSocket handshake has been successfully completed
Setting up pipeline
0:00:00.040135596  2466 0x58b47b56ef20 DEBUG              webrtcbin
gstwebrtcbin.c:319:gst_webrtc_bin_pad_new:<'':sink_0> new visible pad with
direction sink
0:00:00.040182602  2466 0x58b47b56ef20 TRACE              webrtcbin
gstwebrtcbin.c:469:_find_transceiver_for_mline:<webrtcbin> Found
transceiver (NULL) for mlineindex 0
0:00:00.040755260  2466 0x58b47b56ef20 LOG                webrtcbin
gstwebrtcbin.c:5810:gst_webrtc_bin_request_new_pad:<webrtcbin> Created new
transceiver <webrtctransceiver0> for mline 0
0:00:00.042137091  2466 0x58b47b56ef20 DEBUG              webrtcbin
gstwebrtcbin.c:319:gst_webrtc_bin_pad_new:<'':sink_1> new visible pad with
direction sink
0:00:00.042170292  2466 0x58b47b56ef20 TRACE              webrtcbin
gstwebrtcbin.c:469:_find_transceiver_for_mline:<webrtcbin> Found
transceiver (NULL) for mlineindex 1
0:00:00.042202906  2466 0x58b47b56ef20 LOG                webrtcbin
gstwebrtcbin.c:5810:gst_webrtc_bin_request_new_pad:<webrtcbin> Created new
transceiver <webrtctransceiver1> for mline 1
0:00:00.042636827  2466 0x58b47b56ef20 DEBUG              webrtcice
gstwebrtcice.c:958:gst_webrtc_ice_set_stun_server:<webrtcbin0:ice> setting
stun server, stun://stun.l.google.com:19302
0:00:00.044352105  2466 0x58b47b57a850 DEBUG              webrtcbin
gstwebrtcbin.c:5724:gst_webrtc_bin_change_state: changing state: NULL =>
READY
0:00:00.045240190  2466 0x58b47b57a850 LOG                webrtcbin
gstwebrtcbin.c:1341:_check_if_negotiation_is_needed:<webrtcbin> checking if
negotiation is needed
0:00:00.045679975  2466 0x58b47b57a850 LOG                webrtcbin
gstwebrtcbin.c:1347:_check_if_negotiation_is_needed:<webrtcbin> no
negotiation possible until caps have been received on all sink pads
0:00:00.047373551  2466 0x58b47b57a850 DEBUG              webrtcbin
gstwebrtcbin.c:5724:gst_webrtc_bin_change_state: changing state: READY =>
PAUSED
0:00:00.048683270  2466 0x58b47b57a850 DEBUG              webrtcbin
gstwebrtcbin.c:5724:gst_webrtc_bin_change_state: changing state: PAUSED =>
PLAYING
0:00:00.049580487  2466 0x58b47b3846d0 DEBUG              webrtcbin
gstwebrtcbin.c:5724:gst_webrtc_bin_change_state: changing state: PLAYING =>
PLAYING
0:00:00.050287689  2466 0x7f7c980050a0 FIXME                default
gstutils.c:4026:gst_pad_create_stream_id_internal:<videotestsrc0:src>
Creating random stream-id, consider implementing a deterministic way of
creating a stream-id
0:00:00.052605344  2466 0x7f7c98005050 FIXME                default
gstutils.c:4026:gst_pad_create_stream_id_internal:<audiotestsrc0:src>
Creating random stream-id, consider implementing a deterministic way of
creating a stream-id
0:00:00.054544380  2466 0x7f7c98005050 DEBUG              webrtcbin
gstwebrtcbin.c:279:gst_webrtcbin_sink_event:<webrtcbin> On
<webrtcbin:sink_1> checking negotiation? 1, caps application/x-rtp,
media=(string)audio, clock-rate=(int)48000, encoding-name=(string)OPUS,
sprop-maxcapturerate=(string)48000, sprop-stereo=(string)0,
payload=(int)97, encoding-params=(string)2, ssrc=(uint)465568367,
timestamp-offset=(uint)1614781904, seqnum-offset=(uint)28659
0:00:00.055381380  2466 0x7f7c98005050 LOG                webrtcbin
gstwebrtcbin.c:1341:_check_if_negotiation_is_needed:<webrtcbin> checking if
negotiation is needed
0:00:00.055402242  2466 0x7f7c98005050 LOG                webrtcbin
gstwebrtcbin.c:1347:_check_if_negotiation_is_needed:<webrtcbin> no
negotiation possible until caps have been received on all sink pads
0:00:00.055783493  2466 0x7f7c98005050 LOG                webrtcbin
gstwebrtcbin.c:5771:sink_pad_block:<webrtcbin:sink_1> blocking pad with
data buffer: 0x7f7c8800f240, pts 0:00:00.005567255, dts 0:00:00.005567255,
dur 0:00:00.013500000, size 172, offset none, offset_end none, flags 0x4000
0:00:00.060227009  2466 0x7f7c980050a0 DEBUG              webrtcbin
gstwebrtcbin.c:279:gst_webrtcbin_sink_event:<webrtcbin> On
<webrtcbin:sink_0> checking negotiation? 1, caps application/x-rtp,
media=(string)video, clock-rate=(int)90000, encoding-name=(string)VP8,
payload=(int)96, ssrc=(uint)3497855691, timestamp-offset=(uint)3612195570,
seqnum-offset=(uint)12225, a-framerate=(string)30
0:00:00.061159720  2466 0x7f7c980050a0 LOG                webrtcbin
gstwebrtcbin.c:1341:_check_if_negotiation_is_needed:<webrtcbin> checking if
negotiation is needed
0:00:00.061750620  2466 0x7f7c980050a0 LOG                webrtcbin
gstwebrtcbin.c:1357:_check_if_negotiation_is_needed:<webrtcbin> no local
description set
0:00:00.061892135  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1499:_check_need_negotiation_task:<webrtcbin> emitting
on-negotiation-needed
0:00:00.067811992  2466 0x7f7c980050a0 LOG                webrtcbin
gstwebrtcbin.c:5771:sink_pad_block:<webrtcbin:sink_0> blocking pad with
data bufferlist: 0x58b47b509300, 1 buffers, pts 0:00:00.016753993, dts
99:99:99.999999999, size 736
handle_websocket_message:
{"protocol":"one-to-one","to":"ges-webrtc-service","action":"SEND_SDP","data":{"sdp":{"type":"offer","sdp":"v=0\r\no=-
6037884307875629115 4 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0
1\r\na=msid-semantic: WMS\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:bT57\r\na=ice-pwd:75COdxPqm2uxeNUWBFDiXJDq\r\na=ice-options:trickle\r\na=fingerprint:sha-256
8E:46:00:21:C6:6C:D0:53:10:70:C9:98:03:92:03:F2:6F:53:AB:2F:04:67:35:E7:96:5E:41:1D:C1:7E:34:4C\r\na=setup:actpass\r\na=mid:0\r\na=extmap:1
urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:2
http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4
urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5
urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6
urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\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\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 98 99 100 101 122
102 121 127 120 125 107 108 109 124 119 123 118 114 115 116\r\nc=IN IP4
0.0.0.0\r\na=rtcp:9 IN IP4
0.0.0.0\r\na=ice-ufrag:bT57\r\na=ice-pwd:75COdxPqm2uxeNUWBFDiXJDq\r\na=ice-options:trickle\r\na=fingerprint:sha-256
8E:46:00:21:C6:6C:D0:53:10:70:C9:98:03:92:03:F2:6F:53:AB:2F:04:67:35:E7:96:5E:41:1D:C1:7E:34:4C\r\na=setup:actpass\r\na=mid:1\r\na=extmap:14
urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2
http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13
urn:3gpp:video-orientation\r\na=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12
http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11
http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7
http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8
http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4
urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5
urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6
urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96
VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96
transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96
nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:98
VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98
transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98
nack pli\r\na=fmtp:98 profile-id=0\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99
apt=98\r\na=rtpmap:100 VP9/90000\r\na=rtcp-fb:100
goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm
fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100
profile-id=2\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101
apt=100\r\na=rtpmap:122 VP9/90000\r\na=rtcp-fb:122
goog-remb\r\na=rtcp-fb:122 transport-cc\r\na=rtcp-fb:122 ccm
fir\r\na=rtcp-fb:122 nack\r\na=rtcp-fb:122 nack pli\r\na=fmtp:122
profile-id=1\r\na=rtpmap:102 H264/90000\r\na=rtcp-fb:102
goog-remb\r\na=rtcp-fb:102 transport-cc\r\na=rtcp-fb:102 ccm
fir\r\na=rtcp-fb:102 nack\r\na=rtcp-fb:102 nack pli\r\na=fmtp:102
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42001f\r\na=rtpmap:121
rtx/90000\r\na=fmtp:121 apt=102\r\na=rtpmap:127 H264/90000\r\na=rtcp-fb:127
goog-remb\r\na=rtcp-fb:127 transport-cc\r\na=rtcp-fb:127 ccm
fir\r\na=rtcp-fb:127 nack\r\na=rtcp-fb:127 nack pli\r\na=fmtp:127
level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42001f\r\na=rtpmap:120
rtx/90000\r\na=fmtp:120 apt=127\r\na=rtpmap:125 H264/90000\r\na=rtcp-fb:125
goog-remb\r\na=rtcp-fb:125 transport-cc\r\na=rtcp-fb:125 ccm
fir\r\na=rtcp-fb:125 nack\r\na=rtcp-fb:125 nack pli\r\na=fmtp:125
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f\r\na=rtpmap:107
rtx/90000\r\na=fmtp:107 apt=125\r\na=rtpmap:108 H264/90000\r\na=rtcp-fb:108
goog-remb\r\na=rtcp-fb:108 transport-cc\r\na=rtcp-fb:108 ccm
fir\r\na=rtcp-fb:108 nack\r\na=rtcp-fb:108 nack pli\r\na=fmtp:108
level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42e01f\r\na=rtpmap:109
rtx/90000\r\na=fmtp:109 apt=108\r\na=rtpmap:124 H264/90000\r\na=rtcp-fb:124
goog-remb\r\na=rtcp-fb:124 transport-cc\r\na=rtcp-fb:124 ccm
fir\r\na=rtcp-fb:124 nack\r\na=rtcp-fb:124 nack pli\r\na=fmtp:124
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=4d0033\r\na=rtpmap:119
rtx/90000\r\na=fmtp:119 apt=124\r\na=rtpmap:123 H264/90000\r\na=rtcp-fb:123
goog-remb\r\na=rtcp-fb:123 transport-cc\r\na=rtcp-fb:123 ccm
fir\r\na=rtcp-fb:123 nack\r\na=rtcp-fb:123 nack pli\r\na=fmtp:123
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=640033\r\na=rtpmap:118
rtx/90000\r\na=fmtp:118 apt=123\r\na=rtpmap:114 red/90000\r\na=rtpmap:115
rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:116
ulpfec/90000\r\n"},"user":"123456"}}
GOT SEND_SDP MESSAGE from 123456
Received offer:
v=0
o=- 6037884307875629115 4 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE 0 1
a=msid-semantic: WMS
m=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:bT57
a=ice-pwd:75COdxPqm2uxeNUWBFDiXJDq
a=ice-options:trickle
a=fingerprint:sha-256
8E:46:00:21:C6:6C:D0:53:10:70:C9:98:03:92:03:F2:6F:53:AB:2F:04:67:35:E7:96:5E:41:1D:C1:7E:34:4C
a=setup:actpass
a=mid:0
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
a=recvonly
a=rtcp-mux
a=rtpmap:111 opus/48000/2
a=rtcp-fb:111 transport-cc
a=fmtp:111 minptime=10;useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:104 ISAC/32000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:110 telephone-event/48000
a=rtpmap:112 telephone-event/32000
a=rtpmap:113 telephone-event/16000
a=rtpmap:126 telephone-event/8000
m=video 9 UDP/TLS/RTP/SAVPF 96 97 98 99 100 101 122 102 121 127 120 125 107
108 109 124 119 123 118 114 115 116
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:bT57
a=ice-pwd:75COdxPqm2uxeNUWBFDiXJDq
a=ice-options:trickle
a=fingerprint:sha-256
8E:46:00:21:C6:6C:D0:53:10:70:C9:98:03:92:03:F2:6F:53:AB:2F:04:67:35:E7:96:5E:41:1D:C1:7E:34:4C
a=setup:actpass
a=mid:1
a=extmap:14 urn:ietf:params:rtp-hdrext:toffset
a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=extmap:13 urn:3gpp:video-orientation
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay
a=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type
a=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing
a=extmap:8 http://www.webrtc.org/experiments/rtp-hdrext/color-space
a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
a=recvonly
a=rtcp-mux
a=rtcp-rsize
a=rtpmap:96 VP8/90000
a=rtcp-fb:96 goog-remb
a=rtcp-fb:96 transport-cc
a=rtcp-fb:96 ccm fir
a=rtcp-fb:96 nack
a=rtcp-fb:96 nack pli
a=rtpmap:97 rtx/90000
a=fmtp:97 apt=96
a=rtpmap:98 VP9/90000
a=rtcp-fb:98 goog-remb
a=rtcp-fb:98 transport-cc
a=rtcp-fb:98 ccm fir
a=rtcp-fb:98 nack
a=rtcp-fb:98 nack pli
a=fmtp:98 profile-id=0
a=rtpmap:99 rtx/90000
a=fmtp:99 apt=98
a=rtpmap:100 VP9/90000
a=rtcp-fb:100 goog-remb
a=rtcp-fb:100 transport-cc
a=rtcp-fb:100 ccm fir
a=rtcp-fb:100 nack
a=rtcp-fb:100 nack pli
a=fmtp:100 profile-id=2
a=rtpmap:101 rtx/90000
a=fmtp:101 apt=100
a=rtpmap:122 VP9/90000
a=rtcp-fb:122 goog-remb
a=rtcp-fb:122 transport-cc
a=rtcp-fb:122 ccm fir
a=rtcp-fb:122 nack
a=rtcp-fb:122 nack pli
a=fmtp:122 profile-id=1
a=rtpmap:102 H264/90000
a=rtcp-fb:102 goog-remb
a=rtcp-fb:102 transport-cc
a=rtcp-fb:102 ccm fir
a=rtcp-fb:102 nack
a=rtcp-fb:102 nack pli
a=fmtp:102
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42001f
a=rtpmap:121 rtx/90000
a=fmtp:121 apt=102
a=rtpmap:127 H264/90000
a=rtcp-fb:127 goog-remb
a=rtcp-fb:127 transport-cc
a=rtcp-fb:127 ccm fir
a=rtcp-fb:127 nack
a=rtcp-fb:127 nack pli
a=fmtp:127
level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42001f
a=rtpmap:120 rtx/90000
a=fmtp:120 apt=127
a=rtpmap:125 H264/90000
a=rtcp-fb:125 goog-remb
a=rtcp-fb:125 transport-cc
a=rtcp-fb:125 ccm fir
a=rtcp-fb:125 nack
a=rtcp-fb:125 nack pli
a=fmtp:125
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
a=rtpmap:107 rtx/90000
a=fmtp:107 apt=125
a=rtpmap:108 H264/90000
a=rtcp-fb:108 goog-remb
a=rtcp-fb:108 transport-cc
a=rtcp-fb:108 ccm fir
a=rtcp-fb:108 nack
a=rtcp-fb:108 nack pli
a=fmtp:108
level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42e01f
a=rtpmap:109 rtx/90000
a=fmtp:109 apt=108
a=rtpmap:124 H264/90000
a=rtcp-fb:124 goog-remb
a=rtcp-fb:124 transport-cc
a=rtcp-fb:124 ccm fir
a=rtcp-fb:124 nack
a=rtcp-fb:124 nack pli
a=fmtp:124
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=4d0033
a=rtpmap:119 rtx/90000
a=fmtp:119 apt=124
a=rtpmap:123 H264/90000
a=rtcp-fb:123 goog-remb
a=rtcp-fb:123 transport-cc
a=rtcp-fb:123 ccm fir
a=rtcp-fb:123 nack
a=rtcp-fb:123 nack pli
a=fmtp:123
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=640033
a=rtpmap:118 rtx/90000
a=fmtp:118 apt=123
a=rtpmap:114 red/90000
a=rtpmap:115 rtx/90000
a=fmtp:115 apt=114
a=rtpmap:116 ulpfec/90000

0:00:04.021361800  2466 0x7f7c98005000 INFO               webrtcbin
gstwebrtcbin.c:4312:_set_description_task:<webrtcbin> Attempting to set
remote offer in the stable state
0:00:04.023018570  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4313:_set_description_task:<webrtcbin> SDP contents
v=0
o=- 6037884307875629115 4 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE 0 1
a=msid-semantic: WMS
m=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:bT57
a=ice-pwd:75COdxPqm2uxeNUWBFDiXJDq
a=ice-options:trickle
a=fingerprint:sha-256
8E:46:00:21:C6:6C:D0:53:10:70:C9:98:03:92:03:F2:6F:53:AB:2F:04:67:35:E7:96:5E:41:1D:C1:7E:34:4C
a=setup:actpass
a=mid:0
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
a=recvonly
a=rtcp-mux
a=rtpmap:111 opus/48000/2
a=rtcp-fb:111 transport-cc
a=fmtp:111 minptime=10;useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:104 ISAC/32000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:110 telephone-event/48000
a=rtpmap:112 telephone-event/32000
a=rtpmap:113 telephone-event/16000
a=rtpmap:126 telephone-event/8000
m=video 9 UDP/TLS/RTP/SAVPF 96 97 98 99 100 101 122 102 121 127 120 125 107
108 109 124 119 123 118 114 115 116
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:bT57
a=ice-pwd:75COdxPqm2uxeNUWBFDiXJDq
a=ice-options:trickle
a=fingerprint:sha-256
8E:46:00:21:C6:6C:D0:53:10:70:C9:98:03:92:03:F2:6F:53:AB:2F:04:67:35:E7:96:5E:41:1D:C1:7E:34:4C
a=setup:actpass
a=mid:1
a=extmap:14 urn:ietf:params:rtp-hdrext:toffset
a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=extmap:13 urn:3gpp:video-orientation
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay
a=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type
a=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing
a=extmap:8 http://www.webrtc.org/experiments/rtp-hdrext/color-space
a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
a=recvonly
a=rtcp-mux
a=rtcp-rsize
a=rtpmap:96 VP8/90000
a=rtcp-fb:96 goog-remb
a=rtcp-fb:96 transport-cc
a=rtcp-fb:96 ccm fir
a=rtcp-fb:96 nack
a=rtcp-fb:96 nack pli
a=rtpmap:97 rtx/90000
a=fmtp:97 apt=96
a=rtpmap:98 VP9/90000
a=rtcp-fb:98 goog-remb
a=rtcp-fb:98 transport-cc
a=rtcp-fb:98 ccm fir
a=rtcp-fb:98 nack
a=rtcp-fb:98 nack pli
a=fmtp:98 profile-id=0
a=rtpmap:99 rtx/90000
a=fmtp:99 apt=98
a=rtpmap:100 VP9/90000
a=rtcp-fb:100 goog-remb
a=rtcp-fb:100 transport-cc
a=rtcp-fb:100 ccm fir
a=rtcp-fb:100 nack
a=rtcp-fb:100 nack pli
a=fmtp:100 profile-id=2
a=rtpmap:101 rtx/90000
a=fmtp:101 apt=100
a=rtpmap:122 VP9/90000
a=rtcp-fb:122 goog-remb
a=rtcp-fb:122 transport-cc
a=rtcp-fb:122 ccm fir
a=rtcp-fb:122 nack
a=rtcp-fb:122 nack pli
a=fmtp:122 profile-id=1
a=rtpmap:102 H264/90000
a=rtcp-fb:102 goog-remb
a=rtcp-fb:102 transport-cc
a=rtcp-fb:102 ccm fir
a=rtcp-fb:102 nack
a=rtcp-fb:102 nack pli
a=fmtp:102
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42001f
a=rtpmap:121 rtx/90000
a=fmtp:121 apt=102
a=rtpmap:127 H264/90000
a=rtcp-fb:127 goog-remb
a=rtcp-fb:127 transport-cc
a=rtcp-fb:127 ccm fir
a=rtcp-fb:127 nack
a=rtcp-fb:127 nack pli
a=fmtp:127
level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42001f
a=rtpmap:120 rtx/90000
a=fmtp:120 apt=127
a=rtpmap:125 H264/90000
a=rtcp-fb:125 goog-remb
a=rtcp-fb:125 transport-cc
a=rtcp-fb:125 ccm fir
a=rtcp-fb:125 nack
a=rtcp-fb:125 nack pli
a=fmtp:125
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
a=rtpmap:107 rtx/90000
a=fmtp:107 apt=125
a=rtpmap:108 H264/90000
a=rtcp-fb:108 goog-remb
a=rtcp-fb:108 transport-cc
a=rtcp-fb:108 ccm fir
a=rtcp-fb:108 nack
a=rtcp-fb:108 nack pli
a=fmtp:108
level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42e01f
a=rtpmap:109 rtx/90000
a=fmtp:109 apt=108
a=rtpmap:124 H264/90000
a=rtcp-fb:124 goog-remb
a=rtcp-fb:124 transport-cc
a=rtcp-fb:124 ccm fir
a=rtcp-fb:124 nack
a=rtcp-fb:124 nack pli
a=fmtp:124
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=4d0033
a=rtpmap:119 rtx/90000
a=fmtp:119 apt=124
a=rtpmap:123 H264/90000
a=rtcp-fb:123 goog-remb
a=rtcp-fb:123 transport-cc
a=rtcp-fb:123 ccm fir
a=rtcp-fb:123 nack
a=rtcp-fb:123 nack pli
a=fmtp:123
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=640033
a=rtpmap:118 rtx/90000
a=fmtp:118 apt=123
a=rtpmap:114 red/90000
a=rtpmap:115 rtx/90000
a=fmtp:115 apt=114
a=rtpmap:116 ulpfec/90000

0:00:04.023208190  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:4479:_set_description_task:<webrtcbin> we are in ice
controlling mode: true
0:00:04.023233046  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
(NULL) for session 0
handle_websocket_message:
{"protocol":"one-to-one","to":"ges-webrtc-service","action":"SEND_CANDIDATE","data":{"candidate":{"candidate":"candidate:63126866
1 udp 2113937151 ec09dc7d-e98b-4caa-b8af-ae9b04577210.local 38930 typ host
generation 2 ufrag bT57 network-cost
999","sdpMid":"0","sdpMLineIndex":0},"user":"123456"}}
GOT SEND_CANDIDATE MESSAGE
handle_ice:
candidate:63126866 1 udp 2113937151
ec09dc7d-e98b-4caa-b8af-ae9b04577210.local 38930 typ host generation 2
ufrag bT57 network-cost 999

handle_websocket_message:
{"protocol":"one-to-one","to":"ges-webrtc-service","action":"SEND_CANDIDATE","data":{"candidate":{"candidate":"candidate:3933521124
1 udp 2113934591 e4de021a-535c-4e08-83f1-8b00c44ad5a2.local 38229 typ host
generation 2 ufrag bT57 network-cost
999","sdpMid":"0","sdpMLineIndex":0},"user":"123456"}}
GOT SEND_CANDIDATE MESSAGE
handle_ice:
candidate:3933521124 1 udp 2113934591
e4de021a-535c-4e08-83f1-8b00c44ad5a2.local 38229 typ host generation 2
ufrag bT57 network-cost 999

handle_websocket_message:
{"protocol":"one-to-one","to":"ges-webrtc-service","action":"SEND_CANDIDATE","data":{"candidate":{"candidate":"candidate:842163049
1 udp 1677729535 68.8.32.177 46192 typ srflx raddr 0.0.0.0 rport 0
generation 2 ufrag bT57 network-cost
999","sdpMid":"0","sdpMLineIndex":0},"user":"123456"}}
GOT SEND_CANDIDATE MESSAGE
handle_ice:
candidate:842163049 1 udp 1677729535 68.8.32.177 46192 typ srflx raddr
0.0.0.0 rport 0 generation 2 ufrag bT57 network-cost 999

0:00:04.107356121  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:400:_find_ice_stream_for_session:<webrtcbin> No ice stream
available for session 0
0:00:04.107418543  2466 0x7f7c98005000 DEBUG              webrtcice
gstwebrtcice.c:424:_add_stun_server:<webrtcbin0:ice> adding stun server,
stun://stun.l.google.com:19302
0:00:04.107519650  2466 0x7f7c98005000 DEBUG              webrtcice
gstwebrtcice.c:313:_resolve_host:<webrtcbin0:ice> Resolving host
stun.l.google.com
0:00:04.117080140  2466 0x7f7c98005000 DEBUG              webrtcice
gstwebrtcice.c:322:_resolve_host:<webrtcbin0:ice> Resolved 1 addresses for
host stun.l.google.com
0:00:04.119156837  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:411:_add_ice_stream_item:<webrtcbin> adding ice stream
<webrtcicestream0> for session 0
0:00:04.122378950  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1700:_create_transport_channel:<webrtcbin> Create transport
<transportstream0> for session 0
0:00:04.123957353  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:5188:on_rtpbin_pad_added:<webrtcbin> new rtpbin pad
recv_rtcp_sink_0
0:00:04.124056653  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:5188:on_rtpbin_pad_added:<webrtcbin> new rtpbin pad
send_rtcp_src_0
0:00:04.124148282  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: NULL => READY
0:00:04.124206903  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: READY => PAUSED
0:00:04.125246274  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PAUSED => PLAYING
0:00:04.125348838  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: NULL => READY
0:00:04.125758539  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: READY => PAUSED
0:00:04.126599558  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: PAUSED => PLAYING
0:00:04.128235560  2466 0x7f7c98005000 DEBUG              webrtcice
gstwebrtcice.c:746:gst_webrtc_ice_set_remote_credentials:<webrtcbin0:ice>
Setting remote ICE credentials on ICE stream 1 ufrag:bT57
pwd:75COdxPqm2uxeNUWBFDiXJDq
0:00:04.128831496  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:04.128866622  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:04.128959866  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: PLAYING => PLAYING
0:00:04.129041986  2466 0x7f7c98005000 DEBUG              webrtcice
gstwebrtcice.c:746:gst_webrtc_ice_set_remote_credentials:<webrtcbin0:ice>
Setting remote ICE credentials on ICE stream 1 ufrag:bT57
pwd:75COdxPqm2uxeNUWBFDiXJDq
0:00:04.129062303  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4640:_set_description_task:<webrtcbin> notify
signaling-state from have-remote-offer to have-remote-offer
-----------SET REMOTE DESCRIPTION------------
0:00:04.129222934  2466 0x7f7c98005000 INFO               webrtcbin
gstwebrtcbin.c:3310:_create_sdp_task:<webrtcbin> creating answer sdp with
options (NULL)
0:00:04.129508405  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:1545:_find_codec_preferences:<webrtcbin> retrieving codec
preferences from <webrtctransceiver0>
0:00:04.131310130  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:3120:_create_answer_task:<webrtcbin> trying to compare
application/x-rtp, media=(string)audio, payload=(int)111,
clock-rate=(int)48000, encoding-name=(string)OPUS,
encoding-params=(string)2, minptime=(string)10, useinbandfec=(string)1,
rtcp-fb-transport-cc=(boolean)true; application/x-rtp, media=(string)audio,
payload=(int)103, clock-rate=(int)16000, encoding-name=(string)ISAC;
application/x-rtp, media=(string)audio, payload=(int)104,
clock-rate=(int)32000, encoding-name=(string)ISAC; application/x-rtp,
media=(string)audio, payload=(int)9, clock-rate=(int)8000,
encoding-name=(string)G722; application/x-rtp, media=(string)audio,
payload=(int)0, clock-rate=(int)8000, encoding-name=(string)PCMU;
application/x-rtp, media=(string)audio, payload=(int)8,
clock-rate=(int)8000, encoding-name=(string)PCMA; application/x-rtp,
media=(string)audio, payload=(int)106, clock-rate=(int)32000,
encoding-name=(string)CN; application/x-rtp, media=(string)audio,
payload=(int)105, clock-rate=(int)16000, encoding-name=(string)CN;
application/x-rtp, media=(string)audio, payload=(int)13,
clock-rate=(int)8000, encoding-name=(string)CN; application/x-rtp,
media=(string)audio, payload=(int)110, clock-rate=(int)48000,
encoding-name=(string)TELEPHONE-EVENT; application/x-rtp,
media=(string)audio, payload=(int)112, clock-rate=(int)32000,
encoding-name=(string)TELEPHONE-EVENT; application/x-rtp,
media=(string)audio, payload=(int)113, clock-rate=(int)16000,
encoding-name=(string)TELEPHONE-EVENT; application/x-rtp,
media=(string)audio, payload=(int)126, clock-rate=(int)8000,
encoding-name=(string)TELEPHONE-EVENT and application/x-rtp,
media=(string)video, clock-rate=(int)90000, encoding-name=(string)VP8,
payload=(int)96, ssrc=(uint)3497855691, timestamp-offset=(uint)3612195570,
seqnum-offset=(uint)12225, a-framerate=(string)30
0:00:04.131359231  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:1545:_find_codec_preferences:<webrtcbin> retrieving codec
preferences from <webrtctransceiver1>
0:00:04.131394964  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:3120:_create_answer_task:<webrtcbin> trying to compare
application/x-rtp, media=(string)audio, payload=(int)111,
clock-rate=(int)48000, encoding-name=(string)OPUS,
encoding-params=(string)2, minptime=(string)10, useinbandfec=(string)1,
rtcp-fb-transport-cc=(boolean)true; application/x-rtp, media=(string)audio,
payload=(int)103, clock-rate=(int)16000, encoding-name=(string)ISAC;
application/x-rtp, media=(string)audio, payload=(int)104,
clock-rate=(int)32000, encoding-name=(string)ISAC; application/x-rtp,
media=(string)audio, payload=(int)9, clock-rate=(int)8000,
encoding-name=(string)G722; application/x-rtp, media=(string)audio,
payload=(int)0, clock-rate=(int)8000, encoding-name=(string)PCMU;
application/x-rtp, media=(string)audio, payload=(int)8,
clock-rate=(int)8000, encoding-name=(string)PCMA; application/x-rtp,
media=(string)audio, payload=(int)106, clock-rate=(int)32000,
encoding-name=(string)CN; application/x-rtp, media=(string)audio,
payload=(int)105, clock-rate=(int)16000, encoding-name=(string)CN;
application/x-rtp, media=(string)audio, payload=(int)13,
clock-rate=(int)8000, encoding-name=(string)CN; application/x-rtp,
media=(string)audio, payload=(int)110, clock-rate=(int)48000,
encoding-name=(string)TELEPHONE-EVENT; application/x-rtp,
media=(string)audio, payload=(int)112, clock-rate=(int)32000,
encoding-name=(string)TELEPHONE-EVENT; application/x-rtp,
media=(string)audio, payload=(int)113, clock-rate=(int)16000,
encoding-name=(string)TELEPHONE-EVENT; application/x-rtp,
media=(string)audio, payload=(int)126, clock-rate=(int)8000,
encoding-name=(string)TELEPHONE-EVENT and application/x-rtp,
media=(string)audio, clock-rate=(int)48000, encoding-name=(string)OPUS,
sprop-maxcapturerate=(string)48000, sprop-stereo=(string)0,
payload=(int)97, encoding-params=(string)2, ssrc=(uint)465568367,
timestamp-offset=(uint)1614781904, seqnum-offset=(uint)28659
0:00:04.131455960  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:3176:_create_answer_task:<webrtcbin> Created new transceiver
<webrtctransceiver2> for mline 0
0:00:04.131509657  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:04.131528075  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:04.131566263  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: PLAYING => PLAYING
0:00:04.133398729  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:1545:_find_codec_preferences:<webrtcbin> retrieving codec
preferences from <webrtctransceiver0>
0:00:04.133512214  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:3120:_create_answer_task:<webrtcbin> trying to compare
application/x-rtp, media=(string)video, payload=(int)96,
clock-rate=(int)90000, encoding-name=(string)VP8,
rtcp-fb-goog-remb=(boolean)true, rtcp-fb-transport-cc=(boolean)true,
rtcp-fb-ccm-fir=(boolean)true, rtcp-fb-nack=(boolean)true,
rtcp-fb-nack-pli=(boolean)true; application/x-rtp, media=(string)video,
payload=(int)97, clock-rate=(int)90000, encoding-name=(string)RTX,
apt=(string)96; application/x-rtp, media=(string)video, payload=(int)98,
clock-rate=(int)90000, encoding-name=(string)VP9, profile-id=(string)0,
rtcp-fb-goog-remb=(boolean)true, rtcp-fb-transport-cc=(boolean)true,
rtcp-fb-ccm-fir=(boolean)true, rtcp-fb-nack=(boolean)true,
rtcp-fb-nack-pli=(boolean)true; application/x-rtp, media=(string)video,
payload=(int)99, clock-rate=(int)90000, encoding-name=(string)RTX,
apt=(string)98; application/x-rtp, media=(string)video, payload=(int)100,
clock-rate=(int)90000, encoding-name=(string)VP9, profile-id=(string)2,
rtcp-fb-goog-remb=(boolean)true, rtcp-fb-transport-cc=(boolean)true,
rtcp-fb-ccm-fir=(boolean)true, rtcp-fb-nack=(boolean)true,
rtcp-fb-nack-pli=(boolean)true; application/x-rtp, media=(string)video,
payload=(int)101, clock-rate=(int)90000, encoding-name=(string)RTX,
apt=(string)100; application/x-rtp, media=(string)video, payload=(int)122,
clock-rate=(int)90000, encoding-name=(string)VP9, profile-id=(string)1,
rtcp-fb-goog-remb=(boolean)true, rtcp-fb-transport-cc=(boolean)true,
rtcp-fb-ccm-fir=(boolean)true, rtcp-fb-nack=(boolean)true,
rtcp-fb-nack-pli=(boolean)true; application/x-rtp, media=(string)video,
payload=(int)102, clock-rate=(int)90000, encoding-name=(string)H264,
level-asymmetry-allowed=(string)1, packetization-mode=(string)1,
profile-level-id=(string)42001f, rtcp-fb-goog-remb=(boolean)true,
rtcp-fb-transport-cc=(boolean)true, rtcp-fb-ccm-fir=(boolean)true,
rtcp-fb-nack=(boolean)true, rtcp-fb-nack-pli=(boolean)true;
application/x-rtp, media=(string)video, payload=(int)121,
clock-rate=(int)90000, encoding-name=(string)RTX, apt=(string)102;
application/x-rtp, media=(string)video, payload=(int)127,
clock-rate=(int)90000, encoding-name=(string)H264,
level-asymmetry-allowed=(string)1, packetization-mode=(string)0,
profile-level-id=(string)42001f, rtcp-fb-goog-remb=(boolean)true,
rtcp-fb-transport-cc=(boolean)true, rtcp-fb-ccm-fir=(boolean)true,
rtcp-fb-nack=(boolean)true, rtcp-fb-nack-pli=(boolean)true;
application/x-rtp, media=(string)video, payload=(int)120,
clock-rate=(int)90000, encoding-name=(string)RTX, apt=(string)127;
application/x-rtp, media=(string)video, payload=(int)125,
clock-rate=(int)90000, encoding-name=(string)H264,
level-asymmetry-allowed=(string)1, packetization-mode=(string)1,
profile-level-id=(string)42e01f, rtcp-fb-goog-remb=(boolean)true,
rtcp-fb-transport-cc=(boolean)true, rtcp-fb-ccm-fir=(boolean)true,
rtcp-fb-nack=(boolean)true, rtcp-fb-nack-pli=(boolean)true;
application/x-rtp, media=(string)video, payload=(int)107,
clock-rate=(int)90000, encoding-name=(string)RTX, apt=(string)125;
application/x-rtp, media=(string)video, payload=(int)108,
clock-rate=(int)90000, encoding-name=(string)H264,
level-asymmetry-allowed=(string)1, packetization-mode=(string)0,
profile-level-id=(string)42e01f, rtcp-fb-goog-remb=(boolean)true,
rtcp-fb-transport-cc=(boolean)true, rtcp-fb-ccm-fir=(boolean)true,
rtcp-fb-nack=(boolean)true, rtcp-fb-nack-pli=(boolean)true;
application/x-rtp, media=(string)video, payload=(int)109,
clock-rate=(int)90000, encoding-name=(string)RTX, apt=(string)108;
application/x-rtp, media=(string)video, payload=(int)124,
clock-rate=(int)90000, encoding-name=(string)H264,
level-asymmetry-allowed=(string)1, packetization-mode=(string)1,
profile-level-id=(string)4d0033, rtcp-fb-goog-remb=(boolean)true,
rtcp-fb-transport-cc=(boolean)true, rtcp-fb-ccm-fir=(boolean)true,
rtcp-fb-nack=(boolean)true, rtcp-fb-nack-pli=(boolean)true;
application/x-rtp, media=(string)video, payload=(int)119,
clock-rate=(int)90000, encoding-name=(string)RTX, apt=(string)124;
application/x-rtp, media=(string)video, payload=(int)123,
clock-rate=(int)90000, encoding-name=(string)H264,
level-asymmetry-allowed=(string)1, packetization-mode=(string)1,
profile-level-id=(string)640033, rtcp-fb-goog-remb=(boolean)true,
rtcp-fb-transport-cc=(boolean)true, rtcp-fb-ccm-fir=(boolean)true,
rtcp-fb-nack=(boolean)true, rtcp-fb-nack-pli=(boolean)true;
application/x-rtp, media=(string)video, payload=(int)118,
clock-rate=(int)90000, encoding-name=(string)RTX, apt=(string)123;
application/x-rtp, media=(string)video, payload=(int)114,
clock-rate=(int)90000, encoding-name=(string)RED; application/x-rtp,
media=(string)video, payload=(int)115, clock-rate=(int)90000,
encoding-name=(string)RTX, apt=(string)114; application/x-rtp,
media=(string)video, payload=(int)116, clock-rate=(int)90000,
encoding-name=(string)ULPFEC and application/x-rtp, media=(string)video,
clock-rate=(int)90000, encoding-name=(string)VP8, payload=(int)96,
ssrc=(uint)3497855691, timestamp-offset=(uint)3612195570,
seqnum-offset=(uint)12225, a-framerate=(string)30
0:00:04.133872949  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:3130:_create_answer_task:<webrtcbin> found compatible
transceiver <webrtctransceiver0> for offer media 1
0:00:04.133936322  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:04.134208418  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:04.134092823  2466 0x7f7c900b22d0 FIXME                default
gstutils.c:4026:gst_pad_create_stream_id_internal:<nicesrc0:src> Creating
random stream-id, consider implementing a deterministic way of creating a
stream-id
0:00:04.134325948  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: PLAYING => PLAYING
-----------SENDING SDP ANSWER------------
on_answer_created
0:00:04.134993726  2466 0x7f7c900b2280 FIXME                default
gstutils.c:4026:gst_pad_create_stream_id_internal:<nicesrc1:src> Creating
random stream-id, consider implementing a deterministic way of creating a
stream-id
sending SDP answer to peer: v=0
o=- 6037884307875629115 4 IN IP4 0.0.0.0
s=-
t=0 0
a=group:BUNDLE 0 1
m=audio 9 UDP/TLS/RTP/SAVPF 111
c=IN IP4 0.0.0.0
a=ice-ufrag:PlcKGP4B+N73iCLii7TubbfCVEFWLXXN
a=ice-pwd:9El7McL63197VYihxUTmeJn9vIMpMfOu
a=mid:0
a=rtcp-mux
a=setup:active
a=rtpmap:111 OPUS/48000/2
a=fmtp:111 minptime=10;useinbandfec=1
a=inactive
a=fingerprint:sha-256
7D:F6:6B:3F:33:81:56:F6:46:60:6E:18:F4:CB:F3:2A:1E:2F:D3:28:F6:25:E7:F5:BD:14:77:19:19:A0:5F:BE
m=video 9 UDP/TLS/RTP/SAVPF 96
c=IN IP4 0.0.0.0
a=ice-ufrag:PlcKGP4B+N73iCLii7TubbfCVEFWLXXN
a=ice-pwd:9El7McL63197VYihxUTmeJn9vIMpMfOu
a=mid:1
a=rtcp-mux
a=setup:active
a=rtpmap:96 VP8/90000
a=rtcp-fb:96 nack pli
a=rtcp-fb:96 ccm fir
a=framerate:30
a=ssrc:3497855691 msid:user2578524959 at host-e8f12b09 webrtctransceiver0
a=ssrc:3497855691 cname:user2578524959 at host-e8f12b09
a=sendonly
a=fingerprint:sha-256
7D:F6:6B:3F:33:81:56:F6:46:60:6E:18:F4:CB:F3:2A:1E:2F:D3:28:F6:25:E7:F5:BD:14:77:19:19:A0:5F:BE

Sending message
{"protocol":"one-to-one","to":"123456","action":"SEND_SDP","data":{"sdp":{"type":"answer","sdp":"v=0\r\no=-
6037884307875629115 4 IN IP4 0.0.0.0\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0
1\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111\r\nc=IN IP4
0.0.0.0\r\na=ice-ufrag:PlcKGP4B+N73iCLii7TubbfCVEFWLXXN\r\na=ice-pwd:9El7McL63197VYihxUTmeJn9vIMpMfOu\r\na=mid:0\r\na=rtcp-mux\r\na=setup:active\r\na=rtpmap:111
OPUS/48000/2\r\na=fmtp:111
minptime=10;useinbandfec=1\r\na=inactive\r\na=fingerprint:sha-256
7D:F6:6B:3F:33:81:56:F6:46:60:6E:18:F4:CB:F3:2A:1E:2F:D3:28:F6:25:E7:F5:BD:14:77:19:19:A0:5F:BE\r\nm=video
9 UDP/TLS/RTP/SAVPF 96\r\nc=IN IP4
0.0.0.0\r\na=ice-ufrag:PlcKGP4B+N73iCLii7TubbfCVEFWLXXN\r\na=ice-pwd:9El7McL63197VYihxUTmeJn9vIMpMfOu\r\na=mid:1\r\na=rtcp-mux\r\na=setup:active\r\na=rtpmap:96
VP8/90000\r\na=rtcp-fb:96 nack pli\r\na=rtcp-fb:96 ccm
fir\r\na=framerate:30\r\na=ssrc:3497855691 msid:user2578524959 at host-e8f12b09
webrtctransceiver0\r\na=ssrc:3497855691
cname:user2578524959 at host-e8f12b09\r\na=sendonly\r\na=fingerprint:sha-256
7D:F6:6B:3F:33:81:56:F6:46:60:6E:18:F4:CB:F3:2A:1E:2F:D3:28:F6:25:E7:F5:BD:14:77:19:19:A0:5F:BE\r\n"},"user":"ges-webrtc-service"}}
0:00:04.136527033  2466 0x7f7c98005000 INFO               webrtcbin
gstwebrtcbin.c:4312:_set_description_task:<webrtcbin> Attempting to set
local answer in the have-remote-offer state
0:00:04.136550554  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4313:_set_description_task:<webrtcbin> SDP contents
v=0
o=- 6037884307875629115 4 IN IP4 0.0.0.0
s=-
t=0 0
a=group:BUNDLE 0 1
m=audio 9 UDP/TLS/RTP/SAVPF 111
c=IN IP4 0.0.0.0
a=ice-ufrag:PlcKGP4B+N73iCLii7TubbfCVEFWLXXN
a=ice-pwd:9El7McL63197VYihxUTmeJn9vIMpMfOu
a=mid:0
a=rtcp-mux
a=setup:active
a=rtpmap:111 OPUS/48000/2
a=fmtp:111 minptime=10;useinbandfec=1
a=inactive
a=fingerprint:sha-256
7D:F6:6B:3F:33:81:56:F6:46:60:6E:18:F4:CB:F3:2A:1E:2F:D3:28:F6:25:E7:F5:BD:14:77:19:19:A0:5F:BE
m=video 9 UDP/TLS/RTP/SAVPF 96
c=IN IP4 0.0.0.0
a=ice-ufrag:PlcKGP4B+N73iCLii7TubbfCVEFWLXXN
a=ice-pwd:9El7McL63197VYihxUTmeJn9vIMpMfOu
a=mid:1
a=rtcp-mux
a=setup:active
a=rtpmap:96 VP8/90000
a=rtcp-fb:96 nack pli
a=rtcp-fb:96 ccm fir
a=framerate:30
a=ssrc:3497855691 msid:user2578524959 at host-e8f12b09 webrtctransceiver0
a=ssrc:3497855691 cname:user2578524959 at host-e8f12b09
a=sendonly
a=fingerprint:sha-256
7D:F6:6B:3F:33:81:56:F6:46:60:6E:18:F4:CB:F3:2A:1E:2F:D3:28:F6:25:E7:F5:BD:14:77:19:19:A0:5F:BE

0:00:04.136761837  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:4479:_set_description_task:<webrtcbin> we are in ice
controlling mode: false
0:00:04.136784597  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:04.136810735  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:04.136851866  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: PLAYING => PLAYING
0:00:04.136919126  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:3718:_update_transport_ptmap_from_media:<webrtcbin> mapping
sdp session level attributes to caps
0:00:04.136942062  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:3720:_update_transport_ptmap_from_media:<webrtcbin> mapping
sdp media level attributes to caps
0:00:04.136964782  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:3733:_update_transport_ptmap_from_media:<webrtcbin>  looking
at 0 pt: 111
0:00:04.137003678  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:3718:_update_transport_ptmap_from_media:<webrtcbin> mapping
sdp session level attributes to caps
0:00:04.137017911  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:3720:_update_transport_ptmap_from_media:<webrtcbin> mapping
sdp media level attributes to caps
0:00:04.137040318  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:3733:_update_transport_ptmap_from_media:<webrtcbin>  looking
at 0 pt: 96
0:00:04.137068824  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:04.139372068  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:04.139526173  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:04.139547658  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:5299:on_rtpbin_request_aux_sender:<webrtcbin> requesting aux
sender for stream <transportstream0> with transport <webrtctransceiver0>
and pt map (NULL)
0:00:04.139657564  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:5188:on_rtpbin_pad_added:<webrtcbin> new rtpbin pad
send_rtp_src_0
0:00:04.139695372  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:5188:on_rtpbin_pad_added:<webrtcbin> new rtpbin pad
send_rtp_sink_0
0:00:04.139782959  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:3438:_find_transceiver_for_sdp_media:<webrtcbin> Found
transceiver <webrtctransceiver0>
0:00:04.139801287  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:04.139819234  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:04.139851786  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: PLAYING => PLAYING
0:00:04.139945178  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:3879:_update_transceiver_from_sdp_media:<webrtcbin>
transceiver <webrtctransceiver0> direction change from none to inactive
0:00:04.139978869  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:329:_on_notify_dtls_client_status:<transportsendbin0>
DTLS-SRTP encoder configured. Unlocking it and changing state <dtlssrtpenc0>
0:00:04.140706283  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:329:_on_notify_dtls_client_status:<transportsendbin0>
DTLS-SRTP encoder configured. Unlocking it and changing state <dtlssrtpenc1>
0:00:04.141554025  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:3438:_find_transceiver_for_sdp_media:<webrtcbin> Found
transceiver <webrtctransceiver1>
0:00:04.141587281  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:04.141606536  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:04.141660066  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: PLAYING => PLAYING
0:00:04.141727110  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:3863:_update_transceiver_from_sdp_media:<webrtcbin> marking
stream 0x7f7c98002d40 as active
0:00:04.141744921  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:3879:_update_transceiver_from_sdp_media:<webrtcbin>
transceiver <webrtctransceiver1> direction change from none to sendonly
0:00:04.141760474  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:3912:_update_transceiver_from_sdp_media:<webrtcbin> found
existing send pad <webrtcbin:sink_1> for transceiver <webrtctransceiver1>
0:00:04.141773492  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:126:transport_receive_bin_set_receive_state:<transportreceivebin0>
changing receive state to pass
0:00:04.141812946  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:4534:_set_description_task:<webrtcbin:sink_0> Connecting
input stream to rtpbin with transceiver <webrtctransceiver0> and caps
application/x-rtp, media=(string)video, clock-rate=(int)90000,
encoding-name=(string)VP8, payload=(int)96, ssrc=(uint)3497855691,
timestamp-offset=(uint)3612195570, seqnum-offset=(uint)12225,
a-framerate=(string)30
0:00:04.141829138  2466 0x7f7c98005000 INFO               webrtcbin
gstwebrtcbin.c:3482:_connect_input_stream:<webrtcbin:sink_0> linking input
stream 0
0:00:04.141891571  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:04.144721505  2466 0x7f7c980050a0 DEBUG              webrtcbin
gstwebrtcbin.c:279:gst_webrtcbin_sink_event:<webrtcbin> On
<webrtcbin:sink_0> checking negotiation? 1, caps application/x-rtp,
media=(string)video, clock-rate=(int)90000, encoding-name=(string)VP8,
payload=(int)96, seqnum-offset=(uint)12225,
timestamp-offset=(uint)3612195570, ssrc=(uint)3497855691,
a-framerate=(string)30
0:00:04.144825665  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:4534:_set_description_task:<webrtcbin:sink_1> Connecting
input stream to rtpbin with transceiver <webrtctransceiver1> and caps
application/x-rtp, media=(string)audio, clock-rate=(int)48000,
encoding-name=(string)OPUS, sprop-maxcapturerate=(string)48000,
sprop-stereo=(string)0, payload=(int)97, encoding-params=(string)2,
ssrc=(uint)465568367, timestamp-offset=(uint)1614781904,
seqnum-offset=(uint)28659
0:00:04.144849930  2466 0x7f7c98005000 INFO               webrtcbin
gstwebrtcbin.c:3482:_connect_input_stream:<webrtcbin:sink_1> linking input
stream 1
0:00:04.144959349  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:04.146049259  2466 0x7f7c98005050 DEBUG              webrtcbin
gstwebrtcbin.c:279:gst_webrtcbin_sink_event:<webrtcbin> On
<webrtcbin:sink_1> checking negotiation? 1, caps application/x-rtp,
media=(string)audio, clock-rate=(int)48000, encoding-name=(string)OPUS,
sprop-maxcapturerate=(string)48000, sprop-stereo=(string)0,
payload=(int)97, encoding-params=(string)2, seqnum-offset=(uint)28659,
timestamp-offset=(uint)1614781904, ssrc=(uint)465568367
0:00:04.148991305  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:04.149058818  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:04.149454246  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: PLAYING => PLAYING
0:00:04.149676233  2466 0x7f7c98005000 DEBUG              webrtcice
gstwebrtcice.c:805:gst_webrtc_ice_set_local_credentials:<webrtcbin0:ice>
Setting local ICE credentials on ICE stream 1
ufrag:PlcKGP4B+N73iCLii7TubbfCVEFWLXXN pwd:9El7McL63197VYihxUTmeJn9vIMpMfOu
0:00:04.149804835  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:04.149858415  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:04.150123348  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: PLAYING => PLAYING
0:00:04.150366260  2466 0x7f7c98005000 DEBUG              webrtcice
gstwebrtcice.c:823:gst_webrtc_ice_gather_candidates:<webrtcbin0:ice> gather
candidates for stream 1
0:00:04.150413993  2466 0x7f7c98005000 DEBUG        webrtcicestream
icestream.c:177:gst_webrtc_ice_stream_gather_candidates:<webrtcicestream0>
start gathering candidates
0:00:04.152233597  2466 0x7f7c90083f70 INFO               webrtcbin
gstwebrtcbin.c:5619:on_rtpbin_new_sender_ssrc:<webrtcbin> session 0 ssrc
3497855691 new sender ssrc
0:00:04.165057954  2466 0x7f7c900b2400 LOG     webrtctransportsendbin
transportsendbin.c:160:pad_block:<dtlssrtpenc0:src> blocking pad with data
buffer: 0x7f7c90021ea0, pts 99:99:99.999999999, dts 99:99:99.999999999, dur
99:99:99.999999999, size 255, offset none, offset_end none, flags 0x0
0:00:04.172765171  2466 0x7f7c90083f70 LOG     webrtctransportsendbin
transportsendbin.c:160:pad_block:<rtp_sink:proxypad12> blocking pad with
data bufferlist: 0x58b47b509300, 1 buffers, pts 0:00:00.016753993, dts
99:99:99.999999999, size 736
0:00:04.192156998  2466 0x7f7c900b20a0 LOG     webrtctransportsendbin
transportsendbin.c:160:pad_block:<dtlssrtpenc1:src> blocking pad with data
buffer: 0x7f7c900da480, pts 99:99:99.999999999, dts 99:99:99.999999999, dur
99:99:99.999999999, size 255, offset none, offset_end none, flags 0x0
0:00:04.192239888  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4882:_on_local_ice_candidate_cb:<webrtcbin> Queueing
on_ice_candidate_task
0:00:04.192279547  2466 0x7f7c98005000 DEBUG    webrtcnicetransport
nicetransport.c:186:_on_component_state_changed:<webrtcnicetransport0> 1 1
gathering
0:00:04.192305419  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:394:_find_ice_stream_for_session:<webrtcbin> Found ice
stream id <webrtcicestream0> for session 0
0:00:04.192336811  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:3601:_add_ice_candidate:<webrtcbin> adding ICE candidate
with mline:0, a=candidate:63126866 1 udp 2113937151
ec09dc7d-e98b-4caa-b8af-ae9b04577210.local 38930 typ host generation 2
ufrag bT57 network-cost 999
0:00:04.192374160  2466 0x7f7c98005000 DEBUG              webrtcice
gstwebrtcice.c:313:_resolve_host:<webrtcbin0:ice> Resolving host
ec09dc7d-e98b-4caa-b8af-ae9b04577210.local
0:00:04.204437393  2466 0x7f7c98005000 ERROR              webrtcice
gstwebrtcice.c:316:_resolve_host: Error resolving
“ec09dc7d-e98b-4caa-b8af-ae9b04577210.local”: Name or service not known
0:00:04.205706485  2466 0x7f7c98005000 WARN               webrtcice
gstwebrtcice.c:690:gst_webrtc_ice_add_candidate:<webrtcbin0:ice> Failed to
resolve ec09dc7d-e98b-4caa-b8af-ae9b04577210.local
0:00:04.206692528  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:394:_find_ice_stream_for_session:<webrtcbin> Found ice
stream id <webrtcicestream0> for session 0
0:00:04.207873633  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:3601:_add_ice_candidate:<webrtcbin> adding ICE candidate
with mline:0, a=candidate:3933521124 1 udp 2113934591
e4de021a-535c-4e08-83f1-8b00c44ad5a2.local 38229 typ host generation 2
ufrag bT57 network-cost 999
0:00:04.209293894  2466 0x7f7c98005000 DEBUG              webrtcice
gstwebrtcice.c:313:_resolve_host:<webrtcbin0:ice> Resolving host
e4de021a-535c-4e08-83f1-8b00c44ad5a2.local
0:00:04.213837004  2466 0x58b47b3845e0 DEBUG    webrtcnicetransport
nicetransport.c:186:_on_component_state_changed:<webrtcnicetransport1> 1 2
gathering
0:00:04.220692384  2466 0x7f7c98005000 ERROR              webrtcice
gstwebrtcice.c:316:_resolve_host: Error resolving
“e4de021a-535c-4e08-83f1-8b00c44ad5a2.local”: Name or service not known
0:00:04.221171786  2466 0x7f7c98005000 WARN               webrtcice
gstwebrtcice.c:690:gst_webrtc_ice_add_candidate:<webrtcbin0:ice> Failed to
resolve e4de021a-535c-4e08-83f1-8b00c44ad5a2.local
0:00:04.221570418  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:394:_find_ice_stream_for_session:<webrtcbin> Found ice
stream id <webrtcicestream0> for session 0
0:00:04.221850000  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:3601:_add_ice_candidate:<webrtcbin> adding ICE candidate
with mline:0, a=candidate:842163049 1 udp 1677729535 68.8.32.177 46192 typ
srflx raddr 0.0.0.0 rport 0 generation 2 ufrag bT57 network-cost 999
0:00:04.222376889  2466 0x7f7c98005000 DEBUG    webrtcnicetransport
nicetransport.c:186:_on_component_state_changed:<webrtcnicetransport0> 1 1
connecting
0:00:04.222669896  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4640:_set_description_task:<webrtcbin> notify
signaling-state from stable to stable
0:00:04.222944933  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:1341:_check_if_negotiation_is_needed:<webrtcbin> checking if
negotiation is needed
0:00:04.223258823  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:1478:_check_if_negotiation_is_needed:<webrtcbin> transceiver
direction (sendrecv) doesn't match description intersected direction
inactive (local inactive remote recvonly)
0:00:04.223556135  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1499:_check_need_negotiation_task:<webrtcbin> emitting
on-negotiation-needed
0:00:04.223865416  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 DTLS state: 0x3
0:00:04.223969551  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 ICE state: 0x1
0:00:04.223992264  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 DTLS state: 0x3
0:00:04.224009360  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 ICE state: 0x1
0:00:04.224023979  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1050:_collate_peer_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.224039025  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1118:_collate_peer_connection_states:<webrtcbin> ICE
connection state: 0x2. DTLS connection state: 0x8
0:00:04.224052366  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1154:_collate_peer_connection_states:<webrtcbin> returning
connecting
0:00:04.224621949  2466 0x7f7c98005000 INFO               webrtcbin
gstwebrtcbin.c:1282:_update_peer_connection_state_task:<webrtcbin> Peer
connection state change from new(0) to connecting(1)
0:00:04.224664827  2466 0x7f7c98005050 LOG                webrtcbin
gstwebrtcbin.c:1341:_check_if_negotiation_is_needed:<webrtcbin> checking if
negotiation is needed
0:00:04.224691551  2466 0x7f7c98005050 LOG                webrtcbin
gstwebrtcbin.c:1478:_check_if_negotiation_is_needed:<webrtcbin> transceiver
direction (sendrecv) doesn't match description intersected direction
inactive (local inactive remote recvonly)
0:00:04.226695969  2466 0x7f7c980050a0 LOG                webrtcbin
gstwebrtcbin.c:1341:_check_if_negotiation_is_needed:<webrtcbin> checking if
negotiation is needed
0:00:04.226737126  2466 0x7f7c980050a0 LOG                webrtcbin
gstwebrtcbin.c:1478:_check_if_negotiation_is_needed:<webrtcbin> transceiver
direction (sendrecv) doesn't match description intersected direction
inactive (local inactive remote recvonly)
0:00:04.233466130  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 DTLS state: 0x3
0:00:04.234576250  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 ICE state: 0x1
0:00:04.234838418  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 DTLS state: 0x3
0:00:04.235170790  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 ICE state: 0x1
0:00:04.235436198  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1050:_collate_peer_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.235685269  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1118:_collate_peer_connection_states:<webrtcbin> ICE
connection state: 0x2. DTLS connection state: 0x8
0:00:04.235963223  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1154:_collate_peer_connection_states:<webrtcbin> returning
connecting
0:00:04.236236356  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:975:_collate_ice_gathering_states:<webrtcbin> transceiver
0x58b47b509060 gathering state: 0x1
0:00:04.236632284  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:975:_collate_ice_gathering_states:<webrtcbin> transceiver
0x58b47b509140 gathering state: 0x1
0:00:04.236967474  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:959:_collate_ice_gathering_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.237223826  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:975:_collate_ice_gathering_states:<webrtcbin> transceiver
0x7f7c94058a30 gathering state: 0x1
0:00:04.237340858  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:997:_collate_ice_gathering_states:<webrtcbin> ICE gathering
state: 0x2
0:00:04.237466612  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1001:_collate_ice_gathering_states:<webrtcbin> returning
gathering
0:00:04.237654102  2466 0x7f7c98005000 INFO               webrtcbin
gstwebrtcbin.c:1212:_update_ice_gathering_state_task:<webrtcbin> ICE
gathering state change from new(0) to gathering(1)
0:00:04.237770009  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:975:_collate_ice_gathering_states:<webrtcbin> transceiver
0x58b47b509060 gathering state: 0x1
0:00:04.237996080  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:975:_collate_ice_gathering_states:<webrtcbin> transceiver
0x58b47b509140 gathering state: 0x1
0:00:04.238270255  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:959:_collate_ice_gathering_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.238435857  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:975:_collate_ice_gathering_states:<webrtcbin> transceiver
0x7f7c94058a30 gathering state: 0x1
0:00:04.238839361  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:997:_collate_ice_gathering_states:<webrtcbin> ICE gathering
state: 0x2
0:00:04.239022717  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1001:_collate_ice_gathering_states:<webrtcbin> returning
gathering
0:00:04.239048093  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4837:_on_local_ice_candidate_task:<webrtcbin> produced ICE
candidate for mline:0 and candidate:1 1 UDP 2015363327 172.18.0.5 40437 typ
host
on_ice_candidate candidate:1 1 UDP 2015363327 172.18.0.5 40437 typ host
0:00:04.242721158  2466 0x7f7c900b22d0 TRACE              webrtcbin
gstwebrtcbin.c:4882:_on_local_ice_candidate_cb:<webrtcbin> Queueing
on_ice_candidate_task
0:00:04.243208136  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4837:_on_local_ice_candidate_task:<webrtcbin> produced ICE
candidate for mline:0 and candidate:2 1 TCP 1015021823 172.18.0.5 9 typ
host tcptype active
on_ice_candidate candidate:2 1 TCP 1015021823 172.18.0.5 9 typ host tcptype
active
0:00:04.243374626  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4837:_on_local_ice_candidate_task:<webrtcbin> produced ICE
candidate for mline:0 and candidate:3 1 TCP 1010827519 172.18.0.5 36291 typ
host tcptype passive
on_ice_candidate candidate:3 1 TCP 1010827519 172.18.0.5 36291 typ host
tcptype passive
0:00:04.243436959  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4837:_on_local_ice_candidate_task:<webrtcbin> produced ICE
candidate for mline:0 and candidate:1 2 UDP 2015363326 172.18.0.5 57437 typ
host
on_ice_candidate candidate:1 2 UDP 2015363326 172.18.0.5 57437 typ host
0:00:04.243491372  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4837:_on_local_ice_candidate_task:<webrtcbin> produced ICE
candidate for mline:0 and candidate:2 2 TCP 1015021822 172.18.0.5 9 typ
host tcptype active
on_ice_candidate candidate:2 2 TCP 1015021822 172.18.0.5 9 typ host tcptype
active
0:00:04.243547228  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4837:_on_local_ice_candidate_task:<webrtcbin> produced ICE
candidate for mline:0 and candidate:3 2 TCP 1010827518 172.18.0.5 47945 typ
host tcptype passive
on_ice_candidate candidate:3 2 TCP 1010827518 172.18.0.5 47945 typ host
tcptype passive
0:00:04.243612774  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:858:_collate_ice_connection_states:<webrtcbin> transceiver
0x58b47b509060 state 0x1
0:00:04.243622773  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:858:_collate_ice_connection_states:<webrtcbin> transceiver
0x58b47b509140 state 0x1
0:00:04.243629732  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:847:_collate_ice_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.243635680  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:888:_collate_ice_connection_states:<webrtcbin> ICE
connection state: 0x2
0:00:04.247743393  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:912:_collate_ice_connection_states:<webrtcbin> returning
checking
0:00:04.247767255  2466 0x7f7c98005000 INFO               webrtcbin
gstwebrtcbin.c:1247:_update_ice_connection_state_task:<webrtcbin> ICE
connection state change from new(0) to checking(1)
0:00:04.247848555  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 DTLS state: 0x3
0:00:04.247860570  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 ICE state: 0x1
0:00:04.247869265  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 DTLS state: 0x3
0:00:04.247876484  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 ICE state: 0x1
0:00:04.247884534  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1050:_collate_peer_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.247892217  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1118:_collate_peer_connection_states:<webrtcbin> ICE
connection state: 0x2. DTLS connection state: 0x8
0:00:04.247898984  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1154:_collate_peer_connection_states:<webrtcbin> returning
connecting
0:00:04.247908363  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:858:_collate_ice_connection_states:<webrtcbin> transceiver
0x58b47b509060 state 0x1
0:00:04.247916643  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:858:_collate_ice_connection_states:<webrtcbin> transceiver
0x58b47b509140 state 0x1
0:00:04.247923645  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:847:_collate_ice_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.247930634  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:888:_collate_ice_connection_states:<webrtcbin> ICE
connection state: 0x2
0:00:04.247936869  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:912:_collate_ice_connection_states:<webrtcbin> returning
checking
0:00:04.247945965  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 DTLS state: 0x3
0:00:04.247953823  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 ICE state: 0x1
0:00:04.247961691  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 DTLS state: 0x3
0:00:04.247968538  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 ICE state: 0x1
0:00:04.247975306  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1050:_collate_peer_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.247982666  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1118:_collate_peer_connection_states:<webrtcbin> ICE
connection state: 0x2. DTLS connection state: 0x8
0:00:04.247989024  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1154:_collate_peer_connection_states:<webrtcbin> returning
connecting
0:00:04.247998413  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:858:_collate_ice_connection_states:<webrtcbin> transceiver
0x58b47b509060 state 0x1
0:00:04.248006178  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:858:_collate_ice_connection_states:<webrtcbin> transceiver
0x58b47b509140 state 0x1
0:00:04.248012826  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:847:_collate_ice_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.248018986  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:888:_collate_ice_connection_states:<webrtcbin> ICE
connection state: 0x2
0:00:04.248024949  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:912:_collate_ice_connection_states:<webrtcbin> returning
checking
0:00:04.248033598  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 DTLS state: 0x3
0:00:04.248041013  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 ICE state: 0x1
0:00:04.248048592  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 DTLS state: 0x3
0:00:04.248055140  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 ICE state: 0x1
0:00:04.248061430  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1050:_collate_peer_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.248068214  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1118:_collate_peer_connection_states:<webrtcbin> ICE
connection state: 0x2. DTLS connection state: 0x8
0:00:04.248074264  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1154:_collate_peer_connection_states:<webrtcbin> returning
connecting
0:00:04.248093244  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4837:_on_local_ice_candidate_task:<webrtcbin> produced ICE
candidate for mline:0 and candidate:4 1 UDP 1679819007 68.8.32.177 41645
typ srflx raddr 172.18.0.5 rport 40437
on_ice_candidate candidate:4 1 UDP 1679819007 68.8.32.177 41645 typ srflx
raddr 172.18.0.5 rport 40437
0:00:04.248224525  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4837:_on_local_ice_candidate_task:<webrtcbin> produced ICE
candidate for mline:0 and candidate:5 1 TCP 847249663 68.8.32.177 9 typ
srflx raddr 172.18.0.5 rport 9 tcptype active
on_ice_candidate candidate:5 1 TCP 847249663 68.8.32.177 9 typ srflx raddr
172.18.0.5 rport 9 tcptype active
0:00:04.248287010  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4837:_on_local_ice_candidate_task:<webrtcbin> produced ICE
candidate for mline:0 and candidate:6 1 TCP 843055359 68.8.32.177 36291 typ
srflx raddr 172.18.0.5 rport 36291 tcptype passive
on_ice_candidate candidate:6 1 TCP 843055359 68.8.32.177 36291 typ srflx
raddr 172.18.0.5 rport 36291 tcptype passive
0:00:04.256774319  2466 0x7f7c900b2280 TRACE              webrtcbin
gstwebrtcbin.c:4882:_on_local_ice_candidate_cb:<webrtcbin> Queueing
on_ice_candidate_task
0:00:04.256853984  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4837:_on_local_ice_candidate_task:<webrtcbin> produced ICE
candidate for mline:0 and candidate:4 2 UDP 1679819006 68.8.32.177 39165
typ srflx raddr 172.18.0.5 rport 57437
on_ice_candidate candidate:4 2 UDP 1679819006 68.8.32.177 39165 typ srflx
raddr 172.18.0.5 rport 57437
0:00:04.257026057  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4837:_on_local_ice_candidate_task:<webrtcbin> produced ICE
candidate for mline:0 and candidate:5 2 TCP 847249662 68.8.32.177 9 typ
srflx raddr 172.18.0.5 rport 9 tcptype active
on_ice_candidate candidate:5 2 TCP 847249662 68.8.32.177 9 typ srflx raddr
172.18.0.5 rport 9 tcptype active
0:00:04.273159784  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4837:_on_local_ice_candidate_task:<webrtcbin> produced ICE
candidate for mline:0 and candidate:6 2 TCP 843055358 68.8.32.177 47945 typ
srflx raddr 172.18.0.5 rport 47945 tcptype passive
on_ice_candidate candidate:6 2 TCP 843055358 68.8.32.177 47945 typ srflx
raddr 172.18.0.5 rport 47945 tcptype passive
0:00:04.274714394  2466 0x58b47b3845e0 DEBUG        webrtcicestream
icestream.c:116:_on_candidate_gathering_done:<webrtcicestream0> 1 gathering
done
0:00:04.284772343  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:975:_collate_ice_gathering_states:<webrtcbin> transceiver
0x58b47b509060 gathering state: 0x2
0:00:04.284800924  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:975:_collate_ice_gathering_states:<webrtcbin> transceiver
0x58b47b509140 gathering state: 0x2
0:00:04.284815280  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:959:_collate_ice_gathering_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.284825852  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:975:_collate_ice_gathering_states:<webrtcbin> transceiver
0x7f7c94058a30 gathering state: 0x2
0:00:04.284833778  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:997:_collate_ice_gathering_states:<webrtcbin> ICE gathering
state: 0x4
0:00:04.284841345  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1007:_collate_ice_gathering_states:<webrtcbin> returning
complete
0:00:04.284853011  2466 0x7f7c98005000 INFO               webrtcbin
gstwebrtcbin.c:1212:_update_ice_gathering_state_task:<webrtcbin> ICE
gathering state change from gathering(1) to complete(2)
0:00:04.284873530  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:975:_collate_ice_gathering_states:<webrtcbin> transceiver
0x58b47b509060 gathering state: 0x2
0:00:04.284883121  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:975:_collate_ice_gathering_states:<webrtcbin> transceiver
0x58b47b509140 gathering state: 0x2
0:00:04.284891489  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:959:_collate_ice_gathering_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.284900059  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:975:_collate_ice_gathering_states:<webrtcbin> transceiver
0x7f7c94058a30 gathering state: 0x2
0:00:04.284907711  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:997:_collate_ice_gathering_states:<webrtcbin> ICE gathering
state: 0x4
0:00:04.284914564  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1007:_collate_ice_gathering_states:<webrtcbin> returning
complete
0:00:04.318167415  2466 0x7f7c90083ed0 DEBUG              webrtcbin
gstwebrtcbin.c:5259:on_rtpbin_request_pt_map:<webrtcbin> getting pt map for
pt 96 in session 0
0:00:04.318286272  2466 0x7f7c90083ed0 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:04.318359368  2466 0x7f7c90083ed0 TRACE              webrtcbin
gstwebrtcbin.c:5269:on_rtpbin_request_pt_map:<webrtcbin> Found caps
application/x-rtp, media=(string)video, payload=(int)96,
clock-rate=(int)90000, encoding-name=(string)VP8,
rtcp-fb-nack-pli=(boolean)true, rtcp-fb-ccm-fir=(boolean)true for pt 96 in
session 0
0:00:04.318603986  2466 0x7f7c90083ed0 LOG     webrtctransportsendbin
transportsendbin.c:160:pad_block:<outputselector0:src_0> blocking pad with
data buffer: 0x7f7c900daa20, pts 99:99:99.999999999, dts
99:99:99.999999999, dur 99:99:99.999999999, size 80, offset none,
offset_end none, flags 0x0
0:00:04.372280386  2466 0x7f7c900b22d0 DEBUG    webrtcnicetransport
nicetransport.c:186:_on_component_state_changed:<webrtcnicetransport0> 1 1
connected
0:00:04.372353194  2466 0x7f7c900b22d0 LOG     webrtctransportsendbin
transportsendbin.c:350:_on_notify_ice_connection_state:<transportsendbin0>
Unblocking pad <dtlssrtpenc0:src>
0:00:04.372392556  2466 0x7f7c900b22d0 DEBUG    webrtcnicetransport
nicetransport.c:186:_on_component_state_changed:<webrtcnicetransport0> 1 1
ready
0:00:04.372444923  2466 0x7f7c900b2400 FIXME               basesink
gstbasesink.c:3386:gst_base_sink_default_event:<nicesink0> stream-start
event without group-id. Consider implementing group-id handling in the
upstream elements
0:00:04.372723506  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:858:_collate_ice_connection_states:<webrtcbin> transceiver
0x58b47b509060 state 0x3
0:00:04.372770204  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:858:_collate_ice_connection_states:<webrtcbin> transceiver
0x58b47b509140 state 0x3
0:00:04.372788152  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:847:_collate_ice_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.372803976  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:888:_collate_ice_connection_states:<webrtcbin> ICE
connection state: 0x8
0:00:04.372818846  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:917:_collate_ice_connection_states:<webrtcbin> returning
completed
0:00:04.372837469  2466 0x7f7c98005000 INFO               webrtcbin
gstwebrtcbin.c:1247:_update_ice_connection_state_task:<webrtcbin> ICE
connection state change from checking(1) to completed(3)
0:00:04.372863317  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 DTLS state: 0x3
0:00:04.372880485  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 ICE state: 0x3
0:00:04.372896944  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 DTLS state: 0x3
0:00:04.372913696  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 ICE state: 0x3
0:00:04.372928959  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1050:_collate_peer_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.372944440  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1118:_collate_peer_connection_states:<webrtcbin> ICE
connection state: 0x8. DTLS connection state: 0x8
0:00:04.372958621  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1171:_collate_peer_connection_states:<webrtcbin> returning
connecting
0:00:04.372975335  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:858:_collate_ice_connection_states:<webrtcbin> transceiver
0x58b47b509060 state 0x3
0:00:04.372991627  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:858:_collate_ice_connection_states:<webrtcbin> transceiver
0x58b47b509140 state 0x3
0:00:04.373006240  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:847:_collate_ice_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.373020435  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:888:_collate_ice_connection_states:<webrtcbin> ICE
connection state: 0x8
0:00:04.373033973  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:917:_collate_ice_connection_states:<webrtcbin> returning
completed
0:00:04.373049759  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 DTLS state: 0x3
0:00:04.373064503  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 ICE state: 0x3
0:00:04.373079908  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 DTLS state: 0x3
0:00:04.373094512  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 ICE state: 0x3
0:00:04.373109017  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1050:_collate_peer_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.373136052  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1118:_collate_peer_connection_states:<webrtcbin> ICE
connection state: 0x8. DTLS connection state: 0x8
0:00:04.373150393  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1171:_collate_peer_connection_states:<webrtcbin> returning
connecting
0:00:04.393425473  2466 0x7f7c900b2230 LOG     webrtctransportsendbin
transportsendbin.c:295:_on_dtls_enc_key_set:<transportsendbin0> Unblocking
<dtlssrtpenc0> pads
0:00:04.393972360  2466 0x7f7c90083f70 INFO               webrtcbin
gstwebrtcbin.c:5619:on_rtpbin_new_sender_ssrc:<webrtcbin> session 0 ssrc
465568367 new sender ssrc
0:00:04.397703490  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 DTLS state: 0x4
0:00:04.397951672  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 ICE state: 0x3
0:00:04.398303681  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 DTLS state: 0x4
0:00:04.398812284  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 ICE state: 0x3
0:00:04.399886757  2466 0x7f7c90083ed0 INFO               webrtcbin
gstwebrtcbin.c:5627:on_rtpbin_sender_ssrc_active:<webrtcbin> session 0 ssrc
3497855691 sender ssrc active
0:00:04.399466180  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1050:_collate_peer_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:04.400560332  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1118:_collate_peer_connection_states:<webrtcbin> ICE
connection state: 0x8. DTLS connection state: 0x10
0:00:04.400798395  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1162:_collate_peer_connection_states:<webrtcbin> returning
connected
0:00:04.401046236  2466 0x7f7c98005000 INFO               webrtcbin
gstwebrtcbin.c:1282:_update_peer_connection_state_task:<webrtcbin> Peer
connection state change from connecting(1) to connected(2)
0:00:04.564337057  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5576:on_rtpbin_new_ssrc:<webrtcbin> session 0 ssrc 1 new ssrc
0:00:04.564527627  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:04.625442483  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:04.756241000  2466 0x7f7c90083ed0 DEBUG              webrtcbin
gstwebrtcbin.c:5259:on_rtpbin_request_pt_map:<webrtcbin> getting pt map for
pt 97 in session 0
0:00:04.757414082  2466 0x7f7c90083ed0 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:04.758616116  2466 0x7f7c90083ed0 TRACE              webrtcbin
gstwebrtcbin.c:5269:on_rtpbin_request_pt_map:<webrtcbin> Found caps (NULL)
for pt 97 in session 0
0:00:04.763303904  2466 0x7f7c90083ed0 WARN               rtpsource
rtpsource.c:1578:rtp_source_get_new_sr: no clock-rate, cannot interpolate
rtp time for SSRC 465568367
0:00:04.773796232  2466 0x7f7c90083ed0 INFO               webrtcbin
gstwebrtcbin.c:5627:on_rtpbin_sender_ssrc_active:<webrtcbin> session 0 ssrc
3497855691 sender ssrc active
0:00:04.780904332  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:04.784526042  2466 0x7f7c90083ed0 INFO               webrtcbin
gstwebrtcbin.c:5627:on_rtpbin_sender_ssrc_active:<webrtcbin> session 0 ssrc
465568367 sender ssrc active
0:00:04.808924416  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:04.965853317  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:05.173787805  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:05.371911410  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:05.565984639  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:05.771261422  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:05.968104612  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:06.171078200  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:06.370796303  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:06.572203168  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:06.775277209  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:06.979714849  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:07.182911893  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:07.386129352  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:07.582232460  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
handle_websocket_message:
{"protocol":"one-to-one","to":"ges-webrtc-service","action":"SEND_SDP","data":{"sdp":{"type":"offer","sdp":"v=0\r\no=-
6037884307875629115 5 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0
1\r\na=msid-semantic: WMS\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:mEV3\r\na=ice-pwd:elHiWUbUTm9SIHYJ3KDuHDtm\r\na=ice-options:trickle\r\na=fingerprint:sha-256
8E:46:00:21:C6:6C:D0:53:10:70:C9:98:03:92:03:F2:6F:53:AB:2F:04:67:35:E7:96:5E:41:1D:C1:7E:34:4C\r\na=setup:actpass\r\na=mid:0\r\na=extmap:1
urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:2
http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4
urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5
urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6
urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\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\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 98 99 100 101 122
102 121 127 120 125 107 108 109 124 119 123 118 114 115 116\r\nc=IN IP4
0.0.0.0\r\na=rtcp:9 IN IP4
0.0.0.0\r\na=ice-ufrag:mEV3\r\na=ice-pwd:elHiWUbUTm9SIHYJ3KDuHDtm\r\na=ice-options:trickle\r\na=fingerprint:sha-256
8E:46:00:21:C6:6C:D0:53:10:70:C9:98:03:92:03:F2:6F:53:AB:2F:04:67:35:E7:96:5E:41:1D:C1:7E:34:4C\r\na=setup:actpass\r\na=mid:1\r\na=extmap:14
urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2
http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13
urn:3gpp:video-orientation\r\na=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12
http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11
http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7
http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8
http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4
urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5
urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6
urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96
VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96
transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96
nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:98
VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98
transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98
nack pli\r\na=fmtp:98 profile-id=0\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99
apt=98\r\na=rtpmap:100 VP9/90000\r\na=rtcp-fb:100
goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm
fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100
profile-id=2\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101
apt=100\r\na=rtpmap:122 VP9/90000\r\na=rtcp-fb:122
goog-remb\r\na=rtcp-fb:122 transport-cc\r\na=rtcp-fb:122 ccm
fir\r\na=rtcp-fb:122 nack\r\na=rtcp-fb:122 nack pli\r\na=fmtp:122
profile-id=1\r\na=rtpmap:102 H264/90000\r\na=rtcp-fb:102
goog-remb\r\na=rtcp-fb:102 transport-cc\r\na=rtcp-fb:102 ccm
fir\r\na=rtcp-fb:102 nack\r\na=rtcp-fb:102 nack pli\r\na=fmtp:102
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42001f\r\na=rtpmap:121
rtx/90000\r\na=fmtp:121 apt=102\r\na=rtpmap:127 H264/90000\r\na=rtcp-fb:127
goog-remb\r\na=rtcp-fb:127 transport-cc\r\na=rtcp-fb:127 ccm
fir\r\na=rtcp-fb:127 nack\r\na=rtcp-fb:127 nack pli\r\na=fmtp:127
level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42001f\r\na=rtpmap:120
rtx/90000\r\na=fmtp:120 apt=127\r\na=rtpmap:125 H264/90000\r\na=rtcp-fb:125
goog-remb\r\na=rtcp-fb:125 transport-cc\r\na=rtcp-fb:125 ccm
fir\r\na=rtcp-fb:125 nack\r\na=rtcp-fb:125 nack pli\r\na=fmtp:125
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f\r\na=rtpmap:107
rtx/90000\r\na=fmtp:107 apt=125\r\na=rtpmap:108 H264/90000\r\na=rtcp-fb:108
goog-remb\r\na=rtcp-fb:108 transport-cc\r\na=rtcp-fb:108 ccm
fir\r\na=rtcp-fb:108 nack\r\na=rtcp-fb:108 nack pli\r\na=fmtp:108
level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42e01f\r\na=rtpmap:109
rtx/90000\r\na=fmtp:109 apt=108\r\na=rtpmap:124 H264/90000\r\na=rtcp-fb:124
goog-remb\r\na=rtcp-fb:124 transport-cc\r\na=rtcp-fb:124 ccm
fir\r\na=rtcp-fb:124 nack\r\na=rtcp-fb:124 nack pli\r\na=fmtp:124
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=4d0033\r\na=rtpmap:119
rtx/90000\r\na=fmtp:119 apt=124\r\na=rtpmap:123 H264/90000\r\na=rtcp-fb:123
goog-remb\r\na=rtcp-fb:123 transport-cc\r\na=rtcp-fb:123 ccm
fir\r\na=rtcp-fb:123 nack\r\na=rtcp-fb:123 nack pli\r\na=fmtp:123
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=640033\r\na=rtpmap:118
rtx/90000\r\na=fmtp:118 apt=123\r\na=rtpmap:114 red/90000\r\na=rtpmap:115
rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:116
ulpfec/90000\r\n"},"user":"123456"}}
GOT SEND_SDP MESSAGE from 123456
Received offer:
v=0
o=- 6037884307875629115 5 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE 0 1
a=msid-semantic: WMS
m=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:mEV3
a=ice-pwd:elHiWUbUTm9SIHYJ3KDuHDtm
a=ice-options:trickle
a=fingerprint:sha-256
8E:46:00:21:C6:6C:D0:53:10:70:C9:98:03:92:03:F2:6F:53:AB:2F:04:67:35:E7:96:5E:41:1D:C1:7E:34:4C
a=setup:actpass
a=mid:0
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
a=recvonly
a=rtcp-mux
a=rtpmap:111 opus/48000/2
a=rtcp-fb:111 transport-cc
a=fmtp:111 minptime=10;useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:104 ISAC/32000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:110 telephone-event/48000
a=rtpmap:112 telephone-event/32000
a=rtpmap:113 telephone-event/16000
a=rtpmap:126 telephone-event/8000
m=video 9 UDP/TLS/RTP/SAVPF 96 97 98 99 100 101 122 102 121 127 120 125 107
108 109 124 119 123 118 114 115 116
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:mEV3
a=ice-pwd:elHiWUbUTm9SIHYJ3KDuHDtm
a=ice-options:trickle
a=fingerprint:sha-256
8E:46:00:21:C6:6C:D0:53:10:70:C9:98:03:92:03:F2:6F:53:AB:2F:04:67:35:E7:96:5E:41:1D:C1:7E:34:4C
a=setup:actpass
a=mid:1
a=extmap:14 urn:ietf:params:rtp-hdrext:toffset
a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=extmap:13 urn:3gpp:video-orientation
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay
a=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type
a=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing
a=extmap:8 http://www.webrtc.org/experiments/rtp-hdrext/color-space
a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
a=recvonly
a=rtcp-mux
a=rtcp-rsize
a=rtpmap:96 VP8/90000
a=rtcp-fb:96 goog-remb
a=rtcp-fb:96 transport-cc
a=rtcp-fb:96 ccm fir
a=rtcp-fb:96 nack
a=rtcp-fb:96 nack pli
a=rtpmap:97 rtx/90000
a=fmtp:97 apt=96
a=rtpmap:98 VP9/90000
a=rtcp-fb:98 goog-remb
a=rtcp-fb:98 transport-cc
a=rtcp-fb:98 ccm fir
a=rtcp-fb:98 nack
a=rtcp-fb:98 nack pli
a=fmtp:98 profile-id=0
a=rtpmap:99 rtx/90000
a=fmtp:99 apt=98
a=rtpmap:100 VP9/90000
a=rtcp-fb:100 goog-remb
a=rtcp-fb:100 transport-cc
a=rtcp-fb:100 ccm fir
a=rtcp-fb:100 nack
a=rtcp-fb:100 nack pli
a=fmtp:100 profile-id=2
a=rtpmap:101 rtx/90000
a=fmtp:101 apt=100
a=rtpmap:122 VP9/90000
a=rtcp-fb:122 goog-remb
a=rtcp-fb:122 transport-cc
a=rtcp-fb:122 ccm fir
a=rtcp-fb:122 nack
a=rtcp-fb:122 nack pli
a=fmtp:122 profile-id=1
a=rtpmap:102 H264/90000
a=rtcp-fb:102 goog-remb
a=rtcp-fb:102 transport-cc
a=rtcp-fb:102 ccm fir
a=rtcp-fb:102 nack
a=rtcp-fb:102 nack pli
a=fmtp:102
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42001f
a=rtpmap:121 rtx/90000
a=fmtp:121 apt=102
a=rtpmap:127 H264/90000
a=rtcp-fb:127 goog-remb
a=rtcp-fb:127 transport-cc
a=rtcp-fb:127 ccm fir
a=rtcp-fb:127 nack
a=rtcp-fb:127 nack pli
a=fmtp:127
level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42001f
a=rtpmap:120 rtx/90000
a=fmtp:120 apt=127
a=rtpmap:125 H264/90000
a=rtcp-fb:125 goog-remb
a=rtcp-fb:125 transport-cc
a=rtcp-fb:125 ccm fir
a=rtcp-fb:125 nack
a=rtcp-fb:125 nack pli
a=fmtp:125
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
a=rtpmap:107 rtx/90000
a=fmtp:107 apt=125
a=rtpmap:108 H264/90000
a=rtcp-fb:108 goog-remb
a=rtcp-fb:108 transport-cc
a=rtcp-fb:108 ccm fir
a=rtcp-fb:108 nack
a=rtcp-fb:108 nack pli
a=fmtp:108
level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42e01f
a=rtpmap:109 rtx/90000
a=fmtp:109 apt=108
a=rtpmap:124 H264/90000
a=rtcp-fb:124 goog-remb
a=rtcp-fb:124 transport-cc
a=rtcp-fb:124 ccm fir
a=rtcp-fb:124 nack
a=rtcp-fb:124 nack pli
a=fmtp:124
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=4d0033
a=rtpmap:119 rtx/90000
a=fmtp:119 apt=124
a=rtpmap:123 H264/90000
a=rtcp-fb:123 goog-remb
a=rtcp-fb:123 transport-cc
a=rtcp-fb:123 ccm fir
a=rtcp-fb:123 nack
a=rtcp-fb:123 nack pli
a=fmtp:123
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=640033
a=rtpmap:118 rtx/90000
a=fmtp:118 apt=123
a=rtpmap:114 red/90000
a=rtpmap:115 rtx/90000
a=fmtp:115 apt=114
a=rtpmap:116 ulpfec/90000

0:00:07.791284678  2466 0x7f7c98005000 INFO               webrtcbin
gstwebrtcbin.c:4312:_set_description_task:<webrtcbin> Attempting to set
remote offer in the stable state
0:00:07.791421688  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4313:_set_description_task:<webrtcbin> SDP contents
v=0
o=- 6037884307875629115 5 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE 0 1
a=msid-semantic: WMS
m=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:mEV3
a=ice-pwd:elHiWUbUTm9SIHYJ3KDuHDtm
a=ice-options:trickle
a=fingerprint:sha-256
8E:46:00:21:C6:6C:D0:53:10:70:C9:98:03:92:03:F2:6F:53:AB:2F:04:67:35:E7:96:5E:41:1D:C1:7E:34:4C
a=setup:actpass
a=mid:0
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
a=recvonly
a=rtcp-mux
a=rtpmap:111 opus/48000/2
a=rtcp-fb:111 transport-cc
a=fmtp:111 minptime=10;useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:104 ISAC/32000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:110 telephone-event/48000
a=rtpmap:112 telephone-event/32000
a=rtpmap:113 telephone-event/16000
a=rtpmap:126 telephone-event/8000
m=video 9 UDP/TLS/RTP/SAVPF 96 97 98 99 100 101 122 102 121 127 120 125 107
108 109 124 119 123 118 114 115 116
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:mEV3
a=ice-pwd:elHiWUbUTm9SIHYJ3KDuHDtm
a=ice-options:trickle
a=fingerprint:sha-256
8E:46:00:21:C6:6C:D0:53:10:70:C9:98:03:92:03:F2:6F:53:AB:2F:04:67:35:E7:96:5E:41:1D:C1:7E:34:4C
a=setup:actpass
a=mid:1
a=extmap:14 urn:ietf:params:rtp-hdrext:toffset
a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=extmap:13 urn:3gpp:video-orientation
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay
a=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type
a=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing
a=extmap:8 http://www.webrtc.org/experiments/rtp-hdrext/color-space
a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
a=recvonly
a=rtcp-mux
a=rtcp-rsize
a=rtpmap:96 VP8/90000
a=rtcp-fb:96 goog-remb
a=rtcp-fb:96 transport-cc
a=rtcp-fb:96 ccm fir
a=rtcp-fb:96 nack
a=rtcp-fb:96 nack pli
a=rtpmap:97 rtx/90000
a=fmtp:97 apt=96
a=rtpmap:98 VP9/90000
a=rtcp-fb:98 goog-remb
a=rtcp-fb:98 transport-cc
a=rtcp-fb:98 ccm fir
a=rtcp-fb:98 nack
a=rtcp-fb:98 nack pli
a=fmtp:98 profile-id=0
a=rtpmap:99 rtx/90000
a=fmtp:99 apt=98
a=rtpmap:100 VP9/90000
a=rtcp-fb:100 goog-remb
a=rtcp-fb:100 transport-cc
a=rtcp-fb:100 ccm fir
a=rtcp-fb:100 nack
a=rtcp-fb:100 nack pli
a=fmtp:100 profile-id=2
a=rtpmap:101 rtx/90000
a=fmtp:101 apt=100
a=rtpmap:122 VP9/90000
a=rtcp-fb:122 goog-remb
a=rtcp-fb:122 transport-cc
a=rtcp-fb:122 ccm fir
a=rtcp-fb:122 nack
a=rtcp-fb:122 nack pli
a=fmtp:122 profile-id=1
a=rtpmap:102 H264/90000
a=rtcp-fb:102 goog-remb
a=rtcp-fb:102 transport-cc
a=rtcp-fb:102 ccm fir
a=rtcp-fb:102 nack
a=rtcp-fb:102 nack pli
a=fmtp:102
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42001f
a=rtpmap:121 rtx/90000
a=fmtp:121 apt=102
a=rtpmap:127 H264/90000
a=rtcp-fb:127 goog-remb
a=rtcp-fb:127 transport-cc
a=rtcp-fb:127 ccm fir
a=rtcp-fb:127 nack
a=rtcp-fb:127 nack pli
a=fmtp:127
level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42001f
a=rtpmap:120 rtx/90000
a=fmtp:120 apt=127
a=rtpmap:125 H264/90000
a=rtcp-fb:125 goog-remb
a=rtcp-fb:125 transport-cc
a=rtcp-fb:125 ccm fir
a=rtcp-fb:125 nack
a=rtcp-fb:125 nack pli
a=fmtp:125
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
a=rtpmap:107 rtx/90000
a=fmtp:107 apt=125
a=rtpmap:108 H264/90000
a=rtcp-fb:108 goog-remb
a=rtcp-fb:108 transport-cc
a=rtcp-fb:108 ccm fir
a=rtcp-fb:108 nack
a=rtcp-fb:108 nack pli
a=fmtp:108
level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42e01f
a=rtpmap:109 rtx/90000
a=fmtp:109 apt=108
a=rtpmap:124 H264/90000
a=rtcp-fb:124 goog-remb
a=rtcp-fb:124 transport-cc
a=rtcp-fb:124 ccm fir
a=rtcp-fb:124 nack
a=rtcp-fb:124 nack pli
a=fmtp:124
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=4d0033
a=rtpmap:119 rtx/90000
a=fmtp:119 apt=124
a=rtpmap:123 H264/90000
a=rtcp-fb:123 goog-remb
a=rtcp-fb:123 transport-cc
a=rtcp-fb:123 ccm fir
a=rtcp-fb:123 nack
a=rtcp-fb:123 nack pli
a=fmtp:123
level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=640033
a=rtpmap:118 rtx/90000
a=fmtp:118 apt=123
a=rtpmap:114 red/90000
a=rtpmap:115 rtx/90000
a=fmtp:115 apt=114
a=rtpmap:116 ulpfec/90000

0:00:07.791997046  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:4479:_set_description_task:<webrtcbin> we are in ice
controlling mode: false
0:00:07.792046521  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:07.792090029  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:07.793996416  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: PLAYING => PLAYING
0:00:07.795176100  2466 0x7f7c98005000 DEBUG              webrtcice
gstwebrtcice.c:746:gst_webrtc_ice_set_remote_credentials:<webrtcbin0:ice>
Setting remote ICE credentials on ICE stream 1 ufrag:mEV3
pwd:elHiWUbUTm9SIHYJ3KDuHDtm
0:00:07.795616284  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:07.796243513  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:07.797615658  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:07.798421961  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: PLAYING => PLAYING
0:00:07.799180821  2466 0x7f7c98005000 DEBUG              webrtcice
gstwebrtcice.c:746:gst_webrtc_ice_set_remote_credentials:<webrtcbin0:ice>
Setting remote ICE credentials on ICE stream 1 ufrag:mEV3
pwd:elHiWUbUTm9SIHYJ3KDuHDtm
0:00:07.799627578  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4640:_set_description_task:<webrtcbin> notify
signaling-state from have-remote-offer to have-remote-offer
-----------SET REMOTE DESCRIPTION------------
0:00:07.800173749  2466 0x7f7c98005000 INFO               webrtcbin
gstwebrtcbin.c:3310:_create_sdp_task:<webrtcbin> creating answer sdp with
options (NULL)
0:00:07.802944590  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:1545:_find_codec_preferences:<webrtcbin> retrieving codec
preferences from <webrtctransceiver0>
0:00:07.803153309  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:3103:_create_answer_task:<webrtcbin> Found existing
previously negotiated transceiver <webrtctransceiver0> from mid 0 for mline
0 using caps application/x-rtp, media=(string)video, clock-rate=(int)90000,
encoding-name=(string)VP8, payload=(int)96, seqnum-offset=(uint)12225,
timestamp-offset=(uint)3612195570, ssrc=(uint)3497855691,
a-framerate=(string)30
0:00:07.806307896  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:1545:_find_codec_preferences:<webrtcbin> retrieving codec
preferences from <webrtctransceiver1>
0:00:07.814746217  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:3103:_create_answer_task:<webrtcbin> Found existing
previously negotiated transceiver <webrtctransceiver1> from mid 1 for mline
1 using caps application/x-rtp, media=(string)audio, clock-rate=(int)48000,
encoding-name=(string)OPUS, sprop-maxcapturerate=(string)48000,
sprop-stereo=(string)0, payload=(int)97, encoding-params=(string)2,
seqnum-offset=(uint)28659, timestamp-offset=(uint)1614781904,
ssrc=(uint)465568367
-----------SENDING SDP ANSWER------------
on_answer_created
sending SDP answer to peer: v=0
o=- 6037884307875629115 5 IN IP4 0.0.0.0
s=-
t=0 0
a=group:BUNDLE 0 1
m=video 9 UDP/TLS/RTP/SAVPF 96
c=IN IP4 0.0.0.0
a=ice-ufrag:PlcKGP4B+N73iCLii7TubbfCVEFWLXXN
a=ice-pwd:9El7McL63197VYihxUTmeJn9vIMpMfOu
a=mid:0
a=rtcp-mux
a=setup:active
a=rtpmap:96 VP8/90000
a=framerate:30
a=ssrc:3497855691 msid:user2578524959 at host-e8f12b09 webrtctransceiver0
a=ssrc:3497855691 cname:user2578524959 at host-e8f12b09
a=sendonly
a=fingerprint:sha-256
7D:F6:6B:3F:33:81:56:F6:46:60:6E:18:F4:CB:F3:2A:1E:2F:D3:28:F6:25:E7:F5:BD:14:77:19:19:A0:5F:BE
m=audio 9 UDP/TLS/RTP/SAVPF 97
c=IN IP4 0.0.0.0
a=ice-ufrag:PlcKGP4B+N73iCLii7TubbfCVEFWLXXN
a=ice-pwd:9El7McL63197VYihxUTmeJn9vIMpMfOu
a=mid:1
a=rtcp-mux
a=setup:active
a=rtpmap:97 OPUS/48000/2
a=fmtp:97 sprop-maxcapturerate=48000;sprop-stereo=0
a=ssrc:465568367 msid:user2578524959 at host-e8f12b09 webrtctransceiver1
a=ssrc:465568367 cname:user2578524959 at host-e8f12b09
a=sendonly
a=fingerprint:sha-256
7D:F6:6B:3F:33:81:56:F6:46:60:6E:18:F4:CB:F3:2A:1E:2F:D3:28:F6:25:E7:F5:BD:14:77:19:19:A0:5F:BE

handle_websocket_message:
{"protocol":"one-to-one","to":"ges-webrtc-service","action":"SEND_CANDIDATE","data":{"candidate":{"candidate":"candidate:63126866
1 udp 2113937151 ec09dc7d-e98b-4caa-b8af-ae9b04577210.local 43331 typ host
generation 3 ufrag mEV3 network-cost
999","sdpMid":"0","sdpMLineIndex":0},"user":"123456"}}
GOT SEND_CANDIDATE MESSAGE
handle_ice:
candidate:63126866 1 udp 2113937151
ec09dc7d-e98b-4caa-b8af-ae9b04577210.local 43331 typ host generation 3
ufrag mEV3 network-cost 999

handle_websocket_message:
{"protocol":"one-to-one","to":"ges-webrtc-service","action":"SEND_CANDIDATE","data":{"candidate":{"candidate":"candidate:3933521124
1 udp 2113934591 e4de021a-535c-4e08-83f1-8b00c44ad5a2.local 35398 typ host
generation 3 ufrag mEV3 network-cost
999","sdpMid":"0","sdpMLineIndex":0},"user":"123456"}}
GOT SEND_CANDIDATE MESSAGE
handle_ice:
candidate:3933521124 1 udp 2113934591
e4de021a-535c-4e08-83f1-8b00c44ad5a2.local 35398 typ host generation 3
ufrag mEV3 network-cost 999

Sending message
{"protocol":"one-to-one","to":"123456","action":"SEND_SDP","data":{"sdp":{"type":"answer","sdp":"v=0\r\no=-
6037884307875629115 5 IN IP4 0.0.0.0\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0
1\r\nm=video 9 UDP/TLS/RTP/SAVPF 96\r\nc=IN IP4
0.0.0.0\r\na=ice-ufrag:PlcKGP4B+N73iCLii7TubbfCVEFWLXXN\r\na=ice-pwd:9El7McL63197VYihxUTmeJn9vIMpMfOu\r\na=mid:0\r\na=rtcp-mux\r\na=setup:active\r\na=rtpmap:96
VP8/90000\r\na=framerate:30\r\na=ssrc:3497855691
msid:user2578524959 at host-e8f12b09 webrtctransceiver0\r\na=ssrc:3497855691
cname:user2578524959 at host-e8f12b09\r\na=sendonly\r\na=fingerprint:sha-256
7D:F6:6B:3F:33:81:56:F6:46:60:6E:18:F4:CB:F3:2A:1E:2F:D3:28:F6:25:E7:F5:BD:14:77:19:19:A0:5F:BE\r\nm=audio
9 UDP/TLS/RTP/SAVPF 97\r\nc=IN IP4
0.0.0.0\r\na=ice-ufrag:PlcKGP4B+N73iCLii7TubbfCVEFWLXXN\r\na=ice-pwd:9El7McL63197VYihxUTmeJn9vIMpMfOu\r\na=mid:1\r\na=rtcp-mux\r\na=setup:active\r\na=rtpmap:97
OPUS/48000/2\r\na=fmtp:97
sprop-maxcapturerate=48000;sprop-stereo=0\r\na=ssrc:465568367
msid:user2578524959 at host-e8f12b09 webrtctransceiver1\r\na=ssrc:465568367
cname:user2578524959 at host-e8f12b09\r\na=sendonly\r\na=fingerprint:sha-256
7D:F6:6B:3F:33:81:56:F6:46:60:6E:18:F4:CB:F3:2A:1E:2F:D3:28:F6:25:E7:F5:BD:14:77:19:19:A0:5F:BE\r\n"},"user":"ges-webrtc-service"}}
0:00:07.826491279  2466 0x7f7c98005000 INFO               webrtcbin
gstwebrtcbin.c:4312:_set_description_task:<webrtcbin> Attempting to set
local answer in the have-remote-offer state
0:00:07.826737395  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4313:_set_description_task:<webrtcbin> SDP contents
v=0
o=- 6037884307875629115 5 IN IP4 0.0.0.0
s=-
t=0 0
a=group:BUNDLE 0 1
m=video 9 UDP/TLS/RTP/SAVPF 96
c=IN IP4 0.0.0.0
a=ice-ufrag:PlcKGP4B+N73iCLii7TubbfCVEFWLXXN
a=ice-pwd:9El7McL63197VYihxUTmeJn9vIMpMfOu
a=mid:0
a=rtcp-mux
a=setup:active
a=rtpmap:96 VP8/90000
a=framerate:30
a=ssrc:3497855691 msid:user2578524959 at host-e8f12b09 webrtctransceiver0
a=ssrc:3497855691 cname:user2578524959 at host-e8f12b09
a=sendonly
a=fingerprint:sha-256
7D:F6:6B:3F:33:81:56:F6:46:60:6E:18:F4:CB:F3:2A:1E:2F:D3:28:F6:25:E7:F5:BD:14:77:19:19:A0:5F:BE
m=audio 9 UDP/TLS/RTP/SAVPF 97
c=IN IP4 0.0.0.0
a=ice-ufrag:PlcKGP4B+N73iCLii7TubbfCVEFWLXXN
a=ice-pwd:9El7McL63197VYihxUTmeJn9vIMpMfOu
a=mid:1
a=rtcp-mux
a=setup:active
a=rtpmap:97 OPUS/48000/2
a=fmtp:97 sprop-maxcapturerate=48000;sprop-stereo=0
a=ssrc:465568367 msid:user2578524959 at host-e8f12b09 webrtctransceiver1
a=ssrc:465568367 cname:user2578524959 at host-e8f12b09
a=sendonly
a=fingerprint:sha-256
7D:F6:6B:3F:33:81:56:F6:46:60:6E:18:F4:CB:F3:2A:1E:2F:D3:28:F6:25:E7:F5:BD:14:77:19:19:A0:5F:BE

0:00:07.826833318  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:4479:_set_description_task:<webrtcbin> we are in ice
controlling mode: false
0:00:07.826859249  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:07.826883333  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:07.826956753  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: PLAYING => PLAYING
0:00:07.827129672  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:3718:_update_transport_ptmap_from_media:<webrtcbin> mapping
sdp session level attributes to caps
0:00:07.827148891  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:3720:_update_transport_ptmap_from_media:<webrtcbin> mapping
sdp media level attributes to caps
0:00:07.827171907  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:3733:_update_transport_ptmap_from_media:<webrtcbin>  looking
at 0 pt: 96
0:00:07.827204179  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:3718:_update_transport_ptmap_from_media:<webrtcbin> mapping
sdp session level attributes to caps
0:00:07.827214192  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:3720:_update_transport_ptmap_from_media:<webrtcbin> mapping
sdp media level attributes to caps
0:00:07.827230943  2466 0x7f7c98005000 DEBUG              webrtcbin
gstwebrtcbin.c:3733:_update_transport_ptmap_from_media:<webrtcbin>  looking
at 0 pt: 97
0:00:07.827260933  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:07.827333368  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:3438:_find_transceiver_for_sdp_media:<webrtcbin> Found
transceiver <webrtctransceiver0>
0:00:07.827349611  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:07.827368771  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:07.827622306  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: PLAYING => PLAYING
0:00:07.828150605  2466 0x7f7c98005000 FIXME              webrtcbin
gstwebrtcbin.c:3828:_update_transceiver_from_sdp_media:<webrtcbin>
implement transceiver direction changes
0:00:07.828183303  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:3438:_find_transceiver_for_sdp_media:<webrtcbin> Found
transceiver <webrtctransceiver1>
0:00:07.833425299  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:07.833556666  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:07.833973815  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: PLAYING => PLAYING
0:00:07.834589648  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:3863:_update_transceiver_from_sdp_media:<webrtcbin> marking
stream 0x7f7c98002d40 as active
0:00:07.834621003  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:07.834833295  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:07.835508304  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: PLAYING => PLAYING
0:00:07.835625265  2466 0x7f7c98005000 DEBUG              webrtcice
gstwebrtcice.c:805:gst_webrtc_ice_set_local_credentials:<webrtcbin0:ice>
Setting local ICE credentials on ICE stream 1
ufrag:PlcKGP4B+N73iCLii7TubbfCVEFWLXXN pwd:9El7McL63197VYihxUTmeJn9vIMpMfOu
0:00:07.835712060  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:07.835728506  2466 0x7f7c98005000 DEBUG   webrtctransportsendbin
transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>
changing state: PLAYING => PLAYING
0:00:07.835823179  2466 0x7f7c98005000 DEBUG   webrtctransportreceivebin
transportreceivebin.c:236:transport_receive_bin_change_state: changing
state: PLAYING => PLAYING
0:00:07.836077452  2466 0x7f7c98005000 DEBUG              webrtcice
gstwebrtcice.c:823:gst_webrtc_ice_gather_candidates:<webrtcbin0:ice> gather
candidates for stream 1
0:00:07.836099703  2466 0x7f7c98005000 DEBUG        webrtcicestream
icestream.c:177:gst_webrtc_ice_stream_gather_candidates:<webrtcicestream0>
start gathering candidates
0:00:07.836122081  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:4640:_set_description_task:<webrtcbin> notify
signaling-state from stable to stable
0:00:07.836174797  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:1341:_check_if_negotiation_is_needed:<webrtcbin> checking if
negotiation is needed
0:00:07.836199274  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:1478:_check_if_negotiation_is_needed:<webrtcbin> transceiver
direction (sendrecv) doesn't match description intersected direction
sendonly (local sendonly remote recvonly)
0:00:07.836209186  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1499:_check_need_negotiation_task:<webrtcbin> emitting
on-negotiation-needed
0:00:07.836292620  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:394:_find_ice_stream_for_session:<webrtcbin> Found ice
stream id <webrtcicestream0> for session 0
0:00:07.836308842  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:3601:_add_ice_candidate:<webrtcbin> adding ICE candidate
with mline:0, a=candidate:63126866 1 udp 2113937151
ec09dc7d-e98b-4caa-b8af-ae9b04577210.local 43331 typ host generation 3
ufrag mEV3 network-cost 999
0:00:07.836379330  2466 0x7f7c98005000 DEBUG              webrtcice
gstwebrtcice.c:313:_resolve_host:<webrtcbin0:ice> Resolving host
ec09dc7d-e98b-4caa-b8af-ae9b04577210.local
0:00:07.842732024  2466 0x7f7c98005000 ERROR              webrtcice
gstwebrtcice.c:316:_resolve_host: Error resolving
“ec09dc7d-e98b-4caa-b8af-ae9b04577210.local”: Name or service not known
0:00:07.842768010  2466 0x7f7c98005000 WARN               webrtcice
gstwebrtcice.c:690:gst_webrtc_ice_add_candidate:<webrtcbin0:ice> Failed to
resolve ec09dc7d-e98b-4caa-b8af-ae9b04577210.local
0:00:07.842795756  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:394:_find_ice_stream_for_session:<webrtcbin> Found ice
stream id <webrtcicestream0> for session 0
0:00:07.842810874  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:3601:_add_ice_candidate:<webrtcbin> adding ICE candidate
with mline:0, a=candidate:3933521124 1 udp 2113934591
e4de021a-535c-4e08-83f1-8b00c44ad5a2.local 35398 typ host generation 3
ufrag mEV3 network-cost 999
0:00:07.844057594  2466 0x7f7c98005000 DEBUG              webrtcice
gstwebrtcice.c:313:_resolve_host:<webrtcbin0:ice> Resolving host
e4de021a-535c-4e08-83f1-8b00c44ad5a2.local
0:00:07.849683557  2466 0x7f7c98005000 ERROR              webrtcice
gstwebrtcice.c:316:_resolve_host: Error resolving
“e4de021a-535c-4e08-83f1-8b00c44ad5a2.local”: Name or service not known
0:00:07.849777147  2466 0x7f7c98005000 WARN               webrtcice
gstwebrtcice.c:690:gst_webrtc_ice_add_candidate:<webrtcbin0:ice> Failed to
resolve e4de021a-535c-4e08-83f1-8b00c44ad5a2.local
handle_websocket_message:
{"protocol":"one-to-one","to":"ges-webrtc-service","action":"SEND_CANDIDATE","data":{"candidate":{"candidate":"candidate:842163049
1 udp 1677729535 68.8.32.177 33783 typ srflx raddr 0.0.0.0 rport 0
generation 3 ufrag mEV3 network-cost
999","sdpMid":"0","sdpMLineIndex":0},"user":"123456"}}
GOT SEND_CANDIDATE MESSAGE
handle_ice:
candidate:842163049 1 udp 1677729535 68.8.32.177 33783 typ srflx raddr
0.0.0.0 rport 0 generation 3 ufrag mEV3 network-cost 999

0:00:07.855120833  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:394:_find_ice_stream_for_session:<webrtcbin> Found ice
stream id <webrtcicestream0> for session 0
0:00:07.855139754  2466 0x7f7c98005000 LOG                webrtcbin
gstwebrtcbin.c:3601:_add_ice_candidate:<webrtcbin> adding ICE candidate
with mline:0, a=candidate:842163049 1 udp 1677729535 68.8.32.177 33783 typ
srflx raddr 0.0.0.0 rport 0 generation 3 ufrag mEV3 network-cost 999
0:00:07.855301370  2466 0x7f7c98005000 DEBUG    webrtcnicetransport
nicetransport.c:186:_on_component_state_changed:<webrtcnicetransport0> 1 1
connected
0:00:07.855360226  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:858:_collate_ice_connection_states:<webrtcbin> transceiver
0x58b47b509060 state 0x2
0:00:07.855371773  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:858:_collate_ice_connection_states:<webrtcbin> transceiver
0x58b47b509140 state 0x2
0:00:07.855380585  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:847:_collate_ice_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:07.855388679  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:888:_collate_ice_connection_states:<webrtcbin> ICE
connection state: 0x4
0:00:07.855395568  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:922:_collate_ice_connection_states:<webrtcbin> returning
connected
0:00:07.855406640  2466 0x7f7c98005000 INFO               webrtcbin
gstwebrtcbin.c:1247:_update_ice_connection_state_task:<webrtcbin> ICE
connection state change from completed(3) to connected(2)
0:00:07.855421699  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 DTLS state: 0x4
0:00:07.855431074  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 ICE state: 0x2
0:00:07.855439294  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 DTLS state: 0x4
0:00:07.855446977  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 ICE state: 0x2
0:00:07.855454619  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1050:_collate_peer_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:07.855463129  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1118:_collate_peer_connection_states:<webrtcbin> ICE
connection state: 0x4. DTLS connection state: 0x10
0:00:07.855470032  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1162:_collate_peer_connection_states:<webrtcbin> returning
connected
0:00:07.984381133  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:08.081011841  2466 0x7f7c900b22d0 DEBUG    webrtcnicetransport
nicetransport.c:186:_on_component_state_changed:<webrtcnicetransport0> 1 1
ready
0:00:08.081107663  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:858:_collate_ice_connection_states:<webrtcbin> transceiver
0x58b47b509060 state 0x3
0:00:08.081122577  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:858:_collate_ice_connection_states:<webrtcbin> transceiver
0x58b47b509140 state 0x3
0:00:08.081132564  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:847:_collate_ice_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:08.081141136  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:888:_collate_ice_connection_states:<webrtcbin> ICE
connection state: 0x8
0:00:08.081149055  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:917:_collate_ice_connection_states:<webrtcbin> returning
completed
0:00:08.081161913  2466 0x7f7c98005000 INFO               webrtcbin
gstwebrtcbin.c:1247:_update_ice_connection_state_task:<webrtcbin> ICE
connection state change from connected(2) to completed(3)
0:00:08.081179455  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 DTLS state: 0x4
0:00:08.081189127  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509060 ICE state: 0x3
0:00:08.081197854  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1060:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 DTLS state: 0x4
0:00:08.081206549  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1073:_collate_peer_connection_states:<webrtcbin> transceiver
0x58b47b509140 ICE state: 0x3
0:00:08.081214585  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1050:_collate_peer_connection_states:<webrtcbin> transceiver
0x7f7c94058a30 has no mid
0:00:08.081223688  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1118:_collate_peer_connection_states:<webrtcbin> ICE
connection state: 0x8. DTLS connection state: 0x10
0:00:08.081231187  2466 0x7f7c98005000 TRACE              webrtcbin
gstwebrtcbin.c:1162:_collate_peer_connection_states:<webrtcbin> returning
connected
0:00:08.181530613  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:08.386843059  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:08.586541507  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:08.783631002  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:08.991216724  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:09.153280307  2466 0x7f7c90083ed0 DEBUG              webrtcbin
gstwebrtcbin.c:5259:on_rtpbin_request_pt_map:<webrtcbin> getting pt map for
pt 97 in session 0
0:00:09.153419827  2466 0x7f7c90083ed0 TRACE              webrtcbin
gstwebrtcbin.c:508:_find_transport_for_session:<webrtcbin> Found transport
<transportstream0> for session 0
0:00:09.153470733  2466 0x7f7c90083ed0 TRACE              webrtcbin
gstwebrtcbin.c:5269:on_rtpbin_request_pt_map:<webrtcbin> Found caps
application/x-rtp, media=(string)audio, payload=(int)97,
clock-rate=(int)48000, encoding-name=(string)OPUS,
encoding-params=(string)2, sprop-maxcapturerate=(string)48000,
sprop-stereo=(string)0 for pt 97 in session 0
0:00:09.156492981  2466 0x7f7c90083ed0 INFO               webrtcbin
gstwebrtcbin.c:5627:on_rtpbin_sender_ssrc_active:<webrtcbin> session 0 ssrc
3497855691 sender ssrc active
0:00:09.160586274  2466 0x7f7c90083ed0 INFO               webrtcbin
gstwebrtcbin.c:5627:on_rtpbin_sender_ssrc_active:<webrtcbin> session 0 ssrc
465568367 sender ssrc active
0:00:09.202115716  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:09.400252636  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:09.606276215  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:09.823805590  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:10.011814841  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:10.208918998  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:10.409515722  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:10.609140676  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:10.804380944  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:11.014004889  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:11.207673524  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:11.439261574  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
0:00:11.611624416  2466 0x7f7c900b2230 INFO               webrtcbin
gstwebrtcbin.c:5583:on_rtpbin_ssrc_active:<webrtcbin> session 0 ssrc 1
active
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/gstreamer-devel/attachments/20201024/e7c0bc50/attachment-0001.htm>


More information about the gstreamer-devel mailing list