call fails with handshake timeout failure
Describe your environment
Please specify the following:
- OS: GNU/Linux and Android
- Jami version/build: Guix (20240325.0) / Play Store 20240419-01
Steps to reproduce
I think the key is having a long running Android jami application, but I'm not sure, really. Restarting my local network access point/router and force restarting the android Jami app doesn't help (restarting the GNU/Linux client doesn't either)
- Can you reproduce the bug: at will once triggered else occasionally
- Steps:
- Place a call to or from the Android client while both accounts are connected.
- Actual result: Call fails.
- Expected result: Call succeeds.
Additional information
Logs for the remote GNU/Linux Jami client (192.168.10.15):
[1713886239.913|4340] [device 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f] Received request
[1713886239.922|4340] Found peer device: 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f account:fd11be3e4a2b141b81e3856cd876d531b1f1129a CA:afa5c63e84df9a3b1e584298a17961fc057b9530
[1713886239.923|4340] [device 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f] New connection request
[1713886239.925|4340|account_manager.cpp :431 ] Found peer device: 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f account:fd11be3e4a2b141b81e3856cd876d531b1f1129a CA:afa5c63e84df9a3b1e584298a17961fc057b9530
[1713886239.929|4340|jamiaccount.cpp :2003] Accepting ICE request from fd11be3e4a2b141b81e3856cd876d531b1f1129a
[1713886239.929|4340] Store DHT public IPv4 address: 72.10.136.235
[1713886239.930|4340] [device 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f] Accepting connection
[1713886239.930|4340] [ice:0x7faf94257680] Creating IceTransport session for ""
[1713886239.930|4340] [ice:0x7faf94257680] Initializing the session - comp count 1 - as a master
[1713886239.930|4340] [ice:0x7faf94257680] Add srflx reflexive candidates [192.168.10.15:9 : 72.10.136.235:9] for comp 1
[1713886239.930|4340] added turn server '144.217.83.140', port 3478
[1713886240.560|4642] [ice:0x7faf94257680] TCP initialization success
[1713886240.560|4642] [ice:0x7faf94257680] as master
[1713886240.570|4642] [ice:0x7faf94257680] (local) ufrag=4aec5f27, pwd=26c8cc6b4fefca055eecb043
[1713886240.570|4326] [ice:0x7faf94257680] Add remote candidate: Hc0a80a9d 1 TCP 2130706431 192.168.10.157 37667 typ host tcptype passive
[1713886240.570|4326] [ice:0x7faf94257680] Add remote candidate: Hc0a80a9d 1 TCP 2130706175 192.168.10.157 9 typ host tcptype active
[1713886240.570|4326] [ice:0x7faf94257680] Add remote candidate: H6d9b7e8e 1 TCP 2130706431 fe80::824e:81ff:fe4b:1364 38690 typ host tcptype passive
[1713886240.570|4326] [ice:0x7faf94257680] Add remote candidate: H6d9b7e8e 1 TCP 2130706175 fe80::824e:81ff:fe4b:1364 9 typ host tcptype active
[1713886240.570|4326] [ice:0x7faf94257680] Add remote candidate: Sc0a80a9d 1 TCP 1694498303 72.10.136.235 9 typ srflx tcptype active
[1713886240.570|4326] [ice:0x7faf94257680] Add remote candidate: R90d9538c 1 TCP 16777215 144.217.83.140 18096 typ relay tcptype passive
[1713886240.570|4326] [device 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f] Connection accepted, DHT reply
[1713886240.580|4326] [ice:0x7faf94257680] negotiation starting (6 remote candidates)
[1713886240.104|4340|jamiaccount.cpp :1914] [Account 84cd2bc44742b72f] Dht status: IPv4 connected; IPv6 connecting
[1713886240.385|4340] [device 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f] Answer to connection request: put encrypted ok
[1713886242.997|4642] [ice:0x7faf94257680] TCP negotiation success
[1713886242.997|4642] [ice:0x7faf94257680] TCP connection pairs ([comp id] local [type] <-> remote [type]):
[2] 192.168.10.15:52860 [prflx] <-> 192.168.10.157:37667 [host]
[1713886242.997|4330] [device 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f] Start TLS session - Initied by DHT request. vid: 2239724050082436
[1713886242.998|4647] [TLS] Start client session
[1713886243.430|4647] [TLS] User identity loaded
[1713886243.430|4647] [TLS] handshake
[1713886271.572|4340] [device 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f] Received request
[1713886271.574|4340] Found peer device: 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f account:fd11be3e4a2b141b81e3856cd876d531b1f1129a CA:afa5c63e84df9a3b1e584298a17961fc057b9530
[1713886271.574|4340] [device 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f] New connection request
[1713886271.576|4340|account_manager.cpp :431 ] Found peer device: 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f account:fd11be3e4a2b141b81e3856cd876d531b1f1129a CA:afa5c63e84df9a3b1e584298a17961fc057b9530
[1713886271.578|4340|jamiaccount.cpp :2003] Accepting ICE request from fd11be3e4a2b141b81e3856cd876d531b1f1129a
[1713886271.578|4340] Store DHT public IPv4 address: 72.10.136.235
[1713886271.579|4340] [device 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f] Accepting connection
[1713886271.579|4340] [ice:0x7faf9498fd10] Creating IceTransport session for ""
[1713886271.579|4340] [ice:0x7faf9498fd10] Initializing the session - comp count 1 - as a master
[1713886271.579|4340] [ice:0x7faf9498fd10] Add srflx reflexive candidates [192.168.10.15:9 : 72.10.136.235:9] for comp 1
[1713886271.579|4340] added turn server '144.217.83.140', port 3478
[1713886271.658|4673] [ice:0x7faf9498fd10] TCP initialization success
[1713886271.658|4673] [ice:0x7faf9498fd10] as master
[1713886271.659|4673] [ice:0x7faf9498fd10] (local) ufrag=040b8a40, pwd=2bad8aef5641556331b9e47b
[1713886271.659|4334] [ice:0x7faf9498fd10] Add remote candidate: Hc0a80a9d 1 TCP 2130706431 192.168.10.157 43154 typ host tcptype passive
[1713886271.659|4334] [ice:0x7faf9498fd10] Add remote candidate: Hc0a80a9d 1 TCP 2130706175 192.168.10.157 9 typ host tcptype active
[1713886271.659|4334] [ice:0x7faf9498fd10] Add remote candidate: H6d9b7e8e 1 TCP 2130706431 fe80::824e:81ff:fe4b:1364 52779 typ host tcptype passive
[1713886271.659|4334] [ice:0x7faf9498fd10] Add remote candidate: H6d9b7e8e 1 TCP 2130706175 fe80::824e:81ff:fe4b:1364 9 typ host tcptype active
[1713886271.659|4334] [ice:0x7faf9498fd10] Add remote candidate: Sc0a80a9d 1 TCP 1694498303 72.10.136.235 9 typ srflx tcptype active
[1713886271.659|4334] [ice:0x7faf9498fd10] Add remote candidate: R90d9538c 1 TCP 16777215 144.217.83.140 15768 typ relay tcptype passive
[1713886271.659|4334] [device 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f] Connection accepted, DHT reply
[1713886271.659|4334] [ice:0x7faf9498fd10] negotiation starting (6 remote candidates)
[1713886271.958|4340] [device 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f] Answer to connection request: put encrypted ok
[1713886272.633|4673] [ice:0x7faf9498fd10] TCP negotiation success
[1713886272.633|4673] [ice:0x7faf9498fd10] TCP connection pairs ([comp id] local [type] <-> remote [type]):
[2] 192.168.10.15:43262 [prflx] <-> 192.168.10.157:43154 [host]
[1713886272.633|4338] [device 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f] Start TLS session - Initied by DHT request. vid: 1762462878832692
[1713886272.634|4674] [TLS] Start client session
[1713886272.678|4674] [TLS] User identity loaded
[1713886272.678|4674] [TLS] handshake
[1713886283.490|4647] [TLS] handshake failed: Opération hors délais
[1713886283.490|4647] [device 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f] TLS connection failure - Initied by DHT request. channel: - vid: 2239724050082436
[1713886283.490|4647] [TLS] shutdown
[1713886283.650|4327] [ice:0x7faf94257680] Destroying ice_strans 0x7faf94953ee8
[1713886297.430|4340|jamiaccount.cpp :1914] [Account 84cd2bc44742b72f] Dht status: IPv4 connected; IPv6 disconnected
[1713886312.678|4674] [TLS] handshake failed: Opération hors délais
[1713886312.679|4674] [device 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f] TLS connection failure - Initied by DHT request. channel: - vid: 1762462878832692
[1713886312.679|4674] [TLS] shutdown
[1713886312.935|4337] [ice:0x7faf9498fd10] Destroying ice_strans 0x7faf94534c68
The caller is Jami from an Android device. Its logs are:
[1713886225.876|23261|manager.cpp :971 ] ############## START MONITORING ##############,
[1713886225.876|23261|manager.cpp :972 ] Using PJSIP version 2.13.1 for armv7a-unknown-linux-androideabi,
[1713886225.876|23261|manager.cpp :973 ] Using GnuTLS version 3.8.3,
[1713886225.876|23261|manager.cpp :974 ] Using OpenDHT version 3.1.11,
[1713886225.876|23261|jamiaccount.cpp :3881] [Account 2f5528c29713a8b5] Monitor connections,
[1713886225.876|23261|jamiaccount.cpp :3882] [Account 2f5528c29713a8b5] Using proxy: dhtproxy.jami.net:84,
[1713886225.876|23261|routing_table.cpp :419 ] SWARM: 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f ,
[1713886225.876|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.876|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.876|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.877|23261|routing_table.cpp :217 ] Node 0 Id: 2e8a92b8268199c0eda5102f54331a3bfab568c7260be4ef258d139d0b13dc50,
[1713886225.877|23261|routing_table.cpp :217 ] Node 1 Id: 3b6a5fcbaf9a7731cfc72f892762380ad3570a65083dcb97831412e795e7694e,
[1713886225.877|23261|routing_table.cpp :217 ] Node 2 Id: ebb49e00f0a2622a3121615683e436a738c6c67431180e25318b61c4f9a20f2e,
[1713886225.877|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.877|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.877|23261|routing_table.cpp :419 ] SWARM: 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f ,
[1713886225.877|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.877|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.877|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.877|23261|routing_table.cpp :217 ] Node 0 Id: 2e8a92b8268199c0eda5102f54331a3bfab568c7260be4ef258d139d0b13dc50,
[1713886225.877|23261|routing_table.cpp :217 ] Node 1 Id: 3b6a5fcbaf9a7731cfc72f892762380ad3570a65083dcb97831412e795e7694e,
[1713886225.877|23261|routing_table.cpp :217 ] Node 2 Id: 60016651bbe3f90cea10c77f00b480da9a28d4dffe9ab29329d733feddb61775,
[1713886225.877|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.877|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.877|23261|routing_table.cpp :419 ] SWARM: 536ba4cf048057f6fa01d8eafa7b65d7bdde470320af6e5c026cf2dc59a0355f ,
[1713886225.877|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.877|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.878|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.878|23261|routing_table.cpp :217 ] Node 0 Id: 2e8a92b8268199c0eda5102f54331a3bfab568c7260be4ef258d139d0b13dc50,
[1713886225.878|23261|routing_table.cpp :217 ] Node 1 Id: 3b6a5fcbaf9a7731cfc72f892762380ad3570a65083dcb97831412e795e7694e,
[1713886225.878|23261|routing_table.cpp :217 ] Node 2 Id: 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78,
[1713886225.878|23261|routing_table.cpp :217 ] Node 3 Id: 526390162f0a7a3f316b3cf5f32c070585ac648289c90b6dcf8986ca46e3bb2d,
[1713886225.878|23261|routing_table.cpp :217 ] Node 4 Id: 90864c740c254b0f5ddf20a39d8c70c4e34f414ef57e32922aa4d7176222f525,
[1713886225.878|23261|routing_table.cpp :217 ] Node 5 Id: fa35b0ed367b5f7e2ea3482eaadcba58e3be56dc3bcf1d85e952e75d0b5889e0,
[1713886225.878|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.878|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.878|23261] ConnectionManager current status:,
[1713886225.878|23261] ConnectionManager end status.,
[1713886225.878|23261|jamiaccount.cpp :3881] [Account 8c975e8f1bb936e2] Monitor connections,
[1713886225.878|23261|jamiaccount.cpp :3882] [Account 8c975e8f1bb936e2] Using proxy: ,
[1713886225.878|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.878|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.878|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.878|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.878|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.878|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.878|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.879|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.879|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.879|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.879|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.879|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.879|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.879|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.879|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.879|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.879|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.879|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.879|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.879|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.879|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.879|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.879|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.879|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.879|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.879|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.879|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.879|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.879|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.880|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.880|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.880|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.880|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.880|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.880|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.880|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.880|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.880|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.880|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.880|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.880|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.880|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.881|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.881|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.881|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.881|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.881|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.881|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.881|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.881|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.881|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.881|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.881|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.881|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.881|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.881|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.881|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.881|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.882|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.882|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.882|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.882|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.882|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.882|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.882|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.882|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.882|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.882|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.882|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.882|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.882|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.882|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.882|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.882|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.882|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.882|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.883|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.883|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.883|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.883|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.883|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.883|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.883|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.883|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.883|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.883|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.883|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.883|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.883|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.883|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.883|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.883|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.884|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.884|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.884|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.884|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.884|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.884|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.884|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.884|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.884|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.884|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.884|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.884|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.884|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.884|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.884|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.884|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.884|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.885|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.885|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.885|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.885|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.885|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.885|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.885|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.885|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.885|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.885|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.885|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.885|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.885|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.885|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.885|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.885|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.886|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.886|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.886|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.886|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.886|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.886|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.886|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.886|23261|routing_table.cpp :419 ] SWARM: 12a51d65666858c586a8c271cdaac502a98fb3e46db8bef07de712732714a5ec ,
[1713886225.886|23261|routing_table.cpp :200 ] BUCKET Number: 1,
[1713886225.886|23261|routing_table.cpp :207 ] Mobile Nodes,
[1713886225.886|23261|routing_table.cpp :214 ] Known Nodes,
[1713886225.886|23261|routing_table.cpp :220 ] Connecting_nodes,
[1713886225.886|23261|routing_table.cpp :424 ] _____________________________________________________________________________,
[1713886225.886|23261|manager.cpp :990 ] ############## END MONITORING ##############,
[1713886244.300|23261|manager.cpp :1108] try outgoing call to 'd873d0ebe6c3b57f77a362fe867dd03a4f9efb76' with account '2f5528c29713a8b5',
[1713886244.300|23261|sipcall.cpp :157 ] [call:258679153032351] Create a new [OUTGOING] SIP call with 2 media,
[1713886244.300|23261|audio_rtp_session.cpp :60 ] Created Audio RTP session: 0x84c91890 - stream id 258679153032351_audio_0,
[1713886244.300|23261|ringbuffer.cpp :55 ] Create new RingBuffer 258679153032351_audio_0,
[1713886244.300|23261|sipcall.cpp :2025] [call:258679153032351] Added media @0: type [AUDIO] enabled [YES] muted [NO] label [audio_0] source [camera://1] secure [YES],
[1713886244.300|23261|video_rtp_session.cpp :77 ] [0x81eb7f10] Video RTP session created for call 258679153032351 (recorder 0x7c54ec70),
[1713886244.310|23261|sipcall.cpp :2025] [call:258679153032351] Added media @1: type [VIDEO] enabled [YES] muted [NO] label [video_0] source [camera://1] secure [YES],
[1713886244.310|23261|sipcall.cpp :2028] [call:258679153032351] Created 2 Media streams,
[1713886244.310|23261|manager.cpp :613 ] ----- Switch current call id to '258679153032351' -----,
[1713886244.310|23335] Store DHT public IPv4 address: 72.10.136.235,
[1713886244.330|23335] [ice:0x809f8a80] Creating IceTransport session for "258679153032351",
[1713886244.340|23335|sipcall.cpp :3411] [call:258679153032351] Successfully created media ICE transport [ice:0x7d72609c],
[1713886244.340|23335|sipcall.cpp :3579] [call:258679153032351] Setting ICE session [0x7d72609c],
[1713886244.340|23335|sipcall.cpp :3431] [call:258679153032351] Init media ICE transport,
[1713886244.340|23335] [ice:0x809f8a80] Initializing the session - comp count 4 - as a master,
[1713886244.350|23335] [ice:0x809f8a80] Add srflx reflexive candidates
[192.168.10.157:20095 : 72.10.136.235:20095] for comp 1,
[1713886244.350|23335] [ice:0x809f8a80] Add srflx reflexive candidates
[192.168.10.157:20462 : 72.10.136.235:20462] for comp 2,
[1713886244.350|23335] [ice:0x809f8a80] Add srflx reflexive candidates
[192.168.10.157:21281 : 72.10.136.235:21281] for comp 3,
[1713886244.350|23335] [ice:0x809f8a80] Add srflx reflexive candidates
[192.168.10.157:24366 : 72.10.136.235:24366] for comp 4,
[1713886244.350|23335] added turn server '144.217.83.140', port 3478,
[1713886244.500|23335|jamiaccount.cpp :372 ] New outgoing call with jami:d873d0ebe6c3b57f77a362fe867dd03a4f9efb76,
[1713886244.510|23335|jamiaccount.cpp :388 ] 0x9eedf010Calling peer d873d0ebe6c3b57f77a362fe867dd03a4f9efb76,
[1713886244.510|23335|contact_list.cpp :72 ] Can't set certificate status for existing contacts d873d0ebe6c3b57f77a362fe867dd03a4f9efb76,
[1713886244.510|23335|call.cpp :248 ] [call:258679153032351] state change 0/0, cnx 0/1, code 0,
[1713886244.510|23335|sipcall.cpp :157 ] [call:8878712811733964] Create a new [OUTGOING] SIP call with 2 media,
[1713886244.520|23335|audio_rtp_session.cpp :60 ] Created Audio RTP session: 0x7c872290 - stream id 8878712811733964_audio_0,
[1713886244.520|23335|ringbuffer.cpp :55 ] Create new RingBuffer 8878712811733964_audio_0,
[1713886244.520|23335|sipcall.cpp :2025] [call:8878712811733964] Added media @0: type [AUDIO] enabled [YES] muted [NO] label [audio_0] source [camera://1] secure [YES],
[1713886244.520|23335|video_rtp_session.cpp :77 ] [0x7f3b7510] Video RTP session created for call 8878712811733964 (recorder 0x81692dd0),
[1713886244.530|23335|sipcall.cpp :2025] [call:8878712811733964] Added media @1: type [VIDEO] enabled [YES] muted [NO] label [video_0] source [camera://1] secure [YES],
[1713886244.530|23335|sipcall.cpp :2028] [call:8878712811733964] Created 2 Media streams,
[1713886244.530|23335|call.cpp :450 ] [call:258679153032351] add subcall 8878712811733964,
[1713886244.530|23335|sipcall.cpp :3579] [call:8878712811733964] Setting ICE session [0x7d72609c],
[1713886244.102|23680] [ice:0x809f8a80] UDP initialization success,
[1713886244.102|23680] [ice:0x809f8a80] as master,
[1713886244.104|23680] [ice:0x809f8a80] (local) ufrag=096d6adf, pwd=574a3d9a451ea08946135a27,
[1713886244.286|23335|sipcall.cpp :157 ] [call:7196215172066835] Create a new [OUTGOING] SIP call with 2 media,
[1713886244.287|23335|audio_rtp_session.cpp :60 ] Created Audio RTP session: 0x7c871110 - stream id 7196215172066835_audio_0,
[1713886244.287|23335|ringbuffer.cpp :55 ] Create new RingBuffer 7196215172066835_audio_0,
[1713886244.288|23335|sipcall.cpp :2025] [call:7196215172066835] Added media @0: type [AUDIO] enabled [YES] muted [NO] label [audio_0] source [camera://1] secure [YES],
[1713886244.288|23335|video_rtp_session.cpp :77 ] [0x7f3b8e10] Video RTP session created for call 7196215172066835 (recorder 0x81692f90),
[1713886244.288|23335|sipcall.cpp :2025] [call:7196215172066835] Added media @1: type [VIDEO] enabled [YES] muted [NO] label [video_0] source [camera://1] secure [YES],
[1713886244.288|23335|sipcall.cpp :2028] [call:7196215172066835] Created 2 Media streams,
[1713886244.289|23335|call.cpp :248 ] [call:7196215172066835] state change 0/0, cnx 0/1, code 0,
[1713886244.289|23335|call.cpp :450 ] [call:258679153032351] add subcall 7196215172066835,
[1713886244.289|23335|sipcall.cpp :3579] [call:7196215172066835] Setting ICE session [0x7d72609c],
[1713886244.289|23335|jamiaccount.cpp :644 ] [call 258679153032351] No channeled socket with this peer. Send request,
[1713886244.290|23335|jamiaccount.cpp :3484] [Account 2f5528c29713a8b5] Request SIP connection to peer d873d0ebe6c3b57f77a362fe867dd03a4f9efb76 on device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78,
[1713886244.290|23335|jamiaccount.cpp :3507] [Account 2f5528c29713a8b5] Ask 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78 for a new SIP channel,
[1713886244.290|23335] Store DHT public IPv4 address: 72.10.136.235,
[1713886244.292|23335] [ice:0x82662280] Creating IceTransport session for "",
[1713886244.292|23335] [ice:0x82662280] Initializing the session - comp count 1 - as a slave,
[1713886244.292|23335] [ice:0x82662280] Add srflx reflexive candidates
[192.168.10.157:9 : 72.10.136.235:9] for comp 1,
[1713886244.292|23335] added turn server '144.217.83.140', port 3478,
[1713886244.297|23335|account_manager.cpp :799 ] Found 1 devices,
[1713886244.390|23682] [ice:0x82662280] TCP initialization success,
[1713886244.390|23682] [ice:0x82662280] as slave,
[1713886244.391|23682] [ice:0x82662280] (local) ufrag=50840f36, pwd=00a6036f15d31fb9160e6269,
[1713886244.392|23319] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] Added local ICE candidate Hc0a80a9d 1 TCP 2130706431 192.168.10.157 37667 typ host tcptype passive,
[1713886244.392|23319] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] Added local ICE candidate Hc0a80a9d 1 TCP 2130706175 192.168.10.157 9 typ host tcptype active,
[1713886244.392|23319] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] Added local ICE candidate H6d9b7e8e 1 TCP 2130706431 fe80::824e:81ff:fe4b:1364 38690 typ host tcptype passive,
[1713886244.393|23319] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] Added local ICE candidate H6d9b7e8e 1 TCP 2130706175 fe80::824e:81ff:fe4b:1364 9 typ host tcptype active,
[1713886244.393|23319] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] Added local ICE candidate Sc0a80a9d 1 TCP 1694498303 72.10.136.235 9 typ srflx tcptype active,
[1713886244.393|23319] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] Added local ICE candidate R90d9538c 1 TCP 16777215 144.217.83.140 18096 typ relay tcptype passive,
[1713886244.393|23319] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] Sending connection request,
[1713886247.932|23335] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] Sent connection request. Put encrypted ok,
[1713886274.423|23279] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] no response from DHT to ICE request.,
[1713886274.892|23315] [ice:0x82662280] Destroying ice_strans 0x7db9ca14,
[1713886275.404|23324|call.cpp :248 ] [call:8878712811733964] state change 0/5, cnx 0/0, code 6,
[1713886275.404|23315|call.cpp :248 ] [call:7196215172066835] state change 0/5, cnx 1/0, code 0,
[1713886275.404|23278|call.cpp :522 ] [call:258679153032351] subcall 8878712811733964 failed,
[1713886275.405|23278|call.cpp :545 ] [call:258679153032351] remains 1 subcall(s),
[1713886275.406|23278|manager.cpp :2094] [call:8878712811733964] Sub-call failed,
[1713886275.406|23278|manager.cpp :1745] [call:8878712811733964] Remove local audio 8878712811733964_audio_0,
[1713886275.407|23278|ringbufferpool.cpp :262 ] Unbind ringbuffer 8878712811733964_audio_0 from all bound ringbuffers,
[1713886275.408|23278|sipcall.cpp :1604] [call:8878712811733964] removeCall(),
[1713886275.408|23278|call_factory.cpp :72 ] Removing call 8878712811733964,
[1713886275.409|23278|call_factory.cpp :75 ] Remaining 2 call,
[1713886275.409|23278|call.cpp :248 ] [call:8878712811733964] state change 5/6, cnx 0/0, code 0,
[1713886275.410|23278|video_rtp_session.cpp :252 ] [0x7f3b7510] Stop video RTP sender: input [] - muted [NO],
[1713886275.411|23278|video_rtp_session.cpp :331 ] [0x7f3b7510] Stopping receiver,
[1713886275.411|23278|video_rtp_session.cpp :84 ] [0x7f3b7510] Video RTP session destroyed,
[1713886275.412|23278|audio_rtp_session.cpp :256 ] [0x7c872290] Stopping receiver,
[1713886275.412|23278|audio_rtp_session.cpp :70 ] Destroyed Audio RTP session: 0x7c872290 - stream id 8878712811733964_audio_0,
[1713886275.413|23278|ringbuffer.cpp :60 ] Destroy RingBuffer 8878712811733964_audio_0,
[1713886275.414|23278|call.cpp :522 ] [call:258679153032351] subcall 7196215172066835 failed,
[1713886275.414|23278|call.cpp :248 ] [call:258679153032351] state change 0/5, cnx 1/0, code 5,
[1713886275.415|23278|call.cpp :279 ] [call:258679153032351] emit client call state change FAILURE, code 5,
[1713886275.477|23278|sipcall.cpp :1604] [call:258679153032351] removeCall(),
[1713886275.478|23278|call_factory.cpp :72 ] Removing call 258679153032351,
[1713886275.479|23278|call_factory.cpp :75 ] Remaining 1 call,
[1713886275.480|23278|call.cpp :248 ] [call:258679153032351] state change 5/6, cnx 0/0, code 0,
[1713886275.486|23278|sipcall.cpp :3341] - Call 258679153032351 with d873d0ebe6c3b57f77a362fe867dd03a4f9efb76:,
[1713886275.487|23278|sipcall.cpp :3342] - Duration: 0 us,
[1713886275.487|23278|sipcall.cpp :3344] - Media: type [AUDIO] enabled [YES] muted [NO] label [audio_0] source [camera://1] secure [YES],
[1713886275.488|23278|sipcall.cpp :3344] - Media: type [VIDEO] enabled [YES] muted [NO] label [video_0] source [camera://1] secure [YES],
[1713886275.489|23278|call.cpp :279 ] [call:258679153032351] emit client call state change OVER, code 0,
[1713886275.493|23278|manager.cpp :2094] [call:7196215172066835] Sub-call failed,
[1713886275.493|23278|manager.cpp :1745] [call:7196215172066835] Remove local audio 7196215172066835_audio_0,
[1713886275.495|23278|ringbufferpool.cpp :262 ] Unbind ringbuffer 7196215172066835_audio_0 from all bound ringbuffers,
[1713886275.496|23278|sipcall.cpp :1604] [call:7196215172066835] removeCall(),
[1713886275.497|23278|call_factory.cpp :72 ] Removing call 7196215172066835,
[1713886275.500|23278|call_factory.cpp :75 ] Remaining 0 call,
[1713886275.501|23278|call.cpp :248 ] [call:7196215172066835] state change 5/6, cnx 0/0, code 0,
[1713886275.605|23309] [ice:0x809f8a80] Destroying ice_strans 0x81a03914,
[1713886275.648|23328|conversationrepository.cpp:1913] New message added with id: bc4cc5513a2d39a7621036ae90349e3a6090057a,
[1713886275.713|23328|message_engine.cpp :59 ] [message 2434297529650982] Replace content,
[1713886275.713|23328|conversation_module.cpp :1070] [Conversation c5b2090ec09d630b6df9bef2452342790a67f4a4] Not yet bootstraped, save notification,
[1713886275.713|23278|message_engine.cpp :121 ] [message 2434297529650982] Retry sending,
[1713886275.714|23328|message_engine.cpp :59 ] [message 6614016877291961] Replace content,
[1713886275.714|23282|message_engine.cpp :307 ] [Account 2f5528c29713a8b5] saved 2 messages to /data/user/0/cx.ring/cache/2f5528c29713a8b5/messages,
[1713886275.714|23278|message_engine.cpp :151 ] [message 2434297529650982] Message sent: failure,
[1713886275.715|23278|message_engine.cpp :190 ] [message 2434297529650982] Status changed to IDLE,
[1713886275.715|23281|message_engine.cpp :307 ] [Account 2f5528c29713a8b5] saved 2 messages to /data/user/0/cx.ring/cache/2f5528c29713a8b5/messages,
[1713886275.715|23278|message_engine.cpp :121 ] [message 6614016877291961] Retry sending,
[1713886275.716|23278|message_engine.cpp :151 ] [message 6614016877291961] Message sent: failure,
[1713886275.717|23278|message_engine.cpp :190 ] [message 6614016877291961] Status changed to IDLE,
[1713886275.980|23335|jamiaccount.cpp :3484] [Account 2f5528c29713a8b5] Request SIP connection to peer d873d0ebe6c3b57f77a362fe867dd03a4f9efb76 on device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78,
[1713886275.981|23335|jamiaccount.cpp :3507] [Account 2f5528c29713a8b5] Ask 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78 for a new SIP channel,
[1713886275.984|23335] Store DHT public IPv4 address: 72.10.136.235,
[1713886275.986|23335] [ice:0x80d3f540] Creating IceTransport session for "",
[1713886275.986|23335] [ice:0x80d3f540] Initializing the session - comp count 1 - as a slave,
[1713886275.986|23335] [ice:0x80d3f540] Add srflx reflexive candidates
[192.168.10.157:9 : 72.10.136.235:9] for comp 1,
[1713886275.986|23335] added turn server '144.217.83.140', port 3478,
[1713886276.780|23903] [ice:0x80d3f540] TCP initialization success,
[1713886276.790|23903] [ice:0x80d3f540] as slave,
[1713886276.840|23903] [ice:0x80d3f540] (local) ufrag=0f2d3440, pwd=7e170a005a534e2e76951c94,
[1713886276.870|23323] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] Added local ICE candidate Hc0a80a9d 1 TCP 2130706431 192.168.10.157 43154 typ host tcptype passive,
[1713886276.890|23323] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] Added local ICE candidate Hc0a80a9d 1 TCP 2130706175 192.168.10.157 9 typ host tcptype active,
[1713886276.920|23323] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] Added local ICE candidate H6d9b7e8e 1 TCP 2130706431 fe80::824e:81ff:fe4b:1364 52779 typ host tcptype passive,
[1713886276.950|23323] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] Added local ICE candidate H6d9b7e8e 1 TCP 2130706175 fe80::824e:81ff:fe4b:1364 9 typ host tcptype active,
[1713886276.960|23323] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] Added local ICE candidate Sc0a80a9d 1 TCP 1694498303 72.10.136.235 9 typ srflx tcptype active,
[1713886276.980|23323] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] Added local ICE candidate R90d9538c 1 TCP 16777215 144.217.83.140 15768 typ relay tcptype passive,
[1713886276.101|23323] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] Sending connection request,
[1713886277.559|23335] [device 459228dd272f39cfd3d29e9bcd0ef7931cc0348da25b38d4ac4a87ec13814b78] Sent connection request. Put encrypted ok