WebRTC Found cpas (NULL) for pt
Hello, I have problem with WebRTCbin. When i'm trying to capture WebRTC stream from Red5Pro server i'm getting those logs:
0:00:12.217442669 [335m 1084[00m 0x7f8e9c1f7630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:469:_find_transport_for_session:<test>[00m Found transport <transportstream0> for session 0
0:00:12.217446907 [335m 1084[00m 0x7f8e9c1f7630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:4348:on_rtpbin_request_pt_map:<test>[00m Found caps (NULL) for pt 126 in session 0
0:00:12.217456816 [335m 1084[00m 0x7f8e9c1f7630 [37mDEBUG [00m [00m webrtcbin gstwebrtcbin.c:4338:on_rtpbin_request_pt_map:<test>[00m getting pt map for pt 126 in session 0
I can receive audio but can not receive video.
Red5Pro offer:
offer: v=0
o=red5pro_test_test 9811772548254 1 IN IP4 0.0.0.0
s=-
t=0 0
a=msid-semantic:WMS 5c22fc4bee649dcc
a=group:BUNDLE audio video
a=ice-options:trickle
m=audio 9 UDP/TLS/RTP/SAVPF 111
c=IN IP4 0.0.0.0
b=AS:64
a=rtpmap:111 opus/48000/2
a=ice-ufrag:fl4bj1e7307fe7
a=ice-pwd:7rosb3q3is5i7ovk5i8eutmoh5
a=fingerprint:sha-256 1B:C8:F3:D2:97:28:1C:3B:36:47:91:57:1F:14:6A:C5:CD:BC:83:27:3C:C4:89:A1:5A:22:23:8F:24:5C:8B:6E
a=setup:actpass
a=mid:audio
a=sendonly
a=rtcp-mux
a=rtcp-rsize
a=fmtp:111 minptime=10;maxplaybackrate=48000;stereo=1;sprop-stereo=1
a=ssrc:3858624538 cname:test
a=ssrc:3858624538 msid:5c22fc4bee649dcc 4e39fd7e-0049-4973-b970-b9563b4c8ee4
m=video 9 UDP/TLS/RTP/SAVPF 126
c=IN IP4 0.0.0.0
b=AS:843
a=rtpmap:126 H264/90000
a=ice-ufrag:fl4bj1e7307fe7
a=ice-pwd:7rosb3q3is5i7ovk5i8eutmoh5
a=fingerprint:sha-256 1B:C8:F3:D2:97:28:1C:3B:36:47:91:57:1F:14:6A:C5:CD:BC:83:27:3C:C4:89:A1:5A:22:23:8F:24:5C:8B:6E
a=setup:actpass
a=mid:video
a=sendonly
a=rtcp-mux
a=rtcp-rsize
a=fmtp:126 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
a=rtcp-fb:126 nack
a=rtcp-fb:126 goog-remb
a=rtcp-fb:126 ccm tmmbr
a=ssrc:3889477870 cname:test
a=ssrc:3889477870 msid:5c22fc4bee649dcc 614300e8-016c-4369-a6c0-5dd74bd87b5b
GStreamer answer:
v=0
o=- 9811772548254 0 IN IP4 0.0.0.0
s=-
t=0 0
a=ice-options:trickle
m=audio 9 UDP/TLS/RTP/SAVPF 111
c=IN IP4 0.0.0.0
a=ice-ufrag:PUPp3ooizmdRd+9CHLZGwg4KaxFRokB0
a=ice-pwd:W9oAdrvyJ0bPl0zP7MTV0gnJiUL3WM5m
a=mid:audio
a=rtcp-mux
a=setup:active
a=rtpmap:111 OPUS/48000/2
a=fmtp:111 minptime=10;maxplaybackrate=48000;stereo=1;sprop-stereo=1
a=recvonly
a=fingerprint:sha-256 31:AD:96:11:10:52:8B:E2:85:2F:96:6C:F2:8C:81:F7:84:57:B5:E9:61:3C:6A:32:A6:E4:B3:C5:5F:45:9D:18
m=video 9 UDP/TLS/RTP/SAVPF 126
c=IN IP4 0.0.0.0
a=ice-ufrag:PUPp3ooizmdRd+9CHLZGwg4KaxFRokB0
a=ice-pwd:W9oAdrvyJ0bPl0zP7MTV0gnJiUL3WM5m
a=mid:video
a=rtcp-mux
a=setup:active
a=rtpmap:126 H264/90000
a=fmtp:126 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
a=recvonly
a=fingerprint:sha-256 31:AD:96:11:10:52:8B:E2:85:2F:96:6C:F2:8C:81:F7:84:57:B5:E9:61:3C:6A:32:A6:E4:B3:C5:5F:45:9D:18
I set DEBUG to webrtc:7 Logs:
0:00:00.100162754 [332m 1561[00m 0x7f62dc677b00 [37mDEBUG [00m [00m webrtcice gstwebrtcice.c:807:gst_webrtc_ice_set_property:<webrtcice0>[00m setting stun server, stun://stun3.l.google.com:19302
0:00:00.146603818 [332m 1561[00m 0x7f62dc677b00 [37mDEBUG [00m [00m webrtcbin gstwebrtcbin.c:4707:gst_webrtc_bin_change_state:[00m changing state: NULL => READY
0:00:00.146782904 [332m 1561[00m 0x7f62dc677b00 [33;01mLOG [00m [00m webrtcbin gstwebrtcbin.c:1175:_check_if_negotiation_is_needed:<test>[00m checking if negotiation is needed
0:00:00.146790859 [332m 1561[00m 0x7f62dc677b00 [33;01mLOG [00m [00m webrtcbin gstwebrtcbin.c:1195:_check_if_negotiation_is_needed:<test>[00m no local description set
0:00:00.146820595 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:1287:_check_need_negotiation_task:<test>[00m emitting on-negotiation-needed
0:00:00.168925197 [332m 1561[00m 0x7f62dc677b00 [37mDEBUG [00m [00m webrtcbin gstwebrtcbin.c:4707:gst_webrtc_bin_change_state:[00m changing state: READY => PAUSED
0:00:00.172557696 [332m 1561[00m 0x7f62dc677b00 [37mDEBUG [00m [00m webrtcbin gstwebrtcbin.c:4707:gst_webrtc_bin_change_state:[00m changing state: PAUSED => PLAYING
0:00:00.451150268 [332m 1561[00m 0x7f62dc58f630 [36mINFO [00m [00m webrtcbin gstwebrtcbin.c:3562:_set_description_task:<test>[00m Attempting to set remote offer in the stable state
0:00:00.451176367 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3563:_set_description_task:<test>[00m SDP contents
v=0
o=red5pro_test_test 9811772548254 1 IN IP4 0.0.0.0
s=-
t=0 0
a=msid-semantic:WMS 5c22fc4bee649dcc
a=group:BUNDLE audio video
a=ice-options:trickle
m=audio 9 UDP/TLS/RTP/SAVPF 111
c=IN IP4 0.0.0.0
b=AS:64
a=rtpmap:111 opus/48000/2
a=ice-ufrag:fl4bj1e7307fe7
a=ice-pwd:7rosb3q3is5i7ovk5i8eutmoh5
a=fingerprint:sha-256 1B:C8:F3:D2:97:28:1C:3B:36:47:91:57:1F:14:6A:C5:CD:BC:83:27:3C:C4:89:A1:5A:22:23:8F:24:5C:8B:6E
a=setup:actpass
a=mid:audio
a=sendonly
a=rtcp-mux
a=rtcp-rsize
a=fmtp:111 minptime=10;maxplaybackrate=48000;stereo=1;sprop-stereo=1
a=ssrc:3858624538 cname:test
a=ssrc:3858624538 msid:5c22fc4bee649dcc 4e39fd7e-0049-4973-b970-b9563b4c8ee4
m=video 9 UDP/TLS/RTP/SAVPF 126
c=IN IP4 0.0.0.0
b=AS:843
a=rtpmap:126 H264/90000
a=ice-ufrag:fl4bj1e7307fe7
a=ice-pwd:7rosb3q3is5i7ovk5i8eutmoh5
a=fingerprint:sha-256 1B:C8:F3:D2:97:28:1C:3B:36:47:91:57:1F:14:6A:C5:CD:BC:83:27:3C:C4:89:A1:5A:22:23:8F:24:5C:8B:6E
a=setup:actpass
a=mid:video
a=sendonly
a=rtcp-mux
a=rtcp-rsize
a=fmtp:126 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
a=rtcp-fb:126 nack
a=rtcp-fb:126 goog-remb
a=rtcp-fb:126 ccm tmmbr
a=ssrc:3889477870 cname:test
a=ssrc:3889477870 msid:5c22fc4bee649dcc 614300e8-016c-4369-a6c0-5dd74bd87b5b
0:00:00.451210611 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3695:_set_description_task:<test>[00m notify signaling-state from stable to have-remote-offer
0:00:00.451307794 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:469:_find_transport_for_session:<test>[00m Found transport (NULL) for session 0
(gst1-java-core:1561): GLib-GObject-WARNING **: 14:57:46.222: g_object_set_is_valid_property: object class 'GstDtlsSrtpEnc' has no property named 'rtp-sync'
(gst1-java-core:1561): GLib-GObject-WARNING **: 14:57:46.302: g_object_set_is_valid_property: object class 'GstDtlsSrtpEnc' has no property named 'rtp-sync'
0:00:00.536379642 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:358:_find_ice_stream_for_session:<test>[00m No ice stream available for session 0
0:00:00.536388518 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcice gstwebrtcice.c:417:_add_stun_server:<webrtcice0>[00m adding stun server, (null)
0:00:00.536426259 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcice gstwebrtcice.c:307:_resolve_host:<webrtcice0>[00m Resolving host stun3.l.google.com
0:00:00.537877954 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcice gstwebrtcice.c:316:_resolve_host:<webrtcice0>[00m Resolved 2 addresses for host stun3.l.google.com
0:00:00.537997328 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:369:_add_ice_stream_item:<test>[00m adding ice stream <webrtcicestream0> for session 0
0:00:00.539612440 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:1467:_create_transport_channel:<test>[00m Create transport <transportstream0> for session 0
0:00:00.547057244 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:4273:on_rtpbin_pad_added:<test>[00m new rtpbin pad recv_rtcp_sink_0
0:00:00.547158875 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:4273:on_rtpbin_pad_added:<test>[00m new rtpbin pad send_rtcp_src_0
0:00:00.547232273 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportsendbin transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>[00m changing state: NULL => READY
0:00:00.548592306 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportsendbin transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>[00m changing state: READY => PAUSED
0:00:00.550483005 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportsendbin transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>[00m changing state: PAUSED => PLAYING
0:00:00.551519961 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportreceivebin transportreceivebin.c:204:transport_receive_bin_change_state:[00m changing state: NULL => READY
0:00:00.558464337 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportreceivebin transportreceivebin.c:204:transport_receive_bin_change_state:[00m changing state: READY => PAUSED
0:00:00.562943064 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportreceivebin transportreceivebin.c:204:transport_receive_bin_change_state:[00m changing state: PAUSED => PLAYING
0:00:00.566793631 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcice gstwebrtcice.c:647:gst_webrtc_ice_set_remote_credentials:<webrtcice0>[00m Setting remote ICE credentials on ICE stream 1 ufrag:fl4bj1e7307fe7 pwd:7rosb3q3is5i7ovk5i8eutmoh5
0:00:00.566807918 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:469:_find_transport_for_session:<test>[00m Found transport <transportstream0> for session 0
0:00:00.566814180 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportsendbin transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>[00m changing state: PLAYING => PLAYING
0:00:00.566832925 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportreceivebin transportreceivebin.c:204:transport_receive_bin_change_state:[00m changing state: PLAYING => PLAYING
0:00:00.566868041 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcice gstwebrtcice.c:702:gst_webrtc_ice_gather_candidates:<webrtcice0>[00m gather candidates for stream 1
0:00:00.566872940 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcicestream icestream.c:177:gst_webrtc_ice_stream_gather_candidates:<webrtcicestream0>[00m start gathering candidates
0:00:00.569165864 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcnicetransport nicetransport.c:186:_on_component_state_changed:<webrtcnicetransport0>[00m 1 1 gathering
0:00:00.569181293 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcnicetransport nicetransport.c:186:_on_component_state_changed:<webrtcnicetransport1>[00m 1 2 gathering
0:00:00.569204877 [332m 1561[00m 0x7f62dc58f630 [36mINFO [00m [00m webrtcbin gstwebrtcbin.c:2745:_create_sdp_task:<test>[00m creating answer sdp with options (NULL)
onNewTransceiver
0
0:00:00.572753839 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:469:_find_transport_for_session:<test>[00m Found transport <transportstream0> for session 0
0:00:00.572771181 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportsendbin transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>[00m changing state: PLAYING => PLAYING
0:00:00.572806307 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportreceivebin transportreceivebin.c:204:transport_receive_bin_change_state:[00m changing state: PLAYING => PLAYING
0:00:00.572898991 [332m 1561[00m 0x7f62dc58f630 [33;01mLOG [00m [00m webrtcbin gstwebrtcbin.c:1331:_find_codec_preferences:<test>[00m retreiving codec preferences from <webrtctransceiver0>
0:00:00.572917646 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:2583:_create_answer_task:<test>[00m trying to compare application/x-rtp, media=(string)video, payload=(int)126, clock-rate=(int)90000, encoding-name=(string)H264, level-asymmetry-allowed=(string)1, packetization-mode=(string)1, profile-level-id=(string)42e01f, rtcp-fb-nack=(boolean)true, rtcp-fb-goog-remb=(boolean)true, rtcp-fb-ccm-tmmbr=(boolean)true and (NULL)
onNewTransceiver
1
0:00:00.574269995 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:469:_find_transport_for_session:<test>[00m Found transport (NULL) for session 1
(gst1-java-core:1561): GLib-GObject-WARNING **: 14:57:46.341: g_object_set_is_valid_property: object class 'GstDtlsSrtpEnc' has no property named 'rtp-sync'
(gst1-java-core:1561): GLib-GObject-WARNING **: 14:57:46.341: g_object_set_is_valid_property: object class 'GstDtlsSrtpEnc' has no property named 'rtp-sync'
0:00:00.575328702 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:358:_find_ice_stream_for_session:<test>[00m No ice stream available for session 1
0:00:00.575337338 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcice gstwebrtcice.c:417:_add_stun_server:<webrtcice0>[00m adding stun server, (null)
0:00:00.575343620 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcice gstwebrtcice.c:307:_resolve_host:<webrtcice0>[00m Resolving host stun3.l.google.com
0:00:00.576057160 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcice gstwebrtcice.c:316:_resolve_host:<webrtcice0>[00m Resolved 2 addresses for host stun3.l.google.com
0:00:00.576208614 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:369:_add_ice_stream_item:<test>[00m adding ice stream <webrtcicestream1> for session 1
0:00:00.579391859 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:1467:_create_transport_channel:<test>[00m Create transport <transportstream1> for session 1
0:00:00.587327535 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:4273:on_rtpbin_pad_added:<test>[00m new rtpbin pad recv_rtcp_sink_1
0:00:00.587463310 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:4273:on_rtpbin_pad_added:<test>[00m new rtpbin pad send_rtcp_src_1
0:00:00.587566694 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportsendbin transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin1>[00m changing state: NULL => READY
0:00:00.589064526 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportsendbin transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin1>[00m changing state: READY => PAUSED
0:00:00.590620717 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportsendbin transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin1>[00m changing state: PAUSED => PLAYING
0:00:00.591239088 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportreceivebin transportreceivebin.c:204:transport_receive_bin_change_state:[00m changing state: NULL => READY
0:00:00.595048849 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportreceivebin transportreceivebin.c:204:transport_receive_bin_change_state:[00m changing state: READY => PAUSED
0:00:00.597951196 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportreceivebin transportreceivebin.c:204:transport_receive_bin_change_state:[00m changing state: PAUSED => PLAYING
0:00:00.605828012 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:895:_collate_ice_gathering_states:<test>[00m ICE gathering state: 0x0
0:00:00.605839955 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:905:_collate_ice_gathering_states:<test>[00m returning complete
0:00:00.605846847 [332m 1561[00m 0x7f62dc58f630 [36mINFO [00m [00m webrtcbin gstwebrtcbin.c:1053:_update_ice_gathering_state_task:<test>[00m ICE gathering state change from new(0) to complete(2)
0:00:00.606016766 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:895:_collate_ice_gathering_states:<test>[00m ICE gathering state: 0x0
0:00:00.606023188 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:905:_collate_ice_gathering_states:<test>[00m returning complete
0:00:00.606028989 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:1 1 UDP 2013266431 fe80::9253:bfb5:e716:22ac 42726 typ host
0:00:00.606034470 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:2 1 TCP 1015022847 fe80::9253:bfb5:e716:22ac 9 typ host tcptype active
0:00:00.606039259 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:3 1 TCP 1010828543 fe80::9253:bfb5:e716:22ac 58349 typ host tcptype passive
0:00:00.606046622 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:4 1 UDP 2013266430 192.168.0.32 47137 typ host
0:00:00.606050830 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:5 1 TCP 1015022079 192.168.0.32 9 typ host tcptype active
0:00:00.606055219 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:6 1 TCP 1010827775 192.168.0.32 48803 typ host tcptype passive
0:00:00.606059697 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:1 2 UDP 2013266430 fe80::9253:bfb5:e716:22ac 42832 typ host
0:00:00.606063524 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:2 2 TCP 1015022846 fe80::9253:bfb5:e716:22ac 9 typ host tcptype active
0:00:00.606067381 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:3 2 TCP 1010828542 fe80::9253:bfb5:e716:22ac 46263 typ host tcptype passive
0:00:00.606072621 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:4 2 UDP 2013266429 192.168.0.32 45587 typ host
0:00:00.606078953 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:5 2 TCP 1015022078 192.168.0.32 9 typ host tcptype active
0:00:00.606082620 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:6 2 TCP 1010827774 192.168.0.32 35157 typ host tcptype passive
0:00:00.606086317 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:800:_collate_ice_connection_states:<test>[00m ICE connection state: 0x0
0:00:00.606089413 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:827:_collate_ice_connection_states:<test>[00m returning new
0:00:00.606093911 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:963:_collate_peer_connection_states:<test>[00m ICE connection state: 0x0. DTLS connection state: 0x0
0:00:00.606096506 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:1017:_collate_peer_connection_states:<test>[00m returning new
0:00:00.606099652 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:800:_collate_ice_connection_states:<test>[00m ICE connection state: 0x0
0:00:00.606102036 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:827:_collate_ice_connection_states:<test>[00m returning new
0:00:00.606105884 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:963:_collate_peer_connection_states:<test>[00m ICE connection state: 0x0. DTLS connection state: 0x0
0:00:00.606108278 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:1017:_collate_peer_connection_states:<test>[00m returning new
0:00:00.608421941 [332m 1561[00m 0x7f62dc58f630 [36mINFO [00m [00m webrtcbin gstwebrtcbin.c:3562:_set_description_task:<test>[00m Attempting to set local answer in the have-remote-offer state
0:00:00.608432100 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3563:_set_description_task:<test>[00m SDP contents
v=0
o=- 9811772548254 0 IN IP4 0.0.0.0
s=-
t=0 0
a=ice-options:trickle
m=audio 9 UDP/TLS/RTP/SAVPF 111
c=IN IP4 0.0.0.0
a=ice-ufrag:PUPp3ooizmdRd+9CHLZGwg4KaxFRokB0
a=ice-pwd:W9oAdrvyJ0bPl0zP7MTV0gnJiUL3WM5m
a=mid:audio
a=rtcp-mux
a=setup:active
a=rtpmap:111 OPUS/48000/2
a=fmtp:111 minptime=10;maxplaybackrate=48000;stereo=1;sprop-stereo=1
a=recvonly
a=fingerprint:sha-256 31:AD:96:11:10:52:8B:E2:85:2F:96:6C:F2:8C:81:F7:84:57:B5:E9:61:3C:6A:32:A6:E4:B3:C5:5F:45:9D:18
m=video 9 UDP/TLS/RTP/SAVPF 126
c=IN IP4 0.0.0.0
a=ice-ufrag:PUPp3ooizmdRd+9CHLZGwg4KaxFRokB0
a=ice-pwd:W9oAdrvyJ0bPl0zP7MTV0gnJiUL3WM5m
a=mid:video
a=rtcp-mux
a=setup:active
a=rtpmap:126 H264/90000
a=fmtp:126 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
a=recvonly
a=fingerprint:sha-256 31:AD:96:11:10:52:8B:E2:85:2F:96:6C:F2:8C:81:F7:84:57:B5:E9:61:3C:6A:32:A6:E4:B3:C5:5F:45:9D:18
0:00:00.608451577 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3695:_set_description_task:<test>[00m notify signaling-state from have-remote-offer to stable
0:00:00.608527950 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:2851:_find_transceiver_for_sdp_media:<test>[00m Found transceiver <webrtctransceiver0>
0:00:00.608537178 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:469:_find_transport_for_session:<test>[00m Found transport <transportstream0> for session 0
0:00:00.608543960 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportsendbin transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>[00m changing state: PLAYING => PLAYING
0:00:00.608570670 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportreceivebin transportreceivebin.c:204:transport_receive_bin_change_state:[00m changing state: PLAYING => PLAYING
0:00:00.608618741 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcbin gstwebrtcbin.c:3051:_update_transport_ptmap_from_media:<test>[00m mapping sdp session level attributes to caps
0:00:00.608626124 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcbin gstwebrtcbin.c:3053:_update_transport_ptmap_from_media:<test>[00m mapping sdp media level attributes to caps
0:00:00.608642215 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcbin gstwebrtcbin.c:3066:_update_transport_ptmap_from_media:<test>[00m looking at 0 pt: 111
0:00:00.608777469 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3186:_update_transceiver_from_sdp_media:<test>[00m transceiver direction change
0:00:00.608786506 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcbin gstwebrtcbin.c:3219:_update_transceiver_from_sdp_media:<test>[00m creating new receive pad for transceiver <webrtctransceiver0>
0:00:00.608826911 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcbin gstwebrtcbin.c:268:gst_webrtc_bin_pad_new:<'':src_0>[00m new visible pad with direction src
0:00:00.608833213 [332m 1561[00m 0x7f62dc58f630 [36mINFO [00m [00m webrtcbin gstwebrtcbin.c:2954:_connect_output_stream:<test>[00m linking output stream 0
0:00:00.608922020 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:4273:on_rtpbin_pad_added:<test>[00m new rtpbin pad recv_rtp_sink_0
0:00:00.608941877 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:469:_find_transport_for_session:<test>[00m Found transport <transportstream0> for session 0
0:00:00.608950193 [332m 1561[00m 0x7f62dc58f630 [33;01mLOG [00m [00m webrtcbin gstwebrtcbin.c:4451:on_rtpbin_request_aux_receiver:<test>[00m requesting aux receiver for stream <transportstream0> with pt red:0 rtx:0
0:00:00.609023731 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportreceivebin transportreceivebin.c:204:transport_receive_bin_change_state:[00m changing state: PLAYING => PLAYING
0:00:00.609108570 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportsendbin transportsendbin.c:334:_on_notify_dtls_client_status:<transportsendbin0>[00m DTLS-SRTP encoder configured. Unlocking it and changing state <dtlssrtpenc0>
0:00:00.611719481 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportsendbin transportsendbin.c:334:_on_notify_dtls_client_status:<transportsendbin0>[00m DTLS-SRTP encoder configured. Unlocking it and changing state <dtlssrtpenc1>
0:00:00.611867619 [332m 1561[00m 0x7f62481cba30 [33;01mLOG [00m [00mwebrtctransportsendbin transportsendbin.c:150:pad_block:<dtlssrtpenc0:src>[00m blocking pad with data buffer: 0x7f62482a1360, 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:00.614314833 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportreceivebin transportreceivebin.c:142:transport_receive_bin_set_receive_state:<transportreceivebin0>[00m changing receive state to pass
0:00:00.614332476 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:2851:_find_transceiver_for_sdp_media:<test>[00m Found transceiver <webrtctransceiver1>
0:00:00.614339098 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:469:_find_transport_for_session:<test>[00m Found transport <transportstream1> for session 1
0:00:00.614345190 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportsendbin transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin1>[00m changing state: PLAYING => PLAYING
0:00:00.614371990 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportreceivebin transportreceivebin.c:204:transport_receive_bin_change_state:[00m changing state: PLAYING => PLAYING
0:00:00.614444636 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcbin gstwebrtcbin.c:3051:_update_transport_ptmap_from_media:<test>[00m mapping sdp session level attributes to caps
0:00:00.614456178 [332m 1561[00m 0x7f62481cb940 [33;01mLOG [00m [00mwebrtctransportsendbin transportsendbin.c:150:pad_block:<dtlssrtpenc1:src>[00m blocking pad with data buffer: 0x7f62482a17e0, 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:00.614461228 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcbin gstwebrtcbin.c:3053:_update_transport_ptmap_from_media:<test>[00m mapping sdp media level attributes to caps
0:00:00.614505210 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcbin gstwebrtcbin.c:3066:_update_transport_ptmap_from_media:<test>[00m looking at 0 pt: 126
0:00:00.614692061 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3186:_update_transceiver_from_sdp_media:<test>[00m transceiver direction change
0:00:00.614706258 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcbin gstwebrtcbin.c:3219:_update_transceiver_from_sdp_media:<test>[00m creating new receive pad for transceiver <webrtctransceiver1>
0:00:00.614735873 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcbin gstwebrtcbin.c:268:gst_webrtc_bin_pad_new:<'':src_1>[00m new visible pad with direction src
0:00:00.614744279 [332m 1561[00m 0x7f62dc58f630 [36mINFO [00m [00m webrtcbin gstwebrtcbin.c:2954:_connect_output_stream:<test>[00m linking output stream 1
0:00:00.614832344 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:4273:on_rtpbin_pad_added:<test>[00m new rtpbin pad recv_rtp_sink_1
0:00:00.614847393 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:469:_find_transport_for_session:<test>[00m Found transport <transportstream1> for session 1
0:00:00.614858243 [332m 1561[00m 0x7f62dc58f630 [33;01mLOG [00m [00m webrtcbin gstwebrtcbin.c:4451:on_rtpbin_request_aux_receiver:<test>[00m requesting aux receiver for stream <transportstream1> with pt red:0 rtx:0
0:00:00.614964382 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportreceivebin transportreceivebin.c:204:transport_receive_bin_change_state:[00m changing state: PLAYING => PLAYING
0:00:00.615090379 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportsendbin transportsendbin.c:334:_on_notify_dtls_client_status:<transportsendbin1>[00m DTLS-SRTP encoder configured. Unlocking it and changing state <dtlssrtpenc2>
0:00:00.620852785 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportsendbin transportsendbin.c:334:_on_notify_dtls_client_status:<transportsendbin1>[00m DTLS-SRTP encoder configured. Unlocking it and changing state <dtlssrtpenc3>
0:00:00.621211949 [332m 1561[00m 0x7f62481cba80 [33;01mLOG [00m [00mwebrtctransportsendbin transportsendbin.c:150:pad_block:<dtlssrtpenc2:src>[00m blocking pad with data buffer: 0x7f62482a1c60, 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:00.624209997 [332m 1561[00m 0x7f6248260540 [33;01mLOG [00m [00mwebrtctransportsendbin transportsendbin.c:150:pad_block:<dtlssrtpenc3:src>[00m blocking pad with data buffer: 0x7f62482e9120, 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:00.624830030 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportreceivebin transportreceivebin.c:142:transport_receive_bin_set_receive_state:<transportreceivebin1>[00m changing receive state to pass
0:00:00.624856149 [332m 1561[00m 0x7f62dc58f630 [33;01mLOG [00m [00m webrtcbin gstwebrtcbin.c:1175:_check_if_negotiation_is_needed:<test>[00m checking if negotiation is needed
0:00:00.624872290 [332m 1561[00m 0x7f62dc58f630 [33;01mLOG [00m [00m webrtcbin gstwebrtcbin.c:1279:_check_if_negotiation_is_needed:<test>[00m no negotiation needed
0:00:00.624882318 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:469:_find_transport_for_session:<test>[00m Found transport <transportstream0> for session 0
0:00:00.624891826 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportsendbin transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin0>[00m changing state: PLAYING => PLAYING
0:00:00.624957470 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportreceivebin transportreceivebin.c:204:transport_receive_bin_change_state:[00m changing state: PLAYING => PLAYING
0:00:00.625033813 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcice gstwebrtcice.c:684:gst_webrtc_ice_set_local_credentials:<webrtcice0>[00m Setting local ICE credentials on ICE stream 1 ufrag:PUPp3ooizmdRd+9CHLZGwg4KaxFRokB0 pwd:W9oAdrvyJ0bPl0zP7MTV0gnJiUL3WM5m
0:00:00.625046016 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:469:_find_transport_for_session:<test>[00m Found transport <transportstream1> for session 1
0:00:00.625054151 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportsendbin transportsendbin.c:195:transport_send_bin_change_state:<transportsendbin1>[00m changing state: PLAYING => PLAYING
0:00:00.625104936 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00mwebrtctransportreceivebin transportreceivebin.c:204:transport_receive_bin_change_state:[00m changing state: PLAYING => PLAYING
0:00:00.625167654 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcice gstwebrtcice.c:684:gst_webrtc_ice_set_local_credentials:<webrtcice0>[00m Setting local ICE credentials on ICE stream 2 ufrag:PUPp3ooizmdRd+9CHLZGwg4KaxFRokB0 pwd:W9oAdrvyJ0bPl0zP7MTV0gnJiUL3WM5m
0:00:00.625174387 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcice gstwebrtcice.c:702:gst_webrtc_ice_gather_candidates:<webrtcice0>[00m gather candidates for stream 1
0:00:00.625179867 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcicestream icestream.c:177:gst_webrtc_ice_stream_gather_candidates:<webrtcicestream0>[00m start gathering candidates
0:00:00.625202520 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcice gstwebrtcice.c:702:gst_webrtc_ice_gather_candidates:<webrtcice0>[00m gather candidates for stream 2
0:00:00.625207850 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcicestream icestream.c:177:gst_webrtc_ice_stream_gather_candidates:<webrtcicestream1>[00m start gathering candidates
0:00:00.627515842 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:352:_find_ice_stream_for_session:<test>[00m Found ice stream id <webrtcicestream0> for session 0
0:00:00.627527985 [332m 1561[00m 0x7f62dc58f630 [33;01mLOG [00m [00m webrtcbin gstwebrtcbin.c:2993:_add_ice_candidate:<test>[00m adding ICE candidate with mline:0, a=candidate:1 1 udp 687865855 192.168.0.32 62104 typ host generation 0
0:00:00.627561197 [332m 1561[00m 0x7f62dc58f630 [37mDEBUG [00m [00m webrtcnicetransport nicetransport.c:186:_on_component_state_changed:<webrtcnicetransport0>[00m 1 1 connecting
0:00:00.627581155 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:352:_find_ice_stream_for_session:<test>[00m Found ice stream id <webrtcicestream0> for session 0
0:00:00.627586324 [332m 1561[00m 0x7f62dc58f630 [33;01mLOG [00m [00m webrtcbin gstwebrtcbin.c:2993:_add_ice_candidate:<test>[00m adding ICE candidate with mline:0, a=candidate:2 1 udp 1862270975 45.11.62.243 62104 typ prflx raddr 192.168.0.32 rport 62104 generation 0
0:00:00.627624656 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:895:_collate_ice_gathering_states:<test>[00m ICE gathering state: 0x2
0:00:00.627629836 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:899:_collate_ice_gathering_states:<test>[00m returning gathering
0:00:00.627638753 [332m 1561[00m 0x7f62dc58f630 [36mINFO [00m [00m webrtcbin gstwebrtcbin.c:1053:_update_ice_gathering_state_task:<test>[00m ICE gathering state change from complete(2) to gathering(1)
0:00:00.627652488 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:895:_collate_ice_gathering_states:<test>[00m ICE gathering state: 0x2
0:00:00.627657047 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:899:_collate_ice_gathering_states:<test>[00m returning gathering
0:00:00.627662738 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:895:_collate_ice_gathering_states:<test>[00m ICE gathering state: 0x2
0:00:00.627665884 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:899:_collate_ice_gathering_states:<test>[00m returning gathering
0:00:00.627671053 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:895:_collate_ice_gathering_states:<test>[00m ICE gathering state: 0x2
0:00:00.627674129 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:899:_collate_ice_gathering_states:<test>[00m returning gathering
0:00:00.627679269 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:1 1 UDP 2013266431 fe80::9253:bfb5:e716:22ac 53933 typ host
0:00:00.629755476 [332m 1561[00m 0x7f62dc58f590 [37mDEBUG [00m [00m webrtcnicetransport nicetransport.c:186:_on_component_state_changed:<webrtcnicetransport2>[00m 2 1 gathering
0:00:00.629792546 [332m 1561[00m 0x7f62dc58f590 [37mDEBUG [00m [00m webrtcnicetransport nicetransport.c:186:_on_component_state_changed:<webrtcnicetransport3>[00m 2 2 gathering
candidate:1 1 UDP 2013266431 fe80::9253:bfb5:e716:22ac 53933 typ host
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:1 1 UDP 2013266431 fe80::9253:bfb5:e716:22ac 53933 typ host","usernameFragment":"PUPp3ooizmdRd+9CHLZGwg4KaxFRokB0","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.632683352 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:2 1 TCP 1015022847 fe80::9253:bfb5:e716:22ac 9 typ host tcptype active
candidate:2 1 TCP 1015022847 fe80::9253:bfb5:e716:22ac 9 typ host tcptype active
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:2 1 TCP 1015022847 fe80::9253:bfb5:e716:22ac 9 typ host tcptype active","usernameFragment":"PUPp3ooizmdRd+9CHLZGwg4KaxFRokB0","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.634461500 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:3 1 TCP 1010828543 fe80::9253:bfb5:e716:22ac 56035 typ host tcptype passive
candidate:3 1 TCP 1010828543 fe80::9253:bfb5:e716:22ac 56035 typ host tcptype passive
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:3 1 TCP 1010828543 fe80::9253:bfb5:e716:22ac 56035 typ host tcptype passive","usernameFragment":"","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.636669985 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:4 1 UDP 2013266430 192.168.0.32 44114 typ host
candidate:4 1 UDP 2013266430 192.168.0.32 44114 typ host
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:4 1 UDP 2013266430 192.168.0.32 44114 typ host","usernameFragment":"","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.637816798 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:5 1 TCP 1015022079 192.168.0.32 9 typ host tcptype active
candidate:5 1 TCP 1015022079 192.168.0.32 9 typ host tcptype active
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:5 1 TCP 1015022079 192.168.0.32 9 typ host tcptype active","usernameFragment":"","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.640899865 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:6 1 TCP 1010827775 192.168.0.32 40209 typ host tcptype passive
candidate:6 1 TCP 1010827775 192.168.0.32 40209 typ host tcptype passive
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:6 1 TCP 1010827775 192.168.0.32 40209 typ host tcptype passive","usernameFragment":"","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.642377509 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:1 2 UDP 2013266430 fe80::9253:bfb5:e716:22ac 45628 typ host
candidate:1 2 UDP 2013266430 fe80::9253:bfb5:e716:22ac 45628 typ host
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:1 2 UDP 2013266430 fe80::9253:bfb5:e716:22ac 45628 typ host","usernameFragment":"","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.644230978 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:2 2 TCP 1015022846 fe80::9253:bfb5:e716:22ac 9 typ host tcptype active
candidate:2 2 TCP 1015022846 fe80::9253:bfb5:e716:22ac 9 typ host tcptype active
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:2 2 TCP 1015022846 fe80::9253:bfb5:e716:22ac 9 typ host tcptype active","usernameFragment":"","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.645826573 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:3 2 TCP 1010828542 fe80::9253:bfb5:e716:22ac 59527 typ host tcptype passive
candidate:3 2 TCP 1010828542 fe80::9253:bfb5:e716:22ac 59527 typ host tcptype passive
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:3 2 TCP 1010828542 fe80::9253:bfb5:e716:22ac 59527 typ host tcptype passive","usernameFragment":"","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.646719029 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:4 2 UDP 2013266429 192.168.0.32 45728 typ host
candidate:4 2 UDP 2013266429 192.168.0.32 45728 typ host
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:4 2 UDP 2013266429 192.168.0.32 45728 typ host","usernameFragment":"","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.647582840 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:5 2 TCP 1015022078 192.168.0.32 9 typ host tcptype active
candidate:5 2 TCP 1015022078 192.168.0.32 9 typ host tcptype active
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:5 2 TCP 1015022078 192.168.0.32 9 typ host tcptype active","usernameFragment":"","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.648568049 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:6 2 TCP 1010827774 192.168.0.32 40207 typ host tcptype passive
candidate:6 2 TCP 1010827774 192.168.0.32 40207 typ host tcptype passive
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:6 2 TCP 1010827774 192.168.0.32 40207 typ host tcptype passive","usernameFragment":"","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.649663786 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:800:_collate_ice_connection_states:<test>[00m ICE connection state: 0x3
0:00:00.649677742 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:820:_collate_ice_connection_states:<test>[00m returning checking
0:00:00.649687460 [332m 1561[00m 0x7f62dc58f630 [36mINFO [00m [00m webrtcbin gstwebrtcbin.c:1088:_update_ice_connection_state_task:<test>[00m ICE connection state change from new(0) to checking(1)
0:00:00.649715733 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:963:_collate_peer_connection_states:<test>[00m ICE connection state: 0x3. DTLS connection state: 0x1
0:00:00.649722717 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:984:_collate_peer_connection_states:<test>[00m returning connecting
0:00:00.649729479 [332m 1561[00m 0x7f62dc58f630 [36mINFO [00m [00m webrtcbin gstwebrtcbin.c:1123:_update_peer_connection_state_task:<test>[00m Peer connection state change from new(0) to connecting(1)
0:00:00.649744738 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:800:_collate_ice_connection_states:<test>[00m ICE connection state: 0x3
0:00:00.649749767 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:820:_collate_ice_connection_states:<test>[00m returning checking
0:00:00.649757632 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:963:_collate_peer_connection_states:<test>[00m ICE connection state: 0x3. DTLS connection state: 0x1
0:00:00.649762531 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:984:_collate_peer_connection_states:<test>[00m returning connecting
0:00:00.649768082 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:800:_collate_ice_connection_states:<test>[00m ICE connection state: 0x3
0:00:00.649773712 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:820:_collate_ice_connection_states:<test>[00m returning checking
0:00:00.649780174 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:963:_collate_peer_connection_states:<test>[00m ICE connection state: 0x3. DTLS connection state: 0x1
0:00:00.649784673 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:984:_collate_peer_connection_states:<test>[00m returning connecting
0:00:00.731716657 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:7 2 UDP 1677722110 45.11.62.243 45587 typ srflx raddr 192.168.0.32 rport 45587
candidate:7 2 UDP 1677722110 45.11.62.243 45587 typ srflx raddr 192.168.0.32 rport 45587
{"data":{"candidate":{"sdpMLineIndex":0,"candidate":"candidate:7 2 UDP 1677722110 45.11.62.243 45587 typ srflx raddr 192.168.0.32 rport 45587","usernameFragment":"","sdpMid":"audio"}},"requestId":"test","handleCandidate":"test"}
0:00:00.733538997 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:7 1 UDP 1677722111 45.11.62.243 47137 typ srflx raddr 192.168.0.32 rport 47137
candidate:7 1 UDP 1677722111 45.11.62.243 47137 typ srflx raddr 192.168.0.32 rport 47137
{"data":{"candidate":{"sdpMLineIndex":0,"candidate":"candidate:7 1 UDP 1677722111 45.11.62.243 47137 typ srflx raddr 192.168.0.32 rport 47137","usernameFragment":"","sdpMid":"audio"}},"requestId":"test","handleCandidate":"test"}
0:00:00.735334027 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:8 2 TCP 847249918 45.11.62.243 9 typ srflx raddr 192.168.0.32 rport 9 tcptype active
candidate:8 2 TCP 847249918 45.11.62.243 9 typ srflx raddr 192.168.0.32 rport 9 tcptype active
{"data":{"candidate":{"sdpMLineIndex":0,"candidate":"candidate:8 2 TCP 847249918 45.11.62.243 9 typ srflx raddr 192.168.0.32 rport 9 tcptype active","usernameFragment":"","sdpMid":"audio"}},"requestId":"test","handleCandidate":"test"}
0:00:00.737444418 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:8 1 TCP 847249919 45.11.62.243 9 typ srflx raddr 192.168.0.32 rport 9 tcptype active
candidate:8 1 TCP 847249919 45.11.62.243 9 typ srflx raddr 192.168.0.32 rport 9 tcptype active
{"data":{"candidate":{"sdpMLineIndex":0,"candidate":"candidate:8 1 TCP 847249919 45.11.62.243 9 typ srflx raddr 192.168.0.32 rport 9 tcptype active","usernameFragment":"","sdpMid":"audio"}},"requestId":"test","handleCandidate":"test"}
0:00:00.740402270 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:9 2 TCP 843055614 45.11.62.243 35157 typ srflx raddr 192.168.0.32 rport 35157 tcptype passive
candidate:9 2 TCP 843055614 45.11.62.243 35157 typ srflx raddr 192.168.0.32 rport 35157 tcptype passive
{"data":{"candidate":{"sdpMLineIndex":0,"candidate":"candidate:9 2 TCP 843055614 45.11.62.243 35157 typ srflx raddr 192.168.0.32 rport 35157 tcptype passive","usernameFragment":"","sdpMid":"audio"}},"requestId":"test","handleCandidate":"test"}
0:00:00.742350266 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:0 and candidate:9 1 TCP 843055615 45.11.62.243 48803 typ srflx raddr 192.168.0.32 rport 48803 tcptype passive
candidate:9 1 TCP 843055615 45.11.62.243 48803 typ srflx raddr 192.168.0.32 rport 48803 tcptype passive
{"data":{"candidate":{"sdpMLineIndex":0,"candidate":"candidate:9 1 TCP 843055615 45.11.62.243 48803 typ srflx raddr 192.168.0.32 rport 48803 tcptype passive","usernameFragment":"","sdpMid":"audio"}},"requestId":"test","handleCandidate":"test"}
0:00:00.777482042 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:7 1 UDP 1677722111 45.11.62.243 44114 typ srflx raddr 192.168.0.32 rport 44114
candidate:7 1 UDP 1677722111 45.11.62.243 44114 typ srflx raddr 192.168.0.32 rport 44114
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:7 1 UDP 1677722111 45.11.62.243 44114 typ srflx raddr 192.168.0.32 rport 44114","usernameFragment":"","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.779474642 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:8 1 TCP 847249919 45.11.62.243 9 typ srflx raddr 192.168.0.32 rport 9 tcptype active
candidate:8 1 TCP 847249919 45.11.62.243 9 typ srflx raddr 192.168.0.32 rport 9 tcptype active
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:8 1 TCP 847249919 45.11.62.243 9 typ srflx raddr 192.168.0.32 rport 9 tcptype active","usernameFragment":"","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.780984877 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:9 1 TCP 843055615 45.11.62.243 40209 typ srflx raddr 192.168.0.32 rport 40209 tcptype passive
candidate:9 1 TCP 843055615 45.11.62.243 40209 typ srflx raddr 192.168.0.32 rport 40209 tcptype passive
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:9 1 TCP 843055615 45.11.62.243 40209 typ srflx raddr 192.168.0.32 rport 40209 tcptype passive","usernameFragment":"","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.785598207 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:7 2 UDP 1677722110 45.11.62.243 45728 typ srflx raddr 192.168.0.32 rport 45728
candidate:7 2 UDP 1677722110 45.11.62.243 45728 typ srflx raddr 192.168.0.32 rport 45728
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:7 2 UDP 1677722110 45.11.62.243 45728 typ srflx raddr 192.168.0.32 rport 45728","usernameFragment":"","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.787151743 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:8 2 TCP 847249918 45.11.62.243 9 typ srflx raddr 192.168.0.32 rport 9 tcptype active
candidate:8 2 TCP 847249918 45.11.62.243 9 typ srflx raddr 192.168.0.32 rport 9 tcptype active
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:8 2 TCP 847249918 45.11.62.243 9 typ srflx raddr 192.168.0.32 rport 9 tcptype active","usernameFragment":"","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
0:00:00.788901979 [332m 1561[00m 0x7f62dc58f630 [37mTRACE [00m [00m webrtcbin gstwebrtcbin.c:3961:_on_ice_candidate_task:<test>[00m produced ICE candidate for mline:1 and candidate:9 2 TCP 843055614 45.11.62.243 40207 typ srflx raddr 192.168.0.32 rport 40207 tcptype passive
candidate:9 2 TCP 843055614 45.11.62.243 40207 typ srflx raddr 192.168.0.32 rport 40207 tcptype passive
{"data":{"candidate":{"sdpMLineIndex":1,"candidate":"candidate:9 2 TCP 843055614 45.11.62.243 40207 typ srflx raddr 192.168.0.32 rport 40207 tcptype passive","usernameFragment":"","sdpMid":"video"}},"requestId":"test","handleCandidate":"test"}
GStreamer and all plugins version 1.16.2 (Builded from source) OS: Ubuntu 18.04
Is this a bug or am I doing something wrong?
Thank You for help.