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@host-5afdf240 webrtctransceiver0 a=ssrc:1290502017 cname:user2307279746@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@host-8f3a122d webrtctransceiver0 a=ssrc:422817870 cname:user2542828131@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@host-8f3a122d webrtctransceiver1 a=ssrc:4187272436 cname:user2542828131@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 <a href="http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:3">http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:3 <a href="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4">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 <a href="http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13">http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 <a href="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12">http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 <a href="http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11">http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 <a href="http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7">http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 <a href="http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8">http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 <a href="http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4">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@host-e8f12b09 webrtctransceiver0 a=ssrc:3497855691 cname:user2578524959@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@host-e8f12b09 webrtctransceiver0\r\na=ssrc:3497855691 cname:user2578524959@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@host-e8f12b09 webrtctransceiver0 a=ssrc:3497855691 cname:user2578524959@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 <a href="http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:3">http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:3 <a href="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4">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 <a href="http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13">http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 <a href="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12">http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 <a href="http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11">http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 <a href="http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7">http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 <a href="http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8">http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 <a href="http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4">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@host-e8f12b09 webrtctransceiver0 a=ssrc:3497855691 cname:user2578524959@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@host-e8f12b09 webrtctransceiver1 a=ssrc:465568367 cname:user2578524959@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@host-e8f12b09 webrtctransceiver0\r\na=ssrc:3497855691 cname:user2578524959@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@host-e8f12b09 webrtctransceiver1\r\na=ssrc:465568367 cname:user2578524959@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@host-e8f12b09 webrtctransceiver0 a=ssrc:3497855691 cname:user2578524959@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@host-e8f12b09 webrtctransceiver1 a=ssrc:465568367 cname:user2578524959@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 _______________________________________________ gstreamer-devel mailing list [hidden email] https://lists.freedesktop.org/mailman/listinfo/gstreamer-devel |
Hi, I think the problem is that you are setting the payload type to 97 when you are payloading opus, but in the offer from chrome, opus is 111. In the short term, try changing the pt to 111 on the encode/payload side into webrtcbin. If that is the problem, then you're going to have to build that part of the pipeline only after you receive the offer, and you're going to have to look inside the offer to extract the desired payload type. On Sat, Oct 24, 2020 at 10:29 AM Luke McGartland <[hidden email]> wrote:
_______________________________________________ gstreamer-devel mailing list [hidden email] https://lists.freedesktop.org/mailman/listinfo/gstreamer-devel |
Free forum by Nabble | Edit this page |