Two IceTransport sessions created when making a call
The problem seems to be due to the fact that the JamiAccount::requestSIPConnection
function is called with the forceNewConnection
argument set to true
when a new call is made. Because of the change made to the JamiAccount::requestMessageConnection
function in commit d2e12fc1 (https://review.jami.net/c/jami-daemon/+/29605), this results in ConnectionManager::connectDevice
being called twice with forceNewSocket
set to true
and therefore to two ICE sessions being started.
[1732739435.231|44549|manager.cpp :1093] try outgoing call to 'ring:75fbce89291349022c6dd881c8d19034ac4976e4' with account '28b1e06a1c38e847'
[1732739435.231|44549|sipcall.cpp :144 ] [call:6426735365005180] Create a new [OUTGOING] SIP call with 2 media
[1732739435.231|44549|audio_rtp_session.cpp :56 ] Created Audio RTP session: 0x63bc14c5a6c0 - stream id 6426735365005180_audio_0
[1732739435.231|44549|ringbuffer.cpp :47 ] Create new RingBuffer 6426735365005180_audio_0
[1732739435.231|44549|sipcall.cpp :2016] [call:6426735365005180] Added media @0: type [AUDIO] enabled [YES] muted [NO] label [audio_0] source [camera://046d_HD_Pro_Webcam_C920_A144323F] secure [YES]
[1732739435.231|44549|video_rtp_session.cpp :72 ] [0x63bc1572a5a0] Video RTP session created for call 6426735365005180 (recorder 0x71a7c42da2f0)
[1732739435.231|44549|sipcall.cpp :2016] [call:6426735365005180] Added media @1: type [VIDEO] enabled [YES] muted [NO] label [video_0] source [camera://046d_HD_Pro_Webcam_C920_A144323F] secure [YES]
[1732739435.231|44549|sipcall.cpp :2022] [call:6426735365005180] Created 2 Media streams
[...]
[1732739435.236|44594] [ice:0x71a7ec3adab0] Creating IceTransport session for ""
[1732739435.236|44594] [ice:0x71a7ec3adab0] Initializing the session - comp count 1 - as a slave
[1732739435.236|44594] Desired port is not set, will provide the first available port for [TCP]
[1732739435.237|44594] [1732739435.237|44572] Successfully registered controller 0x71a7ec393160
Request for mapping JAMI-TCP:10300 failed, no IGD available
[1732739435.237|44594] [ice:0x71a7ec3adab0] UPNP mapping failed: JAMI-TCP:10300 (state=FAILED, auto-update=NO)
[1732739435.237|44594] Unregistered mapping JAMI-TCP:10300
[1732739435.237|44594] [ice:0x71a7ec3adab0] UPNP mapping failed: expected 1 mappings, got 0
[1732739435.237|44594] [ice:0x71a7ec3adab0] Add srflx reflexive candidates [192.168.50.147:9 : 208.88.110.46:9] for comp 1
[1732739435.237|44594] added turn server '51.222.138.120', port 3478
[1732739435.238|44594] Store DHT public IPv4 address: 208.88.110.46
[1732739435.238|44594] Store DHT public IPv6 address: [2607:fad8:4:6:ff34:6c15:1ea5:4105]
[1732739435.238|44594] [ice:0x71a7ec438c20] Creating IceTransport session for ""
[1732739435.238|44594] [ice:0x71a7ec438c20] Initializing the session - comp count 1 - as a slave
[1732739435.238|44594] Desired port is not set, will provide the first available port for [TCP]
[1732739435.238|44594] Request for mapping JAMI-TCP:10347 failed, no IGD available
[1732739435.238|44594] [1732739435.238|44572] Successfully registered controller 0x71a7ec0419e0
[ice:0x71a7ec438c20] UPNP mapping failed: JAMI-TCP:10347 (state=FAILED, auto-update=NO)
[1732739435.238|44594] Unregistered mapping JAMI-TCP:10347
[1732739435.238|44594] [ice:0x71a7ec438c20] UPNP mapping failed: expected 1 mappings, got 0
[1732739435.238|44594] [ice:0x71a7ec438c20] Add srflx reflexive candidates [192.168.50.147:9 : 208.88.110.46:9] for comp 1
[1732739435.238|44594] added turn server '51.222.138.120', port 3478
[1732739435.249|44754] [ice:0x71a7ec1bd2e0] UDP initialization success
[1732739435.249|44754] [ice:0x71a7ec1bd2e0] as master
[1732739435.249|44754] [ice:0x71a7ec1bd2e0] (local) ufrag=3e2d5de5, pwd=030eff86258457a85cb35b14
[1732739435.256|44755] [ice:0x71a7ec3adab0] TCP initialization success
[1732739435.256|44755] [ice:0x71a7ec3adab0] as slave
[1732739435.256|44755] [ice:0x71a7ec3adab0] (local) ufrag=656144de, pwd=5b6181ec74413e7008257677
[1732739435.256|44589] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Added local ICE candidate Hc0a83293 1 TCP 2130706431 192.168.50.147 44653 typ host tcptype passive
[1732739435.256|44589] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Added local ICE candidate Hc0a83293 1 TCP 2130706175 192.168.50.147 9 typ host tcptype active
[1732739435.256|44589] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Added local ICE candidate He2012a46 1 TCP 2130706431 2607:fad8:4:6:ff34:6c15:1ea5:4105 47313 typ host tcptype passive
[1732739435.256|44589] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Added local ICE candidate He2012a46 1 TCP 2130706175 2607:fad8:4:6:ff34:6c15:1ea5:4105 9 typ host tcptype active
[1732739435.256|44589] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Added local ICE candidate H7f5e2697 1 TCP 2130705919 2607:fad8:4:6:c3cb:a6d4:d5e9:db0d 47313 typ host tcptype passive
[1732739435.256|44589] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Added local ICE candidate H7f5e2697 1 TCP 2130705663 2607:fad8:4:6:c3cb:a6d4:d5e9:db0d 9 typ host tcptype active
[1732739435.256|44589] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Added local ICE candidate Sc0a83293 1 TCP 1694498303 208.88.110.46 9 typ srflx tcptype active
[1732739435.256|44589] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Added local ICE candidate R33de8a78 1 TCP 16777215 51.222.138.120 27297 typ relay tcptype passive
[1732739435.256|44589] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Sending connection request
[1732739435.257|44756] [ice:0x71a7ec438c20] TCP initialization success
[1732739435.257|44756] [ice:0x71a7ec438c20] as slave
[1732739435.257|44756] [ice:0x71a7ec438c20] (local) ufrag=260b48d0, pwd=23cfcffe48787e182c30bff6
[1732739435.257|44742] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Added local ICE candidate Hc0a83293 1 TCP 2130706431 192.168.50.147 40943 typ host tcptype passive
[1732739435.257|44742] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Added local ICE candidate Hc0a83293 1 TCP 2130706175 192.168.50.147 9 typ host tcptype active
[1732739435.257|44742] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Added local ICE candidate He2012a46 1 TCP 2130706431 2607:fad8:4:6:ff34:6c15:1ea5:4105 42209 typ host tcptype passive
[1732739435.257|44742] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Added local ICE candidate He2012a46 1 TCP 2130706175 2607:fad8:4:6:ff34:6c15:1ea5:4105 9 typ host tcptype active
[1732739435.257|44742] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Added local ICE candidate H7f5e2697 1 TCP 2130705919 2607:fad8:4:6:c3cb:a6d4:d5e9:db0d 42209 typ host tcptype passive
[1732739435.257|44742] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Added local ICE candidate H7f5e2697 1 TCP 2130705663 2607:fad8:4:6:c3cb:a6d4:d5e9:db0d 9 typ host tcptype active
[1732739435.257|44742] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Added local ICE candidate Sc0a83293 1 TCP 1694498303 208.88.110.46 9 typ srflx tcptype active
[1732739435.257|44742] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Added local ICE candidate R33de8a78 1 TCP 16777215 51.222.138.120 21121 typ relay tcptype passive
[1732739435.257|44742] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Sending connection request
[1732739435.434|44594] [device 99c0fdff435f3f45e665d15c13522f58ec0635d42a63842cf12b19612d7a1bae] Sent connection request. Put encrypted ok