Connecting issue: call Windows 10 -> Android if the Windows 10 user does not wait >30 sec before he makes the first call & Android <-> Android
both peers just enabled TURN (no other options like STUN) and use different Jami usernames ("username1" and "username2"; Both: Swarm: Is true)
(NOTE: Turning on/off another option (STUN, Open-DHT, UPnP) did not change anything)
- same issue if you use "sameusername1" -> "sameusername1" and if you are using the same network (Wifi)
- if you disable turn (if you use the same Wifi hotspot) same issue
- I never (?) reproduced any connectivity issue if both peers wait > 30 seconds (after you see the green dot of the contact in the contact list) before you make a call Windows 10 -> Android
Using the newest beta Jami (both; Android and Windows 10 client; beta version 202207191300)
-
Android -> Android is described here: #776 (comment 37101) and #776 (comment 37118)
-
Steps to reproduce: Windows 10 -> Android
Alice: Android (EMUI 12) 20220615-01
Bob: Windows 10: Jami (beta-)Version 202206231612
Issues: These issues happen randomly (sometimes it is possible to make a call even if you follow these steps)
Step 1: EDIT: It seems that now the first time calls are working; but once Bob exit Jami and opens Jami again Bob gets this connectivity issue.
- Alice alreads opened the Jami (Android) app so it is running
- Bob opens the Jami (Windows 10) app
- Bob makes a video call (Windows 10 -> Android) immediately after he sees the green dot of Alice in the contact list (jami-project#1388 (comment 34674))
- Bob just sees "Connecting"
(After this issue even Alice can't call Bob (anymore); she needs to proceed to step 2) (It also won't change anything if just Bob exits Windows 10 and tries to make a video call again - even if he waits over 30 seconds after he sees the green dot of Alice; EDIT: The green dot of Alice seems to be gone after a few minutes)
Step 2:
- Now Alice exits the Jami (Android) app (NOT Bob, he keeps Jami (windows 10) running)
- Alice opens the Jami (Android) app
- As soon as Alice gets the green dot of Bob, BOB makes a video call (Windows 10 -> Android) which is now working
Even if the call is successful
next possible issues:
option a:
Now just Bob exits Jami (Windows 10) but NOT Alice (she keeps Jami (Android) running)
Sometimes (the last time) Bob can make a call but another times you get the same issue starting from step 1
option b:
- Just Alice exits Jami (Android App; Bob does NOT exit)
- Now Alice opens Jami again
- Bob tries to make a call (Windows 10 -> Android) but just views "Connecting"
- Bob cancles the call (otherwise it will stuck "Connecting"; or sometimes the call will just fail) [Does not change anything if Bob repeats 3 and 4 ]
- Bob exits Jami (but NOT Alice)
- Bob tries to make a call (Windows 10 -> Android) but just views "Connecting"
- Bob cancles the call (otherwise it will stuck "Connecting"; or sometimes the call will just fail)
- Now neither Alice can call Bob nor Bob can call Allice, even sending text messages won't work (both directions; Windows 10 <-> Android)
Now Alice starts from step 2 (again)
Suddenly everything will work (again)
Issue when it comes to the Windows 10 (Bob) client Windows 10 shows just "searching", sometimes also "Conneting" and will fail after some time
Generally (?) it is possible to make calls Windows 10 -> Android if you wait for at least 30 seconds (jami-project#1388 (comment 34349))
However I am able to make calls Android -> Windows 10 (jami-project#1388 (comment 36389))
- Make a video call Android -> Windows 10
- Now the Windows 10 user answers the video call
- Now the Windows 10 user exits the video call
- Now the Windows 10 user can make a video call [Windows 10 -> Android]
After these steps I can make calls Windows 10 -> Android without any problem
Designs
- Show closed items
Activity
-
Newest first Oldest first
-
Show all activity Show comments only Show history only
Seems an issue because I once blocked ? the contact?
None of the contact is blocked anymore.
If I sent a text message Windows 10 -> Android the Android user won't get the text message.
However - if I sent a text message Android -> Windows 10 I suddenly get an invitation (only Windows 10)
Next steps:
Windows 10: "username1" Android: "username2"
- I got the invitation and see the same contact in the contact list.
- Now the Windows 10 user exits Jami (NOT the Android user)
- Now the Windows 10 user start Jami again
- Suddenly the Windows 10 user can see the "username2" twice in the contact list (both Swarm: Is true).
Now the Windows 10 user can make a video call.
Screenshot:
@atraczyk @sblin I think its actually an issue left of jami-project#1449 (comment 36195) .
Although all contacts are unblocked Jami seems to randomly remember the contact to be blocked. Only if you get twice "username2" you never have any issues.
Edited by Elys- Elys mentioned in issue jami-project#1388 (closed)
mentioned in issue jami-project#1388 (closed)
- Elys mentioned in issue jami-project#1449 (closed)
mentioned in issue jami-project#1449 (closed)
- Elys changed title from [Windows 10] Call Windows 10 -> Android not possible (anymore) to Connecting issue: call Windows 10 -> Android
changed title from [Windows 10] Call Windows 10 -> Android not possible (anymore) to Connecting issue: call Windows 10 -> Android
- Elys changed the description
Compare with previous version changed the description
- Elys mentioned in issue jami-project#1456 (closed)
mentioned in issue jami-project#1456 (closed)
I was able to reproduce the next issue:
- Bob and Alice all open Jami at the same time
- As soon as both see the green dot Bob makes a video call
- Bob just views "Connecting"
- After a few seconds (Bob still sees "Connecting") Alice taps Bob (in the contact list)
- Alice won't get the chatview of Bob - just a grey frame
- Now Bob stops the attempt to make a video call (he only gets "Connecting")
- Alice taps another contact (example Peter) and views the chatview
- Now Alice returns
- Now Alice taps Bob and can see the chatview again
Edited by Elys- Elys mentioned in issue jami-client-android#999
mentioned in issue jami-client-android#999
I just wanted to add the following scenario:
- Alice alreads opened the Jami (Android) app so it is running
- Bob opens the Jami (Windows 10) app
- Bob makes a video call (Windows 10 -> Android) but after he waits more than 30 seconds
- No connectivity issues at all
Everything works fine
However - as soon as Bob exits Jami (NOT Alice) and opens Jami (Windows 10 client) again but immediately makes a video call after he sees the green dot of Alice he will get the issue mentioned above again.
So Bob has to exit Jami again, open Jami again, wait more than 30 seconds ( or even longer) and suddenly everything works fine again.
Edited by Elys- Elys changed the description
Compare with previous version changed the description
Here are the logs from Windows 10 of the failed call
reproduced using step 1 of this issue mentioned in the description
(This is the same issue as jami-client-android#999 (comment 36663) )
For privacy reasons
- I replaced my identifier with abc12345
- and account was changed to Acc123
- A new account/contact I never knew was changed to bde345
[1659793162.642|12640|jamiaccount.cpp :3843 ] [Account Acc123] Cache refreshed for TURN resolution [1659793162.766| 7656|fileutils.cpp :164 ] Path expansion not implemented, returning original [1659793166.336|14760|jamiaccount.cpp :3885 ] [Account Acc123] Request SIP connection to peer abc12345 on device 969394fb5e33f738db2b1d825f0cbf3cd7066a25cd2c068cd662ed8c676273a8 [1659793166.338|14760|jamiaccount.cpp :3912 ] [Account Acc123] Ask 969394fb5e33f738db2b1d825f0cbf3cd7066a25cd2c068cd662ed8c676273a8 for a new SIP channel [1659793166.339|14760|jamiaccount.cpp :1871 ] Buddy abc12345 online [1659793166.339|17932|jamiaccount.cpp :3445 ] [Account Acc123] Store DHT public IPv4 address : 77.119.105.240 [1659793166.345|17932|ice_transport.cpp :332 ] [ice:00000159645009C0] Creating IceTransport session for "Acc123" [1659793166.346|17932|ice_transport.cpp :408 ] [ice:00000159645009C0] Initializing the session - comp count 1 - as a slave [1659793166.347|17932|ice_transport.cpp :446 ] [ice:00000159645009C0] Add host candidates [1659793166.348|17932|ice_transport.cpp :910 ] [ice:00000159645009C0] added host stun config for TCP transport [1659793166.349|17932|ice_transport.cpp :910 ] [ice:00000159645009C0] added host stun config for TCP transport [1659793166.354|17932|ice_transport.cpp :910 ] [ice:00000159645009C0] added host stun config for TCP transport [1659793166.357|17932|ice_transport.cpp :995 ] [ice:00000159645009C0] Add srflx reflexive candidates [192.168.1.2:9 : 77.119.105.240:9] for comp 1 [1659793166.360|17932|ice_transport.cpp :468 ] [ice:00000159645009C0] Added generic srflx candidates: [1659793166.361|17932|ice_transport.cpp :322 ] [ice (TCP)] added turn server '51.254.39.157', port 3478 [1659793166.404|17932|jamiaccount.cpp :2047 ] [Account Acc123] Dht status: IPv4 connected; IPv6 connecting [1659793166.489| 464|ice_transport.cpp :710 ] [ice:00000159645009C0] TCP initialization success [1659793166.492| 464|ice_transport.cpp :788 ] [ice:00000159645009C0] as slave [1659793166.495| 464|ice_transport.cpp :883 ] [ice:00000159645009C0] (local) ufrag=16496df1, pwd=5af141bb26e901eb0bb32ea6 [1659793166.498| 2092|connectionmanager.cpp:305 ] Added local ICE candidate Hc0a80102 1 TCP 2130706431 192.168.1.2 50108 typ host tcptype passive [1659793166.502| 2092|connectionmanager.cpp:305 ] Added local ICE candidate Hc0a80102 1 TCP 2130706175 192.168.1.2 9 typ host tcptype active [1659793166.510| 2092|connectionmanager.cpp:305 ] Added local ICE candidate Hc0a88901 1 TCP 2130705919 192.168.137.1 50108 typ host tcptype passive [1659793166.516| 2092|connectionmanager.cpp:305 ] Added local ICE candidate Hc0a88901 1 TCP 2130705663 192.168.137.1 9 typ host tcptype active [1659793166.518| 2092|connectionmanager.cpp:305 ] Added local ICE candidate Hb9cb0f80 1 TCP 2130706431 fe80::e97d:ff72:a0f3:3226 50109 typ host tcptype passive [1659793166.522| 2092|connectionmanager.cpp:305 ] Added local ICE candidate Hb9cb0f80 1 TCP 2130706175 fe80::e97d:ff72:a0f3:3226 9 typ host tcptype active [1659793166.530| 2092|connectionmanager.cpp:305 ] Added local ICE candidate Sc0a80102 1 TCP 1694498303 77.119.105.240 9 typ srflx tcptype active [1659793166.533| 2092|connectionmanager.cpp:305 ] Added local ICE candidate R33fe279d 1 TCP 16777215 51.254.39.157 16329 typ relay tcptype passive [1659793166.536| 2092|connectionmanager.cpp:319 ] [Account Acc123] Request connection to 969394fb5e33f738db2b1d825f0cbf3cd7066a25cd2c068cd662ed8c676273a8 [1659793167.759|17932|account_manager.cpp:205 ] announcing device at abc12345 [1659793167.762|17932|archive_account_manager.cpp:525 ] Building device sync from a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793167.766|17932|archive_account_manager.cpp:535 ] sending device sync to Samsung SM-G955F 0041bb4a94ff233334f4f37ae5c987aead8dbb1d53901f3dccb7ec0629592a24 [1659793167.771|17932|archive_account_manager.cpp:535 ] sending device sync to HUAWEI LYA-L29 969394fb5e33f738db2b1d825f0cbf3cd7066a25cd2c068cd662ed8c676273a8 [1659793167.782|14760|jamiaccount.cpp :3885 ] [Account Acc123] Request SIP connection to peer abc12345 on device a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793167.784|17932|jamiaccount.cpp :3885 ] [Account Acc123] Request SIP connection to peer abc12345 on device 969394fb5e33f738db2b1d825f0cbf3cd7066a25cd2c068cd662ed8c676273a8 [1659793167.792|14760|jamiaccount.cpp :3912 ] [Account Acc123] Ask a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 for a new SIP channel [1659793167.798|17932|jamiaccount.cpp :3907 ] [1659793167.798|14760|connectionmanager.cpp:498 ] [Account Acc123] Already connecting to 969394fb5e33f738db2b1d825f0cbf3cd7066a25cd2c068cd662ed8c676273a8 Already connecting to 969394fb5e33f738db2b1d825f0cbf3cd7066a25cd2c068cd662ed8c676273a8, wait for the ICE negotiation [1659793169.867| 7656|manager.cpp :1013 ] try outgoing call to 'ring:abc12345' with account 'Acc123' [1659793169.869| 7656|jamiaccount.cpp :422 ] [Account Acc123] Calling peer abc12345 [1659793169.870| 7656|sipcall.cpp :144 ] [call:8122542413983723] Create a new [OUTGOING] SIP call with 2 media [1659793169.870| 7656|audio_rtp_session.cpp:56 ] Created Audio RTP session: 0000015964541480 - call Id 8122542413983723 [1659793169.871| 7656|ringbuffer.cpp :55 ] Create new RingBuffer 8122542413983723 [1659793169.872| 7656|sipcall.cpp :1930 ] [call:8122542413983723] Added media @0: type [AUDIO] enabled [YES] muted [NO] label [audio_0] source [camera://video=@device_pnp_\\?\root#image#0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\global] secure [YES] [1659793169.873| 7656|video_rtp_session.cpp:72 ] [00000159643F1840] Video RTP session created for call 8122542413983723 [1659793169.874| 7656|sipcall.cpp :1930 ] [call:8122542413983723] Added media @1: type [VIDEO] enabled [YES] muted [NO] label [video_0] source [camera://video=@device_pnp_\\?\root#image#0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\global] secure [YES] [1659793169.875| 7656|sipcall.cpp :1933 ] [call:8122542413983723] Created 2 Media streams [1659793169.876| 7656|manager.cpp :604 ] [1659793169.876|17932|jamiaccount.cpp :3445 ] ----- Switch current call id to '8122542413983723' ----- [Account Acc123] Store DHT public IPv4 address : 77.119.105.240 [1659793169.882|17932|ice_transport.cpp :332 ] [ice:000001594891FD60] Creating IceTransport session for "8122542413983723" [1659793169.884|17932|sipcall.cpp :3136 ] [call:8122542413983723] Successfully created media ICE transport [ice:000001595E19A6E0] [1659793169.885|17932|sipcall.cpp :3296 ] [call:8122542413983723] Setting ICE session [000001595E19A6E0] [1659793169.886|17932|sipcall.cpp :3156 ] [call:8122542413983723] Init media ICE transport [1659793169.888|17932|ice_transport.cpp :408 ] [ice:000001594891FD60] Initializing the session - comp count 4 - as a master [1659793169.892|17932|ice_transport.cpp :446 ] [ice:000001594891FD60] Add host candidates [1659793169.894|17932|ice_transport.cpp :910 ] [ice:000001594891FD60] added host stun config for UDP transport [1659793169.895|17932|ice_transport.cpp :910 ] [ice:000001594891FD60] added host stun config for UDP transport [1659793169.896|17932|ice_transport.cpp :910 ] [ice:000001594891FD60] added host stun config for UDP transport [1659793169.897|17932|ice_transport.cpp :995 ] [ice:000001594891FD60] Add srflx reflexive candidates [192.168.1.2:22642 : 77.119.105.240:22642] for comp 1 [1659793169.898|17932|ice_transport.cpp :995 ] [ice:000001594891FD60] Add srflx reflexive candidates [192.168.1.2:20447 : 77.119.105.240:20447] for comp 2 [1659793169.899|17932|ice_transport.cpp :995 ] [ice:000001594891FD60] Add srflx reflexive candidates [192.168.1.2:22952 : 77.119.105.240:22952] for comp 3 [1659793169.900|17932|ice_transport.cpp :995 ] [ice:000001594891FD60] Add srflx reflexive candidates [192.168.1.2:23288 : 77.119.105.240:23288] for comp 4 [1659793169.901|17932|ice_transport.cpp :468 ] [ice:000001594891FD60] Added generic srflx candidates: [1659793169.902|17932|ice_transport.cpp :322 ] [ice (UDP)] added turn server '51.254.39.157', port 3478 [1659793169.962|17932|jamiaccount.cpp :461 ] [Account Acc123] Calling DHT peer abc12345 [1659793169.963|17932|contact_list.cpp :68 ] Can't set certificate status for existing contacts abc12345 [1659793169.965|17932|call.cpp :245 ] [call:8122542413983723] state change 0/0, cnx 0/1, code 0 [1659793169.965|17932|sipcall.cpp :144 ] [call:8877925821049849] Create a new [OUTGOING] SIP call with 2 media [1659793169.966|17932|audio_rtp_session.cpp:56 ] Created Audio RTP session: 0000015964347E70 - call Id 8877925821049849 [1659793169.967|17932|ringbuffer.cpp :55 ] Create new RingBuffer 8877925821049849 [1659793169.968|17932|sipcall.cpp :1930 ] [call:8877925821049849] Added media @0: type [AUDIO] enabled [YES] muted [NO] label [audio_0] source [camera://video=@device_pnp_\\?\root#image#0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\global] secure [YES] [1659793169.969|17932|video_rtp_session.cpp:72 ] [00000159648886F0] Video RTP session created for call 8877925821049849 [1659793169.970|17932|sipcall.cpp :1930 ] [call:8877925821049849] Added media @1: type [VIDEO] enabled [YES] muted [NO] label [video_0] source [camera://video=@device_pnp_\\?\root#image#0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\global] secure [YES] [1659793169.971|17932|sipcall.cpp :1933 ] [call:8877925821049849] Created 2 Media streams [1659793169.975|17932|call.cpp :451 ] [call:8122542413983723] add subcall 8877925821049849 [1659793169.977|17932|sipcall.cpp :3296 ] [call:8877925821049849] Setting ICE session [000001595E19A6E0] [1659793169.979|17932|sipcall.cpp :144 ] [call:8743223382304199] Create a new [OUTGOING] SIP call with 2 media [1659793169.980|17932|audio_rtp_session.cpp:56 ] Created Audio RTP session: 0000015964345110 - call Id 8743223382304199 [1659793169.980|17932|ringbuffer.cpp :55 ] Create new RingBuffer 8743223382304199 [1659793169.981|17932|sipcall.cpp :1930 ] [call:8743223382304199] Added media @0: type [AUDIO] enabled [YES] muted [NO] label [audio_0] source [camera://video=@device_pnp_\\?\root#image#0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\global] secure [YES] [1659793169.982|17932|video_rtp_session.cpp:72 ] [0000015964303870] Video RTP session created for call 8743223382304199 [1659793169.982|17932|sipcall.cpp :1930 ] [call:8743223382304199] Added media @1: type [VIDEO] enabled [YES] muted [NO] label [video_0] source [camera://video=@device_pnp_\\?\root#image#0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\global] secure [YES] [1659793169.984|17932|sipcall.cpp :1933 ] [call:8743223382304199] Created 2 Media streams [1659793169.985|17932|call.cpp :245 ] [call:8743223382304199] state change 0/0, cnx 0/1, code 0 [1659793169.985|17932|call.cpp :451 ] [call:8122542413983723] add subcall 8743223382304199 [1659793169.986|17932|sipcall.cpp :3296 ] [call:8743223382304199] Setting ICE session [000001595E19A6E0] [1659793169.986|17932|jamiaccount.cpp :580 ] [call 8122542413983723] No channeled socket with this peer. Send request [1659793169.987|17932|jamiaccount.cpp :3885 ] [Account Acc123] Request SIP connection to peer abc12345 on device a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793169.987|17932|jamiaccount.cpp :3912 ] [Account Acc123] Ask a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 for a new SIP channel [1659793169.995|17932|call.cpp :245 ] [1659793169.995|12640|call.cpp :245 ] [call:8743223382304199] state change 0/5, cnx 1/0, code 0 [call:8877925821049849] state change 0/5, cnx 0/0, code 6 [1659793169.996|14760|call.cpp :523 ] [1659793169.997|17932|sipcall.cpp :144 ] [call:8122542413983723] subcall 8743223382304199 failed [call:2237531036613514] Create a new [OUTGOING] SIP call with 2 media [1659793169.998|14760|call.cpp :546 ] [call:8122542413983723] remains 1 subcall(s) [1659793169.998|17932|audio_rtp_session.cpp:56 ] Created Audio RTP session: 00000159643458A0 - call Id 2237531036613514 [1659793169.999|14760|manager.cpp :2026 ] [call:8743223382304199] Sub-call failed [1659793170.000|17932|ringbuffer.cpp :55 ] Create new RingBuffer 2237531036613514 [1659793170.000|14760|manager.cpp :1650 ] [call:8743223382304199] Remove local audio [1659793170.000|17932|sipcall.cpp :1930 ] [call:2237531036613514] Added media @0: type [AUDIO] enabled [YES] muted [NO] label [audio_0] source [camera://video=@device_pnp_\\?\root#image#0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\global] secure [YES] [1659793170.001|14760|ringbufferpool.cpp:262 ] Unbind call 8743223382304199 from all bound calls [1659793170.002|17932|video_rtp_session.cpp:72 ] [00000159631FDB50] Video RTP session created for call 2237531036613514 [1659793170.002|14760|sipcall.cpp :1512 ] [call:8743223382304199] removeCall() [1659793170.003|17932|sipcall.cpp :1930 ] [call:2237531036613514] Added media @1: type [VIDEO] enabled [YES] muted [NO] label [video_0] source [camera://video=@device_pnp_\\?\root#image#0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\global] secure [YES] [1659793170.005|17932|sipcall.cpp :1933 ] [call:2237531036613514] Created 2 Media streams [1659793170.009|17932|call.cpp :245 ] [1659793170.009|14760|call_factory.cpp :72 ] [call:2237531036613514] state change 0/0, cnx 0/1, code 0 Removing call 8743223382304199 [1659793170.011|17932|call.cpp :451 ] [call:8122542413983723] add subcall 2237531036613514 [1659793170.012|14760|call_factory.cpp :75 ] Remaining 3 call [1659793170.012|17932|sipcall.cpp :3296 ] [call:2237531036613514] Setting ICE session [000001595E19A6E0] [1659793170.013|14760|call.cpp :245 ] [call:8743223382304199] state change 5/6, cnx 0/0, code 0 [1659793170.013|17932|jamiaccount.cpp :580 ] [call 8122542413983723] No channeled socket with this peer. Send request [1659793170.014|14760|audio_rtp_session.cpp:218 ] [0000015964345110] Stopping receiver [1659793170.015|17932|jamiaccount.cpp :3885 ] [Account Acc123] Request SIP connection to peer abc12345 on device 969394fb5e33f738db2b1d825f0cbf3cd7066a25cd2c068cd662ed8c676273a8 [1659793170.015|14760|audio_rtp_session.cpp:65 ] Destroyed Audio RTP session: 0000015964345110 - call Id 8743223382304199 [1659793170.016|17932|jamiaccount.cpp :3912 ] [Account Acc123] Ask 969394fb5e33f738db2b1d825f0cbf3cd7066a25cd2c068cd662ed8c676273a8 for a new SIP channel [1659793170.017|14760|ringbuffer.cpp :60 ] Destroy RingBuffer 8743223382304199 [1659793170.018|14760|video_rtp_session.cpp:227 ] [0000015964303870] Stop video RTP sender: input [] - muted [NO] [1659793170.019|14760|video_rtp_session.cpp:292 ] [0000015964303870] Stopping receiver [1659793170.019|14760|video_rtp_session.cpp:78 ] [0000015964303870] Video RTP session destroyed [1659793170.020|14760|call.cpp :523 ] [call:8122542413983723] subcall 8877925821049849 failed [1659793170.020|14760|call.cpp :546 ] [1659793170.020|11340|ice_transport.cpp :710 ] [call:8122542413983723] remains 1 subcall(s) [ice:000001594891FD60] UDP initialization success [1659793170.027|14760|manager.cpp :2026 ] [1659793170.027|11340|ice_transport.cpp :770 ] [ice:000001594891FD60] as master [call:8877925821049849] Sub-call failed [1659793170.029|11340|ice_transport.cpp :883 ] [1659793170.029|14760|manager.cpp :1650 ] [ice:000001594891FD60] (local) ufrag=5af141bb, pwd=26e901eb0bb32ea612db153c [call:8877925821049849] Remove local audio [1659793170.031|14760|ringbufferpool.cpp:262 ] Unbind call 8877925821049849 from all bound calls [1659793170.032|14760|sipcall.cpp :1512 ] [call:8877925821049849] removeCall() [1659793170.032|14760|call_factory.cpp :72 ] Removing call 8877925821049849 [1659793170.032|14760|call_factory.cpp :75 ] Remaining 2 call [1659793170.033|14760|call.cpp :245 ] [call:8877925821049849] state change 5/6, cnx 0/0, code 0 [1659793170.034|17932|jamiaccount.cpp :3445 ] [Account Acc123] Store DHT public IPv4 address : 77.119.105.240 [1659793170.039|17932|ice_transport.cpp :332 ] [ice:000001596734B010] Creating IceTransport session for "Acc123" [1659793170.043|17932|ice_transport.cpp :408 ] [ice:000001596734B010] Initializing the session - comp count 1 - as a slave [1659793170.044|17932|ice_transport.cpp :446 ] [ice:000001596734B010] Add host candidates [1659793170.045|17932|ice_transport.cpp :910 ] [ice:000001596734B010] added host stun config for TCP transport [1659793170.046|17932|ice_transport.cpp :910 ] [ice:000001596734B010] added host stun config for TCP transport [1659793170.046|17932|ice_transport.cpp :910 ] [ice:000001596734B010] added host stun config for TCP transport [1659793170.047|17932|ice_transport.cpp :995 ] [ice:000001596734B010] Add srflx reflexive candidates [192.168.1.2:9 : 77.119.105.240:9] for comp 1 [1659793170.048|17932|ice_transport.cpp :468 ] [ice:000001596734B010] Added generic srflx candidates: [1659793170.049|17932|ice_transport.cpp :322 ] [ice (TCP)] added turn server '51.254.39.157', port 3478 [1659793170.156|14232|ice_transport.cpp :710 ] [ice:000001596734B010] TCP initialization success [1659793170.157|14232|ice_transport.cpp :788 ] [ice:000001596734B010] as slave [1659793170.157|14232|ice_transport.cpp :883 ] [ice:000001596734B010] (local) ufrag=69525f90, pwd=16496df15af141bb26e901eb [1659793170.159|12640|connectionmanager.cpp:305 ] Added local ICE candidate Hc0a80102 1 TCP 2130706431 192.168.1.2 50115 typ host tcptype passive [1659793170.160|12640|connectionmanager.cpp:305 ] Added local ICE candidate Hc0a80102 1 TCP 2130706175 192.168.1.2 9 typ host tcptype active [1659793170.162|12640|connectionmanager.cpp:305 ] Added local ICE candidate Hc0a88901 1 TCP 2130705919 192.168.137.1 50115 typ host tcptype passive [1659793170.163|12640|connectionmanager.cpp:305 ] Added local ICE candidate Hc0a88901 1 TCP 2130705663 192.168.137.1 9 typ host tcptype active [1659793170.164|12640|connectionmanager.cpp:305 ] Added local ICE candidate Hb9cb0f80 1 TCP 2130706431 fe80::e97d:ff72:a0f3:3226 50116 typ host tcptype passive [1659793170.165|12640|connectionmanager.cpp:305 ] Added local ICE candidate Hb9cb0f80 1 TCP 2130706175 fe80::e97d:ff72:a0f3:3226 9 typ host tcptype active [1659793170.166|12640|connectionmanager.cpp:305 ] Added local ICE candidate Sc0a80102 1 TCP 1694498303 77.119.105.240 9 typ srflx tcptype active [1659793170.167|12640|connectionmanager.cpp:305 ] Added local ICE candidate R33fe279d 1 TCP 16777215 51.254.39.157 24097 typ relay tcptype passive [1659793170.168|12640|connectionmanager.cpp:319 ] [Account Acc123] Request connection to 969394fb5e33f738db2b1d825f0cbf3cd7066a25cd2c068cd662ed8c676273a8 [1659793172.169| 1012|nat_pmp.cpp :78 ] NAT-PMP: Trying to initialize IGD [1659793172.171| 1012|nat_pmp.cpp :106 ] NAT-PMP: Initialized on gateway 192.168.1.1 [1659793172.424| 1012|nat_pmp.cpp :557 ] NAT-PMP: Read response on IGD 192.168.1.1 failed - RECVFROM [1659793172.425| 1012|nat_pmp.cpp :227 ] NAT-PMP: Start search for IGDs. Attempt 2 [1659793172.866| 8164|pupnp.cpp :366 ] PUPnP: Start search for IGD: attempt 2 [1659793172.867| 8164|pupnp.cpp :275 ] PUPnP: Send IGD search request [1659793174.896|17932|account_manager.cpp:669 ] Found 2 devices [1659793174.900|17932|account_manager.cpp:211 ] device announced at abc12345 [1659793192.449| 1012|nat_pmp.cpp :78 ] NAT-PMP: Trying to initialize IGD [1659793192.454| 1012|nat_pmp.cpp :106 ] NAT-PMP: Initialized on gateway 192.168.1.1 [1659793192.715| 1012|nat_pmp.cpp :557 ] NAT-PMP: Read response on IGD 192.168.1.1 failed - RECVFROM [1659793192.718| 1012|nat_pmp.cpp :227 ] NAT-PMP: Start search for IGDs. Attempt 3 [1659793193.725| 8164|pupnp.cpp :366 ] PUPnP: Start search for IGD: attempt 3 [1659793193.730| 8164|pupnp.cpp :275 ] PUPnP: Send IGD search request [1659793196.542| 2092|connectionmanager.cpp:336 ] no response from DHT to E2E request. [1659793196.552|14760|call.cpp :245 ] [1659793196.552| 2092|call.cpp :245 ] [call:2237531036613514] state change 0/5, cnx 1/0, code 0 [call:8877925821049849] state change 6/5, cnx 0/0, code 6 [1659793196.563|14760|ice_transport.cpp :337 ] [1659793196.564| 2092|call.cpp :252 ] [ice:00000159645009C0] destroying 00000159645B2618 [call:8877925821049849] invalid call state transition from 6 to 5 [1659793196.571| 2092|audio_rtp_session.cpp:218 ] [0000015964347E70] Stopping receiver [1659793196.574| 2092|audio_rtp_session.cpp:65 ] Destroyed Audio RTP session: 0000015964347E70 - call Id 8877925821049849 [1659793196.578| 2092|ringbuffer.cpp :60 ] Destroy RingBuffer 8877925821049849 [1659793196.580| 2092|video_rtp_session.cpp:227 ] [00000159648886F0] Stop video RTP sender: input [] - muted [NO] [1659793196.586| 2092|video_rtp_session.cpp:292 ] [00000159648886F0] Stopping receiver [1659793196.587| 2092|video_rtp_session.cpp:78 ] [00000159648886F0] Video RTP session destroyed [1659793197.004|14760|ice_transport.cpp :351 ] [ice:00000159645009C0] Destroying ice_strans 00000159645B2618 [1659793197.006|14760|ice_transport.cpp :669 ] [ice:00000159645009C0] Timer heap flushed after 0 ms [1659793197.007|14760|ice_transport.cpp :383 ] [ice:00000159645009C0] done destroying [1659793197.008|14760|call.cpp :523 ] [call:8122542413983723] subcall 2237531036613514 failed [1659793197.009|14760|call.cpp :245 ] [call:8122542413983723] state change 0/5, cnx 1/0, code 5 [1659793197.010|14760|call.cpp :276 ] [call:8122542413983723] emit client call state change FAILURE, code 5 [1659793197.011|14760|sipcall.cpp :1512 ] [call:8122542413983723] removeCall() [1659793197.012|14760|call_factory.cpp :72 ] Removing call 8122542413983723 [1659793197.013|14760|call_factory.cpp :75 ] Remaining 1 call [1659793197.018|14760|call.cpp :245 ] [call:8122542413983723] state change 5/6, cnx 0/0, code 0 [1659793197.020|14760|sipcall.cpp :3064 ] - Call 8122542413983723 with abc12345@ring.dht: [1659793197.025|14760|sipcall.cpp :3065 ] - Duration: 0 us [1659793197.026|14760|sipcall.cpp :3067 ] - Media: type [AUDIO] enabled [YES] muted [NO] label [audio_0] source [camera://video=@device_pnp_\\?\root#image#0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\global] secure [YES] [1659793197.029|14760|sipcall.cpp :3067 ] - Media: type [VIDEO] enabled [YES] muted [NO] label [video_0] source [camera://video=@device_pnp_\\?\root#image#0000#{65e8773d-8f56-11d0-a3b9-00a0c9223196}\global] secure [YES] [1659793197.031|14760|call.cpp :276 ] [call:8122542413983723] emit client call state change OVER, code 0 [1659793197.036|14760|manager.cpp :2026 ] [call:2237531036613514] Sub-call failed [1659793197.039|14760|manager.cpp :1650 ] [call:2237531036613514] Remove local audio [1659793197.040|14760|ringbufferpool.cpp:262 ] Unbind call 2237531036613514 from all bound calls [1659793197.041|14760|sipcall.cpp :1512 ] [call:2237531036613514] removeCall() [1659793197.042|14760|call_factory.cpp :72 ] Removing call 2237531036613514 [1659793197.042|14760|call_factory.cpp :75 ] Remaining 0 call [1659793197.043|14760|call.cpp :245 ] [call:2237531036613514] state change 5/6, cnx 0/0, code 0 [1659793197.044|14760|audio_rtp_session.cpp:218 ] [1659793197.044| 2092|ice_transport.cpp :337 ] [00000159643458A0] Stopping receiver [ice:000001594891FD60] destroying 000001596475C858 [1659793197.046|14760|audio_rtp_session.cpp:65 ] Destroyed Audio RTP session: 00000159643458A0 - call Id 2237531036613514 [1659793197.052|14760|ringbuffer.cpp :60 ] Destroy RingBuffer 2237531036613514 [1659793197.054|14760|video_rtp_session.cpp:227 ] [00000159631FDB50] Stop video RTP sender: input [] - muted [NO] [1659793197.056|14760|video_rtp_session.cpp:292 ] [00000159631FDB50] Stopping receiver [1659793197.056|14760|video_rtp_session.cpp:78 ] [00000159631FDB50] Video RTP session destroyed [1659793197.057|14760|audio_rtp_session.cpp:218 ] [0000015964541480] Stopping receiver [1659793197.058|14760|audio_rtp_session.cpp:65 ] Destroyed Audio RTP session: 0000015964541480 - call Id 8122542413983723 [1659793197.059| 7280|conversationrepository.cpp:1685 ] New message added with id: bde345 [1659793197.060|14760|ringbuffer.cpp :60 ] Destroy RingBuffer 8122542413983723 [1659793197.062|14760|video_rtp_session.cpp:227 ] [00000159643F1840] Stop video RTP sender: input [] - muted [NO] [1659793197.063|14760|video_rtp_session.cpp:292 ] [00000159643F1840] Stopping receiver [1659793197.064|14760|video_rtp_session.cpp:78 ] [00000159643F1840] Video RTP session destroyed [1659793197.069|14760|message_engine.cpp:103 ] [1659793197.070|19296|message_engine.cpp:324 ] [message 6930564025692243] Retry sending [Account Acc123] saved 1 messages to C:\Users\TESTUSER_NEU\.cache\jami\Acc123\messages [1659793197.073|17932|jamiaccount.cpp :3885 ] [Account Acc123] Request SIP connection to peer abc12345 on device 969394fb5e33f738db2b1d825f0cbf3cd7066a25cd2c068cd662ed8c676273a8 [1659793197.075|17932|jamiaccount.cpp :3912 ] [Account Acc123] Ask 969394fb5e33f738db2b1d825f0cbf3cd7066a25cd2c068cd662ed8c676273a8 for a new SIP channel [1659793197.077|17932|jamiaccount.cpp :3358 ] [Account Acc123] [message 6930564025692243] Sending message for device 969394fb5e33f738db2b1d825f0cbf3cd7066a25cd2c068cd662ed8c676273a8 [1659793197.079|17932|jamiaccount.cpp :3445 ] [Account Acc123] Store DHT public IPv4 address : 77.119.105.240 [1659793197.084|17932|ice_transport.cpp :332 ] [ice:00000159645009C0] Creating IceTransport session for "Acc123" [1659793197.087|17932|ice_transport.cpp :408 ] [ice:00000159645009C0] Initializing the session - comp count 1 - as a slave [1659793197.088|17932|ice_transport.cpp :446 ] [ice:00000159645009C0] Add host candidates [1659793197.089|17932|ice_transport.cpp :910 ] [ice:00000159645009C0] added host stun config for TCP transport [1659793197.090|17932|ice_transport.cpp :910 ] [ice:00000159645009C0] added host stun config for TCP transport [1659793197.090|17932|ice_transport.cpp :910 ] [ice:00000159645009C0] added host stun config for TCP transport [1659793197.091|17932|ice_transport.cpp :995 ] [ice:00000159645009C0] Add srflx reflexive candidates [192.168.1.2:9 : 77.119.105.240:9] for comp 1 [1659793197.093|17932|ice_transport.cpp :468 ] [ice:00000159645009C0] Added generic srflx candidates: [1659793197.093|17932|ice_transport.cpp :322 ] [ice (TCP)] added turn server '51.254.39.157', port 3478 [1659793197.120| 2092|ice_transport.cpp :351 ] [ice:000001594891FD60] Destroying ice_strans 000001596475C858 [1659793197.121| 2092|ice_transport.cpp :669 ] [ice:000001594891FD60] Timer heap flushed after 0 ms [1659793197.122| 2092|ice_transport.cpp :383 ] [ice:000001594891FD60] done destroying [1659793197.327| 7580|ice_transport.cpp :710 ] [ice:00000159645009C0] TCP initialization success [1659793197.328| 7580|ice_transport.cpp :788 ] [ice:00000159645009C0] as slave [1659793197.329| 7580|ice_transport.cpp :883 ] [ice:00000159645009C0] (local) ufrag=69525f90, pwd=16496df15af141bb26e901eb [1659793197.331| 7280|connectionmanager.cpp:305 ] Added local ICE candidate Hc0a80102 1 TCP 2130706431 192.168.1.2 50150 typ host tcptype passive [1659793197.333| 7280|connectionmanager.cpp:305 ] Added local ICE candidate Hc0a80102 1 TCP 2130706175 192.168.1.2 9 typ host tcptype active [1659793197.335| 7280|connectionmanager.cpp:305 ] Added local ICE candidate Hc0a88901 1 TCP 2130705919 192.168.137.1 50150 typ host tcptype passive [1659793197.336| 7280|connectionmanager.cpp:305 ] Added local ICE candidate Hc0a88901 1 TCP 2130705663 192.168.137.1 9 typ host tcptype active [1659793197.337| 7280|connectionmanager.cpp:305 ] Added local ICE candidate Hb9cb0f80 1 TCP 2130706431 fe80::e97d:ff72:a0f3:3226 50151 typ host tcptype passive [1659793197.340| 7280|connectionmanager.cpp:305 ] Added local ICE candidate Hb9cb0f80 1 TCP 2130706175 fe80::e97d:ff72:a0f3:3226 9 typ host tcptype active [1659793197.341| 7280|connectionmanager.cpp:305 ] Added local ICE candidate Sc0a80102 1 TCP 1694498303 77.119.105.240 9 typ srflx tcptype active [1659793197.343| 7280|connectionmanager.cpp:305 ] Added local ICE candidate R33fe279d 1 TCP 16777215 51.254.39.157 27525 typ relay tcptype passive [1659793197.344| 7280|connectionmanager.cpp:319 ] [Account Acc123] Request connection to 969394fb5e33f738db2b1d825f0cbf3cd7066a25cd2c068cd662ed8c676273a8 [1659793197.595|17932|jamiaccount.cpp :3311 ] [Account Acc123] [message 6930564025692243] Received text message reply [1659793197.598|17932|message_engine.cpp:154 ] [message 6930564025692243] Message sent: success [1659793197.599|17932|message_engine.cpp:168 ] [message 6930564025692243] Status changed to SENT [1659793197.600|18976|message_engine.cpp:324 ] [Account Acc123] saved 1 messages to C:\Users\TESTUSER_NEU\.cache\jami\Acc123\messages [1659793200.171|12640|connectionmanager.cpp:336 ] no response from DHT to E2E request. [1659793200.175|14760|ice_transport.cpp :337 ] [ice:000001596734B010] destroying 00000159673F6978 [1659793200.678|14760|ice_transport.cpp :351 ] [ice:000001596734B010] Destroying ice_strans 00000159673F6978 [1659793200.684|14760|ice_transport.cpp :669 ] [ice:000001596734B010] Timer heap flushed after 0 ms [1659793200.688|14760|ice_transport.cpp :383 ] [ice:000001596734B010] done destroying [1659793218.521|17932|jamiaccount.cpp :3340 ] [Account Acc123] [message 6930564025692243] Put encrypted ok [1659793222.743| 1012|nat_pmp.cpp :78 ] NAT-PMP: Trying to initialize IGD [1659793222.747| 1012|nat_pmp.cpp :106 ] NAT-PMP: Initialized on gateway 192.168.1.1 [1659793223.007| 1012|nat_pmp.cpp :557 ] NAT-PMP: Read response on IGD 192.168.1.1 failed - RECVFROM [1659793223.011| 1012|nat_pmp.cpp :238 ] NAT-PMP: Setup failed after 3 trials. NAT-PMP will be disabled! [1659793224.583| 8164|pupnp.cpp :362 ] PUPnP: Setup failed after 3 trials. PUPnP will be disabled! [1659793227.346| 7280|connectionmanager.cpp:336 ] no response from DHT to E2E request. [1659793227.350|14760|ice_transport.cpp :337 ] [ice:00000159645009C0] destroying 0000015964606B68 [1659793227.847|14760|ice_transport.cpp :351 ] [ice:00000159645009C0] Destroying ice_strans 0000015964606B68 [1659793227.854|14760|ice_transport.cpp :669 ] [ice:00000159645009C0] Timer heap flushed after 0 ms [1659793227.858|14760|ice_transport.cpp :383 ] [ice:00000159645009C0] done destroying [1659793247.586|17932|account_manager.cpp:669 ] Found 2 devices [1659793247.588|17932|message_engine.cpp:154 ] [message 6930564025692243] Message sent: success [1659793247.589|17932|message_engine.cpp:193 ] [message 6930564025692243] State is not SENDING
Edited by ElysNow here are the Android logs: (same call: Windows 10 -> Android)
(Remember: The Android user never received a call or a call notification)
The logs are received as soon as the Windows 10 user starts a call.
Again for privacy reasons I replaced some parts of the logs - as already mentioned above.
A contact identifier was replaced by "anothercontact"
[1659793157.454|10671|jamiaccount.cpp :1782 ] [Account Acc123] Untrack presence for oneothercontact [1659793167.178|10712|jamiaccount.cpp :3889 ] [Account Acc123] Request SIP connection to peer abc12345 on device a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793167.180|10712|jamiaccount.cpp :3916 ] [Account Acc123] Ask a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 for a new SIP channel [1659793167.181|10673|connectionmanager.cpp:498 ] Already connecting to a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9, wait for the ICE negotiation [1659793167.183|10712|jamiaccount.cpp :3449 ] [Account Acc123] Store DHT public IPv4 address : 77.119.105.240 [1659793167.185|10712|ice_transport.cpp :332 ] [ice:0x7c320f2e40] Creating IceTransport session for "Acc123" [1659793167.185|10712|ice_transport.cpp :408 ] [ice:0x7c320f2e40] Initializing the session - comp count 1 - as a slave [1659793167.185|10712|ice_transport.cpp :446 ] [ice:0x7c320f2e40] Add host candidates [1659793167.185|10712|ice_transport.cpp :910 ] [ice:0x7c320f2e40] added host stun config for TCP transport [1659793167.185|10712|ice_transport.cpp :910 ] [ice:0x7c320f2e40] added host stun config for TCP transport [1659793167.186|10712|ice_transport.cpp :910 ] [ice:0x7c320f2e40] added host stun config for TCP transport [1659793167.186|10712|ice_transport.cpp :995 ] [ice:0x7c320f2e40] Add srflx reflexive candidates [192.168.137.131:9 : 77.119.105.240:9] for comp 1 [1659793167.186|10712|ice_transport.cpp :468 ] [ice:0x7c320f2e40] Added generic srflx candidates: [1659793167.186|10712|ice_transport.cpp :322 ] [ice (TCP)] added turn server '51.254.39.157', port 3478 [1659793167.407|12077|ice_transport.cpp :710 ] [ice:0x7c320f2e40] TCP initialization success [1659793167.407|12077|ice_transport.cpp :788 ] [ice:0x7c320f2e40] as slave [1659793167.408|12077|ice_transport.cpp :883 ] [ice:0x7c320f2e40] (local) ufrag=3199b67e, pwd=2e17f9363121b68d17320c74 [1659793167.409|10719|connectionmanager.cpp:305 ] Added local ICE candidate Hc0a88983 1 TCP 2130706431 192.168.137.131 55765 typ host tcptype passive [1659793167.409|10719|connectionmanager.cpp:305 ] Added local ICE candidate Hc0a88983 1 TCP 2130706175 192.168.137.131 9 typ host tcptype active [1659793167.410|10719|connectionmanager.cpp:305 ] Added local ICE candidate Sc0a88983 1 TCP 1694498303 77.119.105.240 9 typ srflx tcptype active [1659793167.410|10719|connectionmanager.cpp:305 ] Added local ICE candidate R33fe279d 1 TCP 16777215 51.254.39.157 10478 typ relay tcptype passive [1659793167.411|10719|connectionmanager.cpp:319 ] [Account Acc123] Request connection to a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793172.360|10712|connectionmanager.cpp:683 ] Received request from a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793172.367|11119|account_manager.cpp:351 ] Found peer device: a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 account:abc12345 CA:fff171c166eec58efb5f1d7ff5dbfea6f59b98e0 [1659793172.368|11119|connectionmanager.cpp:895 ] [Account Acc123] New connection requested by a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793172.374|11119|account_manager.cpp:351 ] Found peer device: a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 account:abc12345 CA:fff171c166eec58efb5f1d7ff5dbfea6f59b98e0 [1659793172.380|11119|jamiaccount.cpp :2123 ] Accepting ICE request from account abc12345 [1659793172.410|10712|connectionmanager.cpp:683 ] Received request from a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793172.411|10712|jamiaccount.cpp :3449 ] [Account Acc123] Store DHT public IPv4 address : 77.119.105.240 [1659793172.412|10712|connectionmanager.cpp:966 ] [Account:Acc123] accepting connection from a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793172.412|10712|ice_transport.cpp :332 ] [ice:0x7c32107640] Creating IceTransport session for "Acc123" [1659793172.412|10712|ice_transport.cpp :408 ] [ice:0x7c32107640] Initializing the session - comp count 1 - as a master [1659793172.412|10712|ice_transport.cpp :446 ] [ice:0x7c32107640] Add host candidates [1659793172.412|10712|ice_transport.cpp :910 ] [ice:0x7c32107640] added host stun config for TCP transport [1659793172.412|10712|ice_transport.cpp :910 ] [ice:0x7c32107640] added host stun config for TCP transport [1659793172.413|10712|ice_transport.cpp :910 ] [ice:0x7c32107640] added host stun config for TCP transport [1659793172.413|10712|ice_transport.cpp :995 ] [ice:0x7c32107640] Add srflx reflexive candidates [192.168.137.131:9 : 77.119.105.240:9] for comp 1 [1659793172.413|10712|ice_transport.cpp :468 ] [ice:0x7c32107640] Added generic srflx candidates: [1659793172.413|10712|ice_transport.cpp :322 ] [ice (TCP)] added turn server '51.254.39.157', port 3478 [1659793172.416|11119|account_manager.cpp:351 ] Found peer device: a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 account:abc12345 CA:fff171c166eec58efb5f1d7ff5dbfea6f59b98e0 [1659793172.416|11119|connectionmanager.cpp:895 ] [Account Acc123] New connection requested by a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793172.425|11119|account_manager.cpp:351 ] Found peer device: a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 account:abc12345 CA:fff171c166eec58efb5f1d7ff5dbfea6f59b98e0 [1659793172.430|11119|jamiaccount.cpp :2123 ] Accepting ICE request from account abc12345 [1659793172.430|10712|jamiaccount.cpp :3449 ] [Account Acc123] Store DHT public IPv4 address : 77.119.105.240 [1659793172.431|10712|connectionmanager.cpp:966 ] [Account:Acc123] accepting connection from a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793172.431|10712|ice_transport.cpp :332 ] [ice:0x7c36d9b440] Creating IceTransport session for "Acc123" [1659793172.431|10712|ice_transport.cpp :408 ] [ice:0x7c36d9b440] Initializing the session - comp count 1 - as a master [1659793172.431|10712|ice_transport.cpp :446 ] [ice:0x7c36d9b440] Add host candidates [1659793172.431|10712|ice_transport.cpp :910 ] [ice:0x7c36d9b440] added host stun config for TCP transport [1659793172.431|10712|ice_transport.cpp :910 ] [ice:0x7c36d9b440] added host stun config for TCP transport [1659793172.431|10712|ice_transport.cpp :910 ] [ice:0x7c36d9b440] added host stun config for TCP transport [1659793172.431|10712|ice_transport.cpp :995 ] [ice:0x7c36d9b440] Add srflx reflexive candidates [192.168.137.131:9 : 77.119.105.240:9] for comp 1 [1659793172.431|10712|ice_transport.cpp :468 ] [ice:0x7c36d9b440] Added generic srflx candidates: [1659793172.431|10712|ice_transport.cpp :322 ] [ice (TCP)] added turn server '51.254.39.157', port 3478 [1659793172.579|12081|ice_transport.cpp :710 ] [ice:0x7c36d9b440] TCP initialization success [1659793172.579|12081|ice_transport.cpp :770 ] [ice:0x7c36d9b440] as master [1659793172.580|12081|ice_transport.cpp :883 ] [ice:0x7c36d9b440] (local) ufrag=39e66975, pwd=5a40bd0942f8233603c1fc8e [1659793172.581|10720|ice_transport.cpp :1805 ] [ice:0x7c36d9b440] Add remote candidate: Hc0a80102 1 TCP 2130706431 192.168.1.2 50115 typ host tcptype passive [1659793172.581|12080|ice_transport.cpp :710 ] [ice:0x7c32107640] TCP initialization success [1659793172.582|12080|ice_transport.cpp :770 ] [ice:0x7c32107640] as master [1659793172.582|10720|ice_transport.cpp :1805 ] [ice:0x7c36d9b440] Add remote candidate: Hc0a80102 1 TCP 2130706175 192.168.1.2 9 typ host tcptype active [1659793172.582|10720|ice_transport.cpp :1805 ] [ice:0x7c36d9b440] Add remote candidate: Hc0a88901 1 TCP 2130705919 192.168.137.1 50115 typ host tcptype passive [1659793172.582|12080|ice_transport.cpp :883 ] [ice:0x7c32107640] (local) ufrag=16f14cab, pwd=2e1e4cb74b6688cc5e0e4756 [1659793172.583|10720|ice_transport.cpp :1805 ] [ice:0x7c36d9b440] Add remote candidate: Hc0a88901 1 TCP 2130705663 192.168.137.1 9 typ host tcptype active [1659793172.583|10720|ice_transport.cpp :1805 ] [ice:0x7c36d9b440] Add remote candidate: Hb9cb0f80 1 TCP 2130706431 fe80::e97d:ff72:a0f3:3226 50116 typ host tcptype passive [1659793172.583|11119|ice_transport.cpp :1805 ] [ice:0x7c32107640] Add remote candidate: Hc0a80102 1 TCP 2130706431 192.168.1.2 50108 typ host tcptype passive [1659793172.584|10720|ice_transport.cpp :1805 ] [ice:0x7c36d9b440] Add remote candidate: Hb9cb0f80 1 TCP 2130706175 fe80::e97d:ff72:a0f3:3226 9 typ host tcptype active [1659793172.584|11119|ice_transport.cpp :1805 ] [ice:0x7c32107640] Add remote candidate: Hc0a80102 1 TCP 2130706175 192.168.1.2 9 typ host tcptype active [1659793172.586|10720|ice_transport.cpp :1805 ] [ice:0x7c36d9b440] Add remote candidate: Sc0a80102 1 TCP 1694498303 77.119.105.240 9 typ srflx tcptype active [1659793172.586|11119|ice_transport.cpp :1805 ] [ice:0x7c32107640] Add remote candidate: Hc0a88901 1 TCP 2130705919 192.168.137.1 50108 typ host tcptype passive [1659793172.587|11119|ice_transport.cpp :1805 ] [ice:0x7c32107640] Add remote candidate: Hc0a88901 1 TCP 2130705663 192.168.137.1 9 typ host tcptype active [1659793172.586|10720|ice_transport.cpp :1805 ] [ice:0x7c36d9b440] Add remote candidate: R33fe279d 1 TCP 16777215 51.254.39.157 24097 typ relay tcptype passive [1659793172.587|10720|connectionmanager.cpp:803 ] [Account Acc123] [CNX] connection accepted, DHT reply to a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793172.587|11119|ice_transport.cpp :1805 ] [ice:0x7c32107640] Add remote candidate: Hb9cb0f80 1 TCP 2130706431 fe80::e97d:ff72:a0f3:3226 50109 typ host tcptype passive [1659793172.588|11119|ice_transport.cpp :1805 ] [ice:0x7c32107640] Add remote candidate: Hb9cb0f80 1 TCP 2130706175 fe80::e97d:ff72:a0f3:3226 9 typ host tcptype active [1659793172.588|10720|ice_transport.cpp :1275 ] [ice:0x7c36d9b440] negotiation starting (8 remote candidates) [1659793172.588|11119|ice_transport.cpp :1805 ] [ice:0x7c32107640] Add remote candidate: Sc0a80102 1 TCP 1694498303 77.119.105.240 9 typ srflx tcptype active [1659793172.588|11119|ice_transport.cpp :1805 ] [ice:0x7c32107640] Add remote candidate: R33fe279d 1 TCP 16777215 51.254.39.157 16329 typ relay tcptype passive [1659793172.589|11119|connectionmanager.cpp:803 ] [Account Acc123] [CNX] connection accepted, DHT reply to a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793172.589|11119|ice_transport.cpp :1275 ] [ice:0x7c32107640] negotiation starting (8 remote candidates) [1659793173.017|10712|jamiaccount.cpp :2047 ] [Account Acc123] Dht status: IPv4 connected; IPv6 connecting [1659793173.087|12081|ice_transport.cpp :710 ] [ice:0x7c36d9b440] TCP negotiation success [1659793173.088|12081|ice_transport.cpp :736 ] [ice:0x7c36d9b440] TCP connection pairs ([comp id] local [type] <-> remote [type]): [2] 192.168.137.131:42662 [prflx] <-> 192.168.137.1:50115 [host] [1659793173.088|10720|connectionmanager.cpp:866 ] [Account Acc123] Start TLS session - Initied by DHT request. Device:ff6ae99e7cc107c3fa2c3eebe9747fada4f1cd8b - vid: 640355457064465 [1659793173.090|12080|ice_transport.cpp :710 ] [ice:0x7c32107640] TCP negotiation success [1659793173.091|12082|tls_session.cpp :1057 ] [TLS] Start client session [1659793173.091|12080|ice_transport.cpp :736 ] [ice:0x7c32107640] TCP connection pairs ([comp id] local [type] <-> remote [type]): [2] 192.168.137.131:58492 [prflx] <-> 192.168.137.1:50108 [host] [1659793173.093|11119|connectionmanager.cpp:866 ] [Account Acc123] Start TLS session - Initied by DHT request. Device:ff6ae99e7cc107c3fa2c3eebe9747fada4f1cd8b - vid: 3046194618804607 [1659793173.094|12083|tls_session.cpp :1057 ] [TLS] Start client session [1659793173.168|12082|tls_session.cpp :528 ] [TLS] User identity loaded [1659793173.168|12083|tls_session.cpp :528 ] [TLS] User identity loaded [1659793173.168|12082|tls_session.cpp :1153 ] [TLS] handshake [1659793173.169|12083|tls_session.cpp :1153 ] [TLS] handshake [1659793175.908|10712|archive_account_manager.cpp:582 ] [Contacts] received device sync data (3 devices, 3 contacts) [1659793175.913|10712|contact_list.cpp :197 ] [Contacts] updated contact: abc12345 [1659793175.913|10712|contact_list.cpp :197 ] [Contacts] updated contact: oneothercontact [1659793175.913|10712|contact_list.cpp :197 ] [Contacts] updated contact: a11f558445d5b054d465b30981fd99b78bd7ee8f [1659793196.542|10712|account_manager.cpp:351 ] Found peer device: a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 account:abc12345 CA:fff171c166eec58efb5f1d7ff5dbfea6f59b98e0 [1659793196.544|10712|sipaccountbase.cpp:464 ] Text message received from abc12345, 1 part(s) [1659793196.545|10712|jamiaccount.cpp :3653 ] Received indication for new commit available in conversation 0cd2f487871cfc6c152d62c9233a645f29200543 [1659793196.545|10712|conversation_module.cpp:1473 ] [Account Acc123] on new commit notification from abc12345, for 0cd2f487871cfc6c152d62c9233a645f29200543, commit 85c9660d6cb2400ca13d42209b7a6ae58cd9bccb [1659793196.545|10712|conversation_module.cpp:372 ] [Account Acc123] fetch commits for peer abc12345 on device a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793196.548|10712|jamiaccount.cpp :2292 ] Sending message confirmation 6930564025692243 [1659793196.549|10673|connectionmanager.cpp:498 ] Already connecting to a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9, wait for the ICE negotiation [1659793196.734|10712|connectionmanager.cpp:683 ] Received request from a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793196.741|10720|account_manager.cpp:351 ] Found peer device: a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 account:abc12345 CA:fff171c166eec58efb5f1d7ff5dbfea6f59b98e0 [1659793196.742|10720|connectionmanager.cpp:895 ] [Account Acc123] New connection requested by a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793196.748|10720|account_manager.cpp:351 ] Found peer device: a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 account:abc12345 CA:fff171c166eec58efb5f1d7ff5dbfea6f59b98e0 [1659793196.754|10720|jamiaccount.cpp :2123 ] Accepting ICE request from account abc12345 [1659793196.755|10712|jamiaccount.cpp :3449 ] [Account Acc123] Store DHT public IPv4 address : 77.119.105.240 [1659793196.756|10712|connectionmanager.cpp:966 ] [Account:Acc123] accepting connection from a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793196.757|10712|ice_transport.cpp :332 ] [ice:0x7c39e97780] Creating IceTransport session for "Acc123" [1659793196.757|10712|ice_transport.cpp :408 ] [ice:0x7c39e97780] Initializing the session - comp count 1 - as a master [1659793196.757|10712|ice_transport.cpp :446 ] [ice:0x7c39e97780] Add host candidates [1659793196.757|10712|ice_transport.cpp :910 ] [ice:0x7c39e97780] added host stun config for TCP transport [1659793196.757|10712|ice_transport.cpp :910 ] [ice:0x7c39e97780] added host stun config for TCP transport [1659793196.757|10712|ice_transport.cpp :910 ] [ice:0x7c39e97780] added host stun config for TCP transport [1659793196.757|10712|ice_transport.cpp :995 ] [ice:0x7c39e97780] Add srflx reflexive candidates [192.168.137.131:9 : 77.119.105.240:9] for comp 1 [1659793196.757|10712|ice_transport.cpp :468 ] [ice:0x7c39e97780] Added generic srflx candidates: [1659793196.757|10712|ice_transport.cpp :322 ] [ice (TCP)] added turn server '51.254.39.157', port 3478 [1659793197.008|12088|ice_transport.cpp :710 ] [ice:0x7c39e97780] TCP initialization success [1659793197.009|12088|ice_transport.cpp :770 ] [ice:0x7c39e97780] as master [1659793197.010|12088|ice_transport.cpp :883 ] [ice:0x7c39e97780] (local) ufrag=51a398dc, pwd=5002205c51db7f686f739720 [1659793197.010|11119|ice_transport.cpp :1805 ] [ice:0x7c39e97780] Add remote candidate: Hc0a80102 1 TCP 2130706431 192.168.1.2 50150 typ host tcptype passive [1659793197.011|11119|ice_transport.cpp :1805 ] [ice:0x7c39e97780] Add remote candidate: Hc0a80102 1 TCP 2130706175 192.168.1.2 9 typ host tcptype active [1659793197.011|11119|ice_transport.cpp :1805 ] [ice:0x7c39e97780] Add remote candidate: Hc0a88901 1 TCP 2130705919 192.168.137.1 50150 typ host tcptype passive [1659793197.012|11119|ice_transport.cpp :1805 ] [ice:0x7c39e97780] Add remote candidate: Hc0a88901 1 TCP 2130705663 192.168.137.1 9 typ host tcptype active [1659793197.012|11119|ice_transport.cpp :1805 ] [ice:0x7c39e97780] Add remote candidate: Hb9cb0f80 1 TCP 2130706431 fe80::e97d:ff72:a0f3:3226 50151 typ host tcptype passive [1659793197.013|11119|ice_transport.cpp :1805 ] [ice:0x7c39e97780] Add remote candidate: Hb9cb0f80 1 TCP 2130706175 fe80::e97d:ff72:a0f3:3226 9 typ host tcptype active [1659793197.013|11119|ice_transport.cpp :1805 ] [ice:0x7c39e97780] Add remote candidate: Sc0a80102 1 TCP 1694498303 77.119.105.240 9 typ srflx tcptype active [1659793197.014|11119|ice_transport.cpp :1805 ] [ice:0x7c39e97780] Add remote candidate: R33fe279d 1 TCP 16777215 51.254.39.157 27525 typ relay tcptype passive [1659793197.014|11119|connectionmanager.cpp:803 ] [Account Acc123] [CNX] connection accepted, DHT reply to a1b193d2098cae6ea30fe00c4a1921c11ce01a3b8ac791370749a9491743aef9 [1659793197.014|11119|ice_transport.cpp :1275 ] [ice:0x7c39e97780] negotiation starting (8 remote candidates) [1659793197.412|10719|connectionmanager.cpp:336 ] no response from DHT to E2E request. [1659793197.414|10673|ice_transport.cpp :337 ] [ice:0x7c320f2e40] destroying 0x7c36ce1228 [1659793197.614|12088|ice_transport.cpp :710 ] [ice:0x7c39e97780] TCP negotiation success [1659793197.615|12088|ice_transport.cpp :736 ] [ice:0x7c39e97780] TCP connection pairs ([comp id] local [type] <-> remote [type]): [2] 192.168.137.131:58090 [prflx] <-> 192.168.137.1:50150 [host] [1659793197.909|10673|ice_transport.cpp :351 ] [ice:0x7c320f2e40] Destroying ice_strans 0x7c36ce1228 [1659793197.911|10673|ice_transport.cpp :669 ] [ice:0x7c320f2e40] Timer heap flushed after 0 ms [1659793197.912|10673|ice_transport.cpp :383 ] [ice:0x7c320f2e40] done destroying [1659793197.913|11119|connectionmanager.cpp:866 ] [Account Acc123] Start TLS session - Initied by DHT request. Device:ff6ae99e7cc107c3fa2c3eebe9747fada4f1cd8b - vid: 2213055069446666 [1659793197.915|12089|tls_session.cpp :1057 ] [TLS] Start client session [1659793197.989|12089|tls_session.cpp :528 ] [TLS] User identity loaded [1659793197.989|12089|tls_session.cpp :1153 ] [TLS] handshake
Edited by Elys- Elys changed title from Connecting issue: call Windows 10 -> Android to Connecting issue: call Windows 10 -> Android if the Windows 10 user does not wait ~30 sec before he makes the first call
changed title from Connecting issue: call Windows 10 -> Android to Connecting issue: call Windows 10 -> Android if the Windows 10 user does not wait ~30 sec before he makes the first call
- Elys changed title from Connecting issue: call Windows 10 -> Android if the Windows 10 user does not wait ~30 sec before he makes the first call to Connecting issue: call Windows 10 -> Android if the Windows 10 user does not wait >30 sec before he makes the first call
changed title from Connecting issue: call Windows 10 -> Android if the Windows 10 user does not wait ~30 sec before he makes the first call to Connecting issue: call Windows 10 -> Android if the Windows 10 user does not wait >30 sec before he makes the first call
- Elys changed the description
Compare with previous version changed the description
- Elys changed the description
Compare with previous version changed the description
- Elys mentioned in issue jami-daemon#743 (closed)
mentioned in issue jami-daemon#743 (closed)
- Elys changed title from Connecting issue: call Windows 10 -> Android if the Windows 10 user does not wait >30 sec before he makes the first call to Connecting issue: call Windows 10 -> Android if the Windows 10 user does not wait >30 sec before he makes the first call & Android <-> Android?
changed title from Connecting issue: call Windows 10 -> Android if the Windows 10 user does not wait >30 sec before he makes the first call to Connecting issue: call Windows 10 -> Android if the Windows 10 user does not wait >30 sec before he makes the first call & Android <-> Android?
- Aline Gondim Santos added to-check label
added to-check label
same issue / similar Android (Smartphone; EMUI 12) -> Android (Tablet, Android 8.1 Oreo)
(this time using the same Wifi (both devices); "sameuseraccount" -> "sameuseraccount")
Step 1:
- Android (both devices) open Jami at the same time
- Immediately make a call (audio or video) [Smartphone -> Tablet]
- The smartphone just shows "connecting"; the tablet will never receive the call
- same issue if you try again (after the calls fails or you cancle the "connecting" call state)
Step 2:
- Now (after these steps) make a call Android Tablet -> Android smartphone
- The Android tablet will show the call ui but neither "connecting" nor any other message
So no calls are possible (no direction)
Step 3:
- Now just the user of the android tablet exits and waits 30 seconds
- Now make video call Smartphone -> Tablet - it now works. (Once you made this call also Tablet -> will work)
Edited by Elys- Elys changed title from Connecting issue: call Windows 10 -> Android if the Windows 10 user does not wait >30 sec before he makes the first call & Android <-> Android? to Connecting issue: call Windows 10 -> Android if the Windows 10 user does not wait >30 sec before he makes the first call & Android <-> Android
changed title from Connecting issue: call Windows 10 -> Android if the Windows 10 user does not wait >30 sec before he makes the first call & Android <-> Android? to Connecting issue: call Windows 10 -> Android if the Windows 10 user does not wait >30 sec before he makes the first call & Android <-> Android
Android: I just reproduced the following issue:
(no devices changed / no updates)
Android (Tablet, Android 8.1 Oreo) -> Android (Smartphone; EMUI 12) ["sameuseraccount1" -> "sameuseraccount1"]
If the tablet goes into sleep mode and you wake it up (but you never exit Jami; and you didn't select "Launch Jami at startup" (No device;)) you still have to wait at least 10 seconds again to make a call. Otherwise all calls will fail - but after 10 seconds or 30 seconds everything will work again.
- Elys changed the description
Compare with previous version changed the description
- Elys changed the description
Compare with previous version changed the description
I wanted to add that even if I disable turn (possible since I am using the same network) I have the same issue.
So - as mentioned - probably not an issue because of turn? jami-client-android#999 (comment 36664)
However: Windows 10 -> Android: It seems it won't work after a failed call unless you proceed the steps mentioned in the description.
Android -> Android: Although the calls will fail (at least for the first 10 seconds) you are able to make call after ~ 30 seconds.
Edited by Elys- Elys changed the description
Compare with previous version changed the description
- Elys mentioned in issue jami-client-android#1113 (closed)
mentioned in issue jami-client-android#1113 (closed)
- Elys mentioned in issue jami-client-android#1108 (closed)
mentioned in issue jami-client-android#1108 (closed)
I've updated to the newest version of Android (20220826-01)
Now calls Android <-> Android (before 30 seconds or even 10 seconds) work better but about 2 of 3 calls will fail if you don't wait at least 10 seconds.
If one call shows connecting you won't be able to make calls Android <-> Android anymore unless you exit both apps on both Android devices.
- Elys mentioned in issue jami-project#1497 (moved)
mentioned in issue jami-project#1497 (moved)
- Elys mentioned in issue jami-client-android#1141 (closed)
mentioned in issue jami-client-android#1141 (closed)