Microphone doesn't work on older Android (5, 6, 7 known broken, 9 known good)
Describe your environment
Please specify the following:
- Ring version: Together 2021-02-10
- Device model: Samsung Galaxy S2
- Android version: 7
- What build you are using: Play Store
This also occur on the following device/environment:
- Ring version: F-Droid ~2020-12-20
- Device model: HTC U Play
- Android version: 6
- What build you are using: F-Droid
A user also wrote having the issue only on older Android 5 and 6, but with Android 9 working: https://lists.gnu.org/archive/html/jami/2021-02/msg00021.html
Steps to reproduce
- Can you reproduce the bug: at will
- Steps:
- Place or receive a call, to any other client
- Actual result: The other party cannot hear me.
- Expected result: The other party can hear me.
Logs
Android side (receiving call)
$ logcat | grep -E 'libring|jami'
02-13 13:05:31.293 21554 21582 I libdring: [Account 2f5528c29713a8b5] using proxy server dhtproxy2.jami.net:82
02-13 13:05:31.301 21554 21582 D libdring: Bootstrap node: bootstrap.jami.net
02-13 13:07:19.366 21554 21582 I libdring: [Account 2f5528c29713a8b5] using proxy server dhtproxy2.jami.net:82
02-13 13:07:19.370 21554 21582 D libdring: Bootstrap node: bootstrap.jami.net
02-13 13:09:45.036 21554 21582 I libdring: [Account 2f5528c29713a8b5] using proxy server dhtproxy2.jami.net:82
02-13 13:09:45.042 21554 21582 D libdring: Bootstrap node: bootstrap.jami.net
Caller side (GNOME client -- known to work well)
[1613240140.883| 1040|manager.cpp :939 ] try outgoing call to 'ring:fd11be3e4a2b141b81e3856cd876d531b1f1129a' with account '5534e909984953c9'
[1613240140.883| 1040|jamiaccount.cpp :391 ] [Account 5534e909984953c9] Calling DHT peer fd11be3e4a2b141b81e3856cd876d531b1f1129a
[1613240140.883| 1040|ringbufferpool.cpp:118 ] Ringbuffer created with id '4973037265292714'
[1613240140.884| 1040|contact_list.cpp :58 ] Can't set certificate status for existing contacts fd11be3e4a2b141b81e3856cd876d531b1f1129a
[1613240140.884| 1040|call.cpp :244 ] [call:4973037265292714] state change 0/0, cnx 0/1, code 0
[1613240140.884| 1040|ringbufferpool.cpp:118 ] Ringbuffer created with id '2165141670119556'
[1613240140.884| 1040|call.cpp :436 ] [call:4973037265292714] add subcall 2165141670119556
[1613240140.884| 1040|manager.cpp :573 ] ----- Switch current call id to '4973037265292714' -----
[1613240140.884| 1064|jamiaccount.cpp :686 ] [call 4973037265292714] No channeled socket with this peer. Send request + DHT request
[1613240140.884| 1064|jamiaccount.cpp :3271 ] A SIP connection with 9dd7ebaba6eca47dce9415516ffe2430fc227370 already exists
[1613240140.884| 1064|jamiaccount.cpp :486 ] [call 4973037265292714] calling device 9dd7ebaba6eca47dce9415516ffe2430fc227370
[1613240140.884| 1064|ringbufferpool.cpp:118 ] Ringbuffer created with id '925409770932260'
[1613240140.884| 1064|ice_transport.cpp :217 ] [ice (UDP)] added host stun server
[1613240140.884| 1064|ice_transport.cpp :217 ] [ice (UDP)] added host stun server
[1613240140.884| 1064|ice_transport.cpp :279 ] [ice (UDP)] added turn server '192.252.140.236', port 3478
[1613240140.884| 1064|ice_transport.cpp :279 ] [ice (UDP)] added turn server '192.252.140.236', port 3478
[1613240140.886| 1064|ice_transport.cpp :217 ] [ice (TCP)] added host stun server
[1613240140.886| 1064|ice_transport.cpp :217 ] [ice (TCP)] added host stun server
[1613240140.886|24166|sip_utils.cpp :203 ] Registered thread 0x7f676fff74d0 (0x410)
[1613240140.887| 1064|ice_transport.cpp :279 ] [ice (TCP)] added turn server '192.252.140.236', port 3478
[1613240140.887| 1064|ice_transport.cpp :279 ] [ice (TCP)] added turn server '192.252.140.236', port 3478
[1613240140.888| 1064|call.cpp :436 ] [call:4973037265292714] add subcall 925409770932260
[1613240140.888|24167|sip_utils.cpp :203 ] Registered thread 0x7f676b7ee4d0 (0x410)
[1613240140.889| 1064|account_manager.cpp:521 ] Found 1 devices for fd11be3e4a2b141b81e3856cd876d531b1f1129a
[1613240140.889| 1064|call.cpp :244 ] [call:2165141670119556] state change 0/5, cnx 0/0, code 6
[1613240140.889| 1042|call.cpp :503 ] [call:4973037265292714] subcall 2165141670119556 failed
[1613240140.889| 1042|call.cpp :520 ] [call:4973037265292714] remains 1 subcall(s)
[1613240140.889| 1042|manager.cpp :2002 ] [call:2165141670119556] Failed
[1613240140.889| 1064|jamiaccount.cpp :1960 ] [Account 5534e909984953c9] Dht status : IPv4 connected; IPv6 connecting
[1613240140.889| 1042|manager.cpp :1624 ] [call:2165141670119556] Remove local audio
[1613240140.889| 1042|sipcall.cpp :815 ] [call:2165141670119556] removeCall()
[1613240140.889| 1042|call_factory.cpp :39 ] Removing call 2165141670119556
[1613240140.889| 1042|call_factory.cpp :43 ] Remaining 2 SIP call(s)
[1613240140.889| 1042|call.cpp :244 ] [call:2165141670119556] state change 5/6, cnx 0/0, code 0
[1613240140.906|24166|ice_transport.cpp :526 ] [ice:0x7f67b4535b60] UDP initialization success
[1613240140.906|24166|ice_transport.cpp :580 ] [ice:0x7f67b4535b60] as master
[1613240140.907|24166|ice_transport.cpp :674 ] [ice:0x7f67b4535b60] (local) ufrag=5dce9d53, pwd=615748936a34c0242b5f44db
[1613240140.916|24167|ice_transport.cpp :526 ] [ice:0x7f67b45acdd0] TCP initialization success
[1613240140.916|24167|ice_transport.cpp :580 ] [ice:0x7f67b45acdd0] as master
[1613240140.917|24167|ice_transport.cpp :674 ] [ice:0x7f67b45acdd0] (local) ufrag=3e6e1877, pwd=3fd4f5d33af692e55e49113b
[1613240140.919| 1042|jamiaccount.cpp :3039 ] [Account 5534e909984953c9] using public IP: 208.88.110.46
[1613240140.919| 1042|ice_transport.cpp :777 ] [ice:0x7f67b4535b60] succeed to add candidate for comp_id=1 : 172.16.21.54 : 208.88.110.46
[1613240140.919| 1042|sipaccountbase.cpp:576 ] [Account 5534e909984953c9] Using public address 208.88.110.46
[1613240140.919| 1042|jamiaccount.cpp :3039 ] [Account 5534e909984953c9] using public IP: 208.88.110.46
[1613240140.919| 1042|ice_transport.cpp :777 ] [ice:0x7f67b45acdd0] succeed to add candidate for comp_id=1 : 172.16.21.54 : 208.88.110.46
[1613240140.919| 1042|sipaccountbase.cpp:576 ] [Account 5534e909984953c9] Using public address 208.88.110.46
[1613240143.561| 1064|jamiaccount.cpp :574 ] Successfully put ICE descriptor on DHT
[1613240145.298| 1064|jamiaccount.cpp :591 ] ICE request for call 925409770932260 replied from DHT peer 9dd7ebaba6eca47dce9415516ffe2430fc227370
Data: 75c575132e25820b3f869e86362af2a08Hc0a80a9d 1 UDP 2130706431 192.168.10.157 33029 typ host7Sc0a80a9d 1 UDP 1694498303 72.10.141.39 21818 typ srflx8Hc0a80a9d 1 UDP 2130706431 192.168.10.157 21818 typ host8Rc0fc8cec 1 UDP 16777215 192.252.140.236 28992 typ relay16508f75429d5fbb2bad34e0190bf223HHc0a80a9d 1 TCP 2130706431 192.168.10.157 41448 typ host tcptype passiveCHc0a80a9d 1 TCP 2130706175 192.168.10.157 9 typ host tcptype activeBSc0a80a9d 1 TCP 1694498303 72.10.141.39 9 typ srflx tcptype activeCHc0a80a9d 1 TCP 2130706431 192.168.10.157 9 typ host tcptype activeHRc0fc8cec 1 TCP 16777215 192.252.140.236 22707 typ relay tcptype passive
[1613240145.298| 1064|call.cpp :244 ] [call:925409770932260] state change 0/0, cnx 0/2, code 0
[1613240145.298| 1042|call.cpp :244 ] [call:4973037265292714] state change 0/0, cnx 1/2, code 0
[1613240145.298| 1042|call.cpp :267 ] [call:4973037265292714] emit client call state change CONNECTING, code 0
[1613240145.298| 1064|ice_transport.cpp :1004 ] [ice:0x7f67b410b150] negotiation starting (4 remote candidates)
[1613240145.299| 1064|ice_transport.cpp :1004 ] [ice:0x7f67b4104980] negotiation starting (5 remote candidates)
[1613240145.311|24167|ice_transport.cpp :526 ] [ice:0x7f67b45acdd0] TCP negotiation success
[1613240145.311|24167|ice_transport.cpp :567 ] [ice:0x7f67b45acdd0] TCP connection pairs (local <-> remote):
[0] 192.168.10.15:55151 <-> 192.168.10.157:41069
[1613240145.311| 1042|jamiaccount.cpp :1535 ] [call:925409770932260] TCP negotiation is ready
[1613240145.311| 1042|sips_transport_ice.cpp:135 ] SipIceTransport@0x7f67c81dbe50 {tr=0x7f67c81dbec8}
[1613240145.311| 1042|siptransport.cpp :76 ] SipTransport@0x7f67c83a2f10 {tr=0x7f67c81dbec8 {rc=1}}
[1613240145.311|24204|tls_session.cpp :771 ] [TLS] Start client session
[1613240145.311| 1042|call.cpp :244 ] [call:925409770932260] state change 0/0, cnx 2/2, code 0
[1613240145.311|24147|ice_transport.cpp :414 ] [ice:0x7f67b4535b60] destroying
[1613240145.312|24204|tls_session.cpp :450 ] [TLS] Peer CA list 2 (0 CRLs): 2
[1613240145.315|24166|ice_transport.cpp :526 ] [ice:0x7f67b4535b60] UDP negotiation success
[1613240145.315|24166|ice_transport.cpp :567 ] [ice:0x7f67b4535b60] UDP connection pairs (local <-> remote):
[0] 192.168.10.15:46784 <-> 192.168.10.157:33029
[1613240145.339|24204|tls_session.cpp :468 ] [TLS] User identity loaded
[1613240145.340|24204|tls_session.cpp :866 ] [TLS] handshake
[1613240145.340|24204|sip_utils.cpp :203 ] Registered thread 0x7f6766fed4d0 (0x410)
[1613240145.543|24204|account_manager.cpp:286 ] Found peer device: 9dd7ebaba6eca47dce9415516ffe2430fc227370 account:fd11be3e4a2b141b81e3856cd876d531b1f1129a CA:afa5c63e84df9a3b1e584298a17961fc057b9530
[1613240145.544|24204|jamiaccount.cpp :1487 ] [peer:9dd7ebaba6eca47dce9415516ffe2430fc227370] Certificate verified
[1613240145.570|24204|tls_session.cpp :903 ] [TLS] session established: (TLS1.3)-(ECDHE-SECP384R1)-(RSA-PSS-RSAE-SHA384)-(AES-256-GCM)
[1613240145.571|24204|sips_transport_ice.cpp:515 ] [TLS] using cipher TLS_ECDHE_RSA_AES_256_GCM_SHA384 (0xC030)
[1613240145.571|24202|siptransport.cpp :203 ] pjsip transport@0x7f67c81dbec8 TLS to 192.168.10.157 -> CONNECTED
[1613240145.571|24202|jamiaccount.cpp :707 ] [call:925409770932260] outgoing call connected to 9dd7ebaba6eca47dce9415516ffe2430fc227370
[1613240145.571|24202|sipcall.cpp :1437 ] [call:925409770932260] create media ICE transport
[1613240145.571|24202|sip_utils.cpp :203 ] Registered thread 0x7f6768fe94d0 (0x410)
[1613240145.571|24202|ice_transport.cpp :217 ] [ice (UDP)] added host stun server
[1613240145.571|24202|ice_transport.cpp :217 ] [ice (UDP)] added host stun server
[1613240145.571|24202|ice_transport.cpp :279 ] [ice (UDP)] added turn server '192.252.140.236', port 3478
[1613240145.571|24202|ice_transport.cpp :279 ] [ice (UDP)] added turn server '192.252.140.236', port 3478
[1613240145.577|24208|sip_utils.cpp :203 ] Registered thread 0x7f6765feb4d0 (0x410)
[1613240145.578|24202|sdp.cpp :363 ] SDP: Local SDP Session:
v=0
o=hurd 3822228945 0 IN IP4 172.16.21.54
s=Jami Daemon
c=IN IP4 172.16.21.54
t=0 0
m=audio 31556 RTP/SAVP 104 101
a=rtpmap:104 opus/48000/2
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtcp:31557 IN IP4 172.16.21.54
a=sendrecv
m=video 64108 RTP/SAVP 96 97
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=428029
a=rtpmap:97 VP8/90000
a=sendrecv
[1613240145.606|24208|ice_transport.cpp :526 ] [ice:0x7f6798086960] UDP initialization success
[1613240145.606|24208|ice_transport.cpp :580 ] [ice:0x7f6798086960] as master
[1613240145.606|24208|ice_transport.cpp :674 ] [ice:0x7f6798086960] (local) ufrag=4fc76ce9, pwd=7f3b74e00da0ee271bc88245
[1613240145.607|24202|ice_transport.cpp :777 ] [ice:0x7f6798086960] succeed to add candidate for comp_id=1 : 172.16.21.54 : 208.88.110.46
[1613240145.608|24202|ice_transport.cpp :777 ] [ice:0x7f6798086960] succeed to add candidate for comp_id=2 : 172.16.21.54 : 208.88.110.46
[1613240145.608|24202|ice_transport.cpp :777 ] [ice:0x7f6798086960] succeed to add candidate for comp_id=3 : 172.16.21.54 : 208.88.110.46
[1613240145.608|24202|ice_transport.cpp :777 ] [ice:0x7f6798086960] succeed to add candidate for comp_id=4 : 172.16.21.54 : 208.88.110.46
[1613240145.608|24202|sipcall.cpp :924 ] [call:925409770932260] fill SDP with ICE transport 0x7f6798083580
[1613240145.609|24202|jamiaccount.cpp :778 ] contact header: "" <sips:2664391145a3d178de9fa19a99a5824b46491066@192.168.10.15:55151;transport=tls> / <sip:d873d0ebe6c3b57f77a362fe867dd03a4f9efb76@ring.dht> -> <sips:9dd7ebaba6eca47dce9415516ffe2430fc227370@192.168.10.157:41069;transport=tls> / <sips:192.168.10.157:41069;transport=tls>
[1613240145.609|24202|jamiaccount.cpp :814 ] [call:925409770932260] Sending SIP invite
[1613240145.609|24202|sipvoiplink.cpp :779 ] [call:925409770932260] INVITE@0x7f67981a9938 state changed to 1 (CALLING): cause=0, tsx@0x7f67981b1f28 status 0 (Default status message)
[1613240145.609|24202|call.cpp :244 ] [call:925409770932260] state change 0/1, cnx 2/2, code 0
[1613240145.609| 1042|call.cpp :244 ] [call:4973037265292714] state change 0/1, cnx 2/2, code 0
[1613240145.672|24202|sipvoiplink.cpp :779 ] [call:925409770932260] INVITE@0x7f67981a9938 state changed to 3 (EARLY): cause=0, tsx@0x7f67981b1f28 status 180 (Ringing)
[1613240145.672|24202|call.cpp :244 ] [call:925409770932260] state change 1/1, cnx 2/3, code 0
[1613240145.672| 1042|manager.cpp :1951 ] [call:925409770932260] Peer ringing
[1613240145.672| 1042|call.cpp :244 ] [call:4973037265292714] state change 1/1, cnx 2/3, code 0
[1613240145.672| 1042|call.cpp :267 ] [call:4973037265292714] emit client call state change RINGING, code 0
[1613240145.672|24209|alsalayer.cpp :182 ] Alsa: Opening default
[1613240145.672| 1042|manager.cpp :1951 ] [call:4973037265292714] Peer ringing
[1613240145.674|24209|alsalayer.cpp :383 ] Buffer size range from 96 to 1048576
[1613240145.674|24209|alsalayer.cpp :384 ] Period size range from 32 to 349526
[1613240145.676|24209|alsalayer.cpp :399 ] Was set period_size = 160
[1613240145.676|24209|alsalayer.cpp :400 ] Was set buffer_size = 1280
[1613240145.676|24209|alsalayer.cpp :411 ] capture using format {s16, 2 channels, 16000Hz}
[1613240145.676|24209|alsalayer.cpp :182 ] Alsa: Opening default
[1613240145.678|24209|alsalayer.cpp :383 ] Buffer size range from 96 to 1048576
[1613240145.678|24209|alsalayer.cpp :384 ] Period size range from 32 to 349526
[1613240145.680|24209|alsalayer.cpp :399 ] Was set period_size = 160
[1613240145.680|24209|alsalayer.cpp :400 ] Was set buffer_size = 1280
[1613240145.680|24209|alsalayer.cpp :411 ] playback using format {s16, 2 channels, 16000Hz}
[1613240145.680|24209|audiolayer.cpp :123 ] Hardware audio format available : {s16, 2 channels, 16000Hz} 0
[1613240145.680|24209|audiolayer.cpp :131 ] Hardware input audio format available : {s16, 2 channels, 16000Hz}
[1613240148.860| 1064|jamiaccount.cpp :1960 ] [Account 5534e909984953c9] Dht status : IPv4 connected; IPv6 disconnected
[1613240149.659|24202|sipvoiplink.cpp :779 ] [call:925409770932260] INVITE@0x7f67981a9938 state changed to 4 (CONNECTING): cause=0, tsx@0x7f67981b1f28 status 200 (OK)
[1613240149.659|24202|sipvoiplink.cpp :933 ] [call:925409770932260] INVITE@0x7f67981a9938 media update: status 0
[1613240149.659|24202|sdp.cpp :363 ] Local active SDP Session:
v=0
o=hurd 3822228945 0 IN IP4 172.16.21.54
s=Jami Daemon
c=IN IP4 172.16.21.54
t=0 0
a=ice-ufrag:4fc76ce9
a=ice-pwd:7f3b74e00da0ee271bc88245
m=audio 31556 RTP/SAVP 104 101
a=rtpmap:104 opus/48000/2
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtcp:31557 IN IP4 172.16.21.54
a=sendrecv
a=candidate:H789f6488 1 UDP 2130706431 fe80::7cfa:e62f:22a7:8670 35650 typ host
a=candidate:Hac101536 1 UDP 2130706431 172.16.21.54 56872 typ host
a=candidate:Hc0a80a0f 1 UDP 2130706431 192.168.10.15 56872 typ host
a=candidate:Rc0fc8cec 1 UDP 16777215 192.252.140.236 13649 typ relay
a=candidate:Rc0fc8cec 1 UDP 16777215 192.252.140.236 13560 typ relay
a=candidate:Sac101536 1 UDP 1694498815 208.88.110.46 56872 typ srflx
a=candidate:H789f6488 2 UDP 2130706430 fe80::7cfa:e62f:22a7:8670 47983 typ host
a=candidate:Hac101536 2 UDP 2130706430 172.16.21.54 46450 typ host
a=candidate:Hc0a80a0f 2 UDP 2130706430 192.168.10.15 46450 typ host
a=candidate:Rc0fc8cec 2 UDP 16777214 192.252.140.236 16376 typ relay
a=candidate:Rc0fc8cec 2 UDP 16777214 192.252.140.236 26728 typ relay
a=candidate:Sac101536 2 UDP 1694498814 208.88.110.46 46450 typ srflx
m=video 64108 RTP/SAVP 96
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=428029
a=sendrecv
a=candidate:H789f6488 3 UDP 2130706429 fe80::7cfa:e62f:22a7:8670 48416 typ host
a=candidate:Hac101536 3 UDP 2130706429 172.16.21.54 38404 typ host
a=candidate:Hc0a80a0f 3 UDP 2130706429 192.168.10.15 38404 typ host
a=candidate:Rc0fc8cec 3 UDP 16777213 192.252.140.236 15292 typ relay
a=candidate:Rc0fc8cec 3 UDP 16777213 192.252.140.236 25278 typ relay
a=candidate:Sac101536 3 UDP 1694498813 208.88.110.46 38404 typ srflx
a=candidate:H789f6488 4 UDP 2130706428 fe80::7cfa:e62f:22a7:8670 40364 typ host
a=candidate:Hac101536 4 UDP 2130706428 172.16.21.54 57532 typ host
a=candidate:Hc0a80a0f 4 UDP 2130706428 192.168.10.15 57532 typ host
a=candidate:Rc0fc8cec 4 UDP 16777212 192.252.140.236 11150 typ relay
a=candidate:Rc0fc8cec 4 UDP 16777212 192.252.140.236 25752 typ relay
a=candidate:Sac101536 4 UDP 1694498812 208.88.110.46 57532 typ srflx
[1613240149.660|24202|sdp.cpp :363 ] Remote active SDP Session:
v=0
o=localhost 3822228951 1 IN IP4 192.168.10.157
s=Jami Daemon
c=IN IP4 192.168.10.157
t=0 0
a=ice-ufrag:6b4ae4dc
a=ice-pwd:5d847623321c0e81582e4ca2
m=audio 18486 RTP/SAVP 104 101
a=rtpmap:104 opus/48000/2
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtcp:18487 IN IP4 192.168.10.157
a=sendrecv
a=candidate:Hc0a80a9d 1 UDP 2130706431 192.168.10.157 45901 typ host
a=candidate:Sc0a80a9d 1 UDP 1694498303 72.10.141.39 24657 typ srflx
a=candidate:Hc0a80a9d 1 UDP 2130706431 192.168.10.157 24657 typ host
a=candidate:Rc0fc8cec 1 UDP 16777215 192.252.140.236 25937 typ relay
a=candidate:Hc0a80a9d 2 UDP 2130706430 192.168.10.157 37946 typ host
a=candidate:Sc0a80a9d 2 UDP 1694498302 72.10.141.39 20122 typ srflx
a=candidate:Hc0a80a9d 2 UDP 2130706430 192.168.10.157 20122 typ host
a=candidate:Rc0fc8cec 2 UDP 16777214 192.252.140.236 13972 typ relay
m=video 50308 RTP/SAVP 96
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=428029
a=sendrecv
a=candidate:Hc0a80a9d 3 UDP 2130706429 192.168.10.157 40589 typ host
a=candidate:Sc0a80a9d 3 UDP 1694498301 72.10.141.39 22937 typ srflx
a=candidate:Hc0a80a9d 3 UDP 2130706429 192.168.10.157 22937 typ host
a=candidate:Rc0fc8cec 3 UDP 16777213 192.252.140.236 11352 typ relay
a=candidate:Hc0a80a9d 4 UDP 2130706428 192.168.10.157 43120 typ host
a=candidate:Sc0a80a9d 4 UDP 1694498300 72.10.141.39 21381 typ srflx
a=candidate:Hc0a80a9d 4 UDP 2130706428 192.168.10.157 21381 typ host
a=candidate:Rc0fc8cec 4 UDP 16777212 192.252.140.236 22679 typ relay
[1613240149.660|24202|sipcall.cpp :1184 ] [call:925409770932260] medias changed
[1613240149.660|24202|sipvoiplink.cpp :784 ] [call:925409770932260] INVITE@0x7f67981a9938 state changed to 5 (CONFIRMED): cause=0 (TX_MSG)
[1613240149.660|24202|sipcall.cpp :871 ] [call:925409770932260] onAnswered()
[1613240149.660| 1042|call.cpp :244 ] [call:925409770932260] state change 1/1, cnx 3/4, code 0
[1613240149.660| 1042|call.cpp :478 ] [call:4973037265292714] subcall 925409770932260 answered by peer
[1613240149.660| 1042|sipcall.cpp :1449 ] [sipcall:4973037265292714] merge subcall 925409770932260
[1613240149.660| 1042|call.cpp :541 ] [call:4973037265292714] merge subcall 925409770932260
[1613240149.660| 1042|call.cpp :244 ] [call:4973037265292714] state change 1/1, cnx 3/4, code 0
[1613240149.660| 1042|call.cpp :267 ] [call:4973037265292714] emit client call state change CURRENT, code 0
[1613240149.660| 1042|manager.cpp :1929 ] [call:4973037265292714] Peer answered
[1613240149.660| 1042|manager.cpp :1604 ] [call:4973037265292714] Attach audio
[1613240149.660| 1042|ringbufferpool.cpp:156 ] Bind rbuf '4973037265292714' to callid 'audiolayer_id'
[1613240149.660| 1042|ringbufferpool.cpp:156 ] Bind rbuf 'audiolayer_id' to callid '4973037265292714'
[1613240149.660| 1042|sipcall.cpp :815 ] [call:925409770932260] removeCall()
[1613240149.660| 1042|call_factory.cpp :39 ] Removing call 925409770932260
[1613240149.660| 1042|call_factory.cpp :43 ] Remaining 1 SIP call(s)
[1613240149.660| 1042|call.cpp :244 ] [call:925409770932260] state change 1/6, cnx 4/4, code 0
[1613240149.660| 1042|sipcall.cpp :947 ] [call:4973037265292714] add remote ICE candidate: Hc0a80a9d 1 UDP 2130706431 192.168.10.157 45901 typ host
[1613240149.660| 1042|sipcall.cpp :947 ] [call:4973037265292714] add remote ICE candidate: Sc0a80a9d 1 UDP 1694498303 72.10.141.39 24657 typ srflx
[1613240149.660| 1042|sipcall.cpp :947 ] [call:4973037265292714] add remote ICE candidate: Hc0a80a9d 1 UDP 2130706431 192.168.10.157 24657 typ host
[1613240149.660| 1042|sipcall.cpp :947 ] [call:4973037265292714] add remote ICE candidate: Rc0fc8cec 1 UDP 16777215 192.252.140.236 25937 typ relay
[1613240149.660| 1042|sipcall.cpp :947 ] [call:4973037265292714] add remote ICE candidate: Hc0a80a9d 2 UDP 2130706430 192.168.10.157 37946 typ host
[1613240149.660| 1042|sipcall.cpp :947 ] [call:4973037265292714] add remote ICE candidate: Sc0a80a9d 2 UDP 1694498302 72.10.141.39 20122 typ srflx
[1613240149.660| 1042|sipcall.cpp :947 ] [call:4973037265292714] add remote ICE candidate: Hc0a80a9d 2 UDP 2130706430 192.168.10.157 20122 typ host
[1613240149.660| 1042|sipcall.cpp :947 ] [call:4973037265292714] add remote ICE candidate: Rc0fc8cec 2 UDP 16777214 192.252.140.236 13972 typ relay
[1613240149.660| 1042|sipcall.cpp :947 ] [call:4973037265292714] add remote ICE candidate: Hc0a80a9d 3 UDP 2130706429 192.168.10.157 40589 typ host
[1613240149.660| 1042|sipcall.cpp :947 ] [call:4973037265292714] add remote ICE candidate: Sc0a80a9d 3 UDP 1694498301 72.10.141.39 22937 typ srflx
[1613240149.660| 1042|sipcall.cpp :947 ] [call:4973037265292714] add remote ICE candidate: Hc0a80a9d 3 UDP 2130706429 192.168.10.157 22937 typ host
[1613240149.660| 1042|sipcall.cpp :947 ] [call:4973037265292714] add remote ICE candidate: Rc0fc8cec 3 UDP 16777213 192.252.140.236 11352 typ relay
[1613240149.660| 1042|sipcall.cpp :947 ] [call:4973037265292714] add remote ICE candidate: Hc0a80a9d 4 UDP 2130706428 192.168.10.157 43120 typ host
[1613240149.660| 1042|sipcall.cpp :947 ] [call:4973037265292714] add remote ICE candidate: Sc0a80a9d 4 UDP 1694498300 72.10.141.39 21381 typ srflx
[1613240149.661| 1042|sipcall.cpp :947 ] [call:4973037265292714] add remote ICE candidate: Hc0a80a9d 4 UDP 2130706428 192.168.10.157 21381 typ host
[1613240149.661| 1042|sipcall.cpp :947 ] [call:4973037265292714] add remote ICE candidate: Rc0fc8cec 4 UDP 16777212 192.252.140.236 22679 typ relay
[1613240149.661| 1042|ice_transport.cpp :979 ] [ice:0x7f6798083580] negotiation starting (16 remote candidates)
[1613240149.737|24208|ice_transport.cpp :526 ] [ice:0x7f6798086960] UDP negotiation success
[1613240149.737|24208|ice_transport.cpp :567 ] [ice:0x7f6798086960] UDP connection pairs (local <-> remote):
[0] 192.168.10.15:56872 <-> 192.168.10.157:45901
[1] 192.168.10.15:46450 <-> 192.168.10.157:37946
[2] 192.168.10.15:38404 <-> 192.168.10.157:40589
[3] 192.168.10.15:57532 <-> 192.168.10.157:43120
[1613240149.751| 1042|sipcall.cpp :1141 ] [call:4973037265292714] stopping all medias
[1613240149.751| 1042|sipcall.cpp :980 ] [call:4973037265292714] startAllMedia()
[1613240149.751| 1042|audio_input.cpp :53 ] Creating audio input with id: 4973037265292714
[1613240149.751| 1042|audio_input.cpp :265 ] Switching audio source to match 'camera:///dev/video0'
[1613240149.751| 1042|audio_sender.cpp :72 ] audioEncoder_->openOutput rtp://192.168.10.157:18486
[1613240149.751| 1042|media_encoder.cpp :251 ] Not using hardware encoding for opus
[1613240149.752| 1042|media_encoder.cpp :549 ] [libopus] Using 4 threads
[1613240149.752| 1042|media_encoder.cpp :593 ] [libopus] Frame size 960
[libopus @ 0x7f67c83d79c0] No bit rate set. Defaulting to 96000 bps.
[1613240149.752| 1042|video_input.cpp :508 ] MRL: 'camera:///dev/video0'
[1613240149.752|24224|media_decoder.cpp :129 ] Trying to open device dummyFilename with format sdp, pixel format , size 0x0, rate 0.000000
[1613240149.752|24224|media_decoder.cpp :141 ] Using format sdp
[1613240149.752|24223|audio_input.cpp :77 ] Switching audio input to '/dev/video0'
[1613240149.753|24223|sip_utils.cpp :203 ] Registered thread 0x7f676fff74d0 (0x410)
[1613240149.753|24227|media_decoder.cpp :129 ] Trying to open device dummyFilename with format sdp, pixel format , size 0x0, rate 0.000000
[1613240149.753|24227|media_decoder.cpp :141 ] Using format sdp
[1613240149.754|24225|sinkclient.cpp :195 ] ShmHolder[Jami Daemon_shm_1040_0]: new sizes: f=0, a=103
[1613240149.754|24225|sinkclient.cpp :161 ] ShmHolder: new holder 'Jami Daemon_shm_1040_0'
[1613240149.754|24225|video_input.cpp :169 ] VideoInput ready to capture
[1613240149.754|24225|media_decoder.cpp :129 ] Trying to open device /dev/video0 with format video4linux2, pixel format mjpeg, size 1280x720, rate 30.000000
ALSA lib pcm.c:8545:(snd_pcm_recover) underrun occurred
[1613240149.918|24225|media_decoder.cpp :141 ] Using format video4linux2
[1613240150.024|24225|media_decoder.cpp :463 ] Decoding video using MJPEG (Motion JPEG) (mjpeg)
[1613240150.024|24225|media_decoder.cpp :472 ] Not using hardware decoding for mjpeg
[1613240150.034|24225|media_encoder.cpp :251 ] Not using hardware encoding for h264
[1613240150.034|24225|media_encoder.cpp :728 ] Hardware encoding disabled
[1613240150.034|24225|media_encoder.cpp :549 ] [libx264] Using 4 threads
[1613240150.034|24225|media_encoder.cpp :675 ] Using profile Constrained Baseline (242) and level 41
[1613240150.034|24225|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=1200 kbit/s
[1613240150.072|24225|sip_utils.cpp :203 ] Registered thread 0x7f67427ec4d0 (0x410)
[1613240150.072|24225|sinkclient.cpp :418 ] Start sink <local / Jami Daemon_shm_1040_0>, size=1280x720, mixer=0
[1613240150.072|24225|sinkclient.cpp :195 ] ShmHolder[Jami Daemon_shm_1040_0]: new sizes: f=3686400, a=7372903
[1613240150.078|24225|video_input.cpp :326 ] created decoder with video params : size=1280X720, fps=30.000000 pix=yuvj422p
[1613240150.078|24225|sinkclient.cpp :418 ] Start sink <local / Jami Daemon_shm_1040_0>, size=1280x720, mixer=0
[1613240150.856|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=1260 kbit/s
[1613240150.976|24202|sipvoiplink.cpp :1107 ] [INVITE:0x7f67981a9938] RX SIP method 6 (INFO)
[1613240150.976|24202|sipvoiplink.cpp :994 ] Rotate video 90 deg.
[1613240151.139|24227|sip_utils.cpp :203 ] Registered thread 0x7f67417ea4d0 (0x410)
[1613240151.477|24227|media_decoder.cpp :463 ] Decoding video using H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 (h264)
[1613240151.477|24227|media_decoder.cpp :472 ] Not using hardware decoding for h264
[1613240151.478|24227|sinkclient.cpp :195 ] ShmHolder[Jami Daemon_shm_1040_1]: new sizes: f=0, a=103
[1613240151.478|24227|sinkclient.cpp :161 ] ShmHolder: new holder 'Jami Daemon_shm_1040_1'
[1613240151.478|24227|sinkclient.cpp :418 ] Start sink <4973037265292714 / Jami Daemon_shm_1040_1>, size=720x480, mixer=0
[1613240151.487|24227|filter_transpose.cpp:30 ] Rotation set to 90
[1613240151.489|24227|media_filter.cpp :108 ] Filter graph initialized with: [in] transpose=2
[1613240151.489|24227|media_filter.cpp :108 ] Filter graph initialized with: [in] transpose=2
[1613240151.489|24227|media_filter.cpp :322 ] Filter graph reinitialized
[1613240151.490|24227|sinkclient.cpp :423 ] Stop sink <4973037265292714 / Jami Daemon_shm_1040_1>, mixer=0
[1613240151.490|24227|sinkclient.cpp :418 ] Start sink <4973037265292714 / Jami Daemon_shm_1040_1>, size=480x720, mixer=0
[1613240151.490|24227|sinkclient.cpp :195 ] ShmHolder[Jami Daemon_shm_1040_1]: new sizes: f=1382400, a=2764903
[1613240151.868|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=1323 kbit/s
[1613240152.893|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=1389 kbit/s
[1613240153.951|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=1458 kbit/s
[1613240154.968|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=1530 kbit/s
[1613240156.027|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=1606 kbit/s
[1613240157.050|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=1686 kbit/s
[1613240158.083|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=1770 kbit/s
[1613240159.075|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=1858 kbit/s
[1613240160.096|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=1950 kbit/s
[1613240161.104|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=2047 kbit/s
[1613240162.100|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=2149 kbit/s
[1613240163.102|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=2256 kbit/s
[1613240164.127|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=2368 kbit/s
[1613240165.176|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=2486 kbit/s
[1613240166.212|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=2610 kbit/s
[1613240167.237|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=2740 kbit/s
[1613240168.248|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=2876 kbit/s
[1613240169.269|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=3019 kbit/s
[1613240170.280|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=3169 kbit/s
[1613240171.300|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=3327 kbit/s
[1613240172.345|24226|media_encoder.cpp :839 ] H264 encoder setup cbr: bitrate=3493 kbit/s
[1613240173.209| 1040|manager.cpp :1624 ] [call:4973037265292714] Remove local audio
[1613240173.209| 1040|sipcall.cpp :350 ] [call:4973037265292714] Terminate SIP session
[1613240173.211| 1040|sipcall.cpp :1141 ] [call:4973037265292714] stopping all medias
[1613240173.212|24224|media_decoder.cpp :463 ] Decoding audio using Opus (opus)
[1613240173.212|24224|media_decoder.cpp :472 ] Not using hardware decoding for opus
[libopus @ 0x7f67c83d79c0] 1 frames left in the queue on closing
[1613240173.215|24227|media_decoder.cpp :318 ] Couldn't read frame: Operation not permitted
[1613240173.215|24227|sinkclient.cpp :423 ] Stop sink <4973037265292714 / Jami Daemon_shm_1040_1>, mixer=0
[1613240173.219| 1040|observer.h :205 ] PublishMapSubject: detaching observers
[1613240173.355|24225|sinkclient.cpp :423 ] Stop sink <local / Jami Daemon_shm_1040_0>, mixer=0
[1613240173.356|24225|video_input.cpp :207 ] VideoInput closed
[1613240173.357| 1040|observer.h :205 ] PublishMapSubject: detaching observers
[1613240173.357| 1040|call.cpp :244 ] [call:4973037265292714] state change 1/1, cnx 4/0, code 0
[1613240173.357| 1040|call.cpp :267 ] [call:4973037265292714] emit client call state change HUNGUP, code 0
[1613240173.357|23963|sipcall.cpp :815 ] [call:4973037265292714] removeCall()
[1613240173.357|23963|call_factory.cpp :39 ] Removing call 4973037265292714
[1613240173.357|23963|call_factory.cpp :43 ] Remaining 0 SIP call(s)
[1613240173.357|23963|call.cpp :244 ] [call:4973037265292714] state change 1/6, cnx 0/0, code 0
[1613240173.357|23963|call.cpp :267 ] [call:4973037265292714] emit client call state change OVER, code 0
[1613240173.357|23963|ice_transport.cpp :414 ] [ice:0x7f6798086960] destroying
[1613240176.490|23963|siptransport.cpp :89 ] ~SipTransport@0x7f67c83a2f10 {tr=0x7f67c81dbec8 {rc=14}}
[1613240176.493|23963|observer.h :216 ] ~PublishMapSubject()
[1613240176.493|23963|observer.h :205 ] PublishMapSubject: detaching observers
[1613240176.493|23963|observer.h :216 ] ~PublishMapSubject()
[1613240176.493|23963|observer.h :205 ] PublishMapSubject: detaching observers
[1613240177.048|24207|tls_session.cpp :1353 ] [TLS] eof
[1613240177.048|24207|sips_transport_ice.cpp:726 ] [SIPS] eof
[1613240177.048|24204|tls_session.cpp :1226 ] [TLS] shutdown
[1613240177.048|24202|sips_transport_ice.cpp:304 ] [SIPS] got disconnected event!
[1613240177.048|24202|sips_transport_ice.cpp:385 ] [SIPS] process disconnect event
[1613240177.048|24202|siptransport.cpp :203 ] pjsip transport@0x7f67c81dbec8 TLS to 192.168.10.157 -> DISCONNECTED