linphone-desktop
linphone-desktop copied to clipboard
Published
20 hours ago •
BelledonneCommunications
BelledonneCommunications
Outgoing calls no audio (Fritzbox)
Im using linphone 4.1.1 on linux using flatpak. incoming calls are working fine, but outgoing calls are not working, there is no audio and they terminate after 30 seconds.
only thing i noticed is that outgoing calls seem to utilize stun.linphone.org while incoming calls do not. and the outgoing call did not receive any packets in the statistics. however if i block stun.linphone.org/sip.linphone.org using
127.0.0.1 stun.linphone.org
127.0.0.1 sip.linphone.org
in /etc/hosts outgoing calls are working. so its possible that this stun stuff is messing something up.
i have left the settings at the default, i only had to disable ipv6 in the settings in order to get a connection to the fritzbox 6490. On linphone 3.11.2 both incoming and outgoing work.
Can you point out what is going wrong here and how to fix it?
here are the logs (i terminated the calls some secs after they established). Thank You.
Outgoing call (Not working):
[11:21:32:244][Info]Core:linphone: Found media local-ip from signaling.
[11:21:32:244][Info]Core:linphone: New LinphoneCall [0x1975550] initialized (LinphoneCore version: 3.12.0)
[11:21:32:244][Info]Core:linphone: Linphone core [0x7f27f4009780] notified [call_created]
[11:21:32:244][Info]Core:linphone: Call 0x1975550: moving from state LinphoneCallIdle to LinphoneCallOutgoingInit
[11:21:32:283][0xba7da0][Info]/run/build/linphone-qt/src/components/calls/CallsListModel.cpp:187: "Add call:" CallModel(0x1a91040)
[11:21:32:283][Info]Core:belle-sip: QT: /run/build/linphone-qt/src/components/calls/CallsListModel.cpp:187: "Add call:" CallModel(0x1a91040)
[11:21:32:289][Info]Core:linphone: Linphone core [0x7f27f4009780] notified [call_state_changed]
[11:21:32:289][Info]Core:linphone: Call [0x1975550], stream type [audio], multicast role is [inactive]
[11:21:32:289][Info]Core:linphone: RtpSession bound to [0.0.0.0] ports [7078] [7079]
[11:21:32:289][Info]Core:linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION
[11:21:32:289][Info]Core:linphone: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80
[11:21:32:289][Info]Core:linphone: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32
[11:21:32:289][Info]Core:linphone: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80
[11:21:32:289][Info]Core:linphone: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32
[11:21:32:289][Info]Core:linphone: Creating ZRTP engine on rtp session [0x1b03c70] ssrc 0xd586ba97
[11:21:32:290][Info]Core:linphone: Setting DSCP to 46 for MSAudio stream.
[11:21:32:290][Info]Core:linphone: Equalizer location: hp
[11:21:32:290][Info]Core:linphone: cannot set noise gate mode to [0] because no volume send
[11:21:32:290][Info]Core:linphone: Call [0x1975550], stream type [video], multicast role is [inactive]
[11:21:32:290][Info]Core:linphone: RtpSession bound to [0.0.0.0] ports [9078] [9079]
[11:21:32:290][Info]Core:linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION
[11:21:32:290][Info]Core:linphone: Initializing multistream ZRTP context on rtp session [0x1ad3b30] ssrc 0x6e915586
[11:21:32:290][Info]Core:linphone: Setting DSCP to 0 for MSVideo stream.
[11:21:32:290][Info]Core:linphone: Call [0x1975550], stream type [text], multicast role is [inactive]
[11:21:32:290][Info]Core:linphone: RtpSession bound to [0.0.0.0] ports [11078] [11079]
[11:21:32:290][Info]Core:linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION
[11:21:32:290][Info]Core:linphone: Created new ICE check list for stream [0]
[11:21:32:290][Info]Core:linphone: ms_filter_link: MSRtpRecv:0x1ba1590,0-->MSVoidSink:0x1bb0250,0
[11:21:32:290][Info]Core:linphone: Priority used: 99
[11:21:32:290][Info]Core:linphone: MSAudio MSTicker priority set to SCHED_RR and value (99)
[11:21:32:290][Info]Core:belle-sip: Resolver is using DNS server(s):
[11:21:32:290][Info]Core:belle-sip: 192.168.42.1
[11:21:32:290][Info]Core:belle-sip: 192.168.42.1
[11:21:32:290][Info]Core:belle-sip: resolver_process_data dns_res_check() in progress
[11:21:32:290][Info]Core:belle-sip: DNS resolution awaiting response, queued to main loop
[11:21:32:340][Info]Core:belle-sip: SRV _stun._udp.stun.linphone.org resolved to [target:stun.linphone.org. port:3478 prio:0 weight:100]
[11:21:32:340][Info]Core:belle-sip: SRV _stun._udp.stun.linphone.org resolved to [target:sip1.linphone.org. port:3478 prio:10 weight:100]
[11:21:32:340][Info]Core:belle-sip: Starting A/AAAA query for srv result [stun.linphone.org.]
[11:21:32:340][Info]Core:belle-sip: Resolver is using DNS server(s):
[11:21:32:340][Info]Core:belle-sip: 192.168.42.1
[11:21:32:340][Info]Core:belle-sip: 192.168.42.1
[11:21:32:341][Info]Core:belle-sip: resolver_process_data dns_res_check() in progress
[11:21:32:341][Info]Core:belle-sip: DNS resolution awaiting response, queued to main loop
[11:21:32:341][Info]Core:belle-sip: Starting A/AAAA query for srv result [sip1.linphone.org.]
[11:21:32:341][Info]Core:belle-sip: Resolver is using DNS server(s):
[11:21:32:341][Info]Core:belle-sip: 192.168.42.1
[11:21:32:341][Info]Core:belle-sip: 192.168.42.1
[11:21:32:341][Info]Core:belle-sip: resolver_process_data dns_res_check() in progress
[11:21:32:341][Info]Core:belle-sip: DNS resolution awaiting response, queued to main loop
[11:21:32:391][Info]Core:belle-sip: sip1.linphone.org. resolved to 91.121.209.194
[11:21:32:391][Info]Core:belle-sip: A query finished for srv result [sip1.linphone.org.]
[11:21:32:391][Info]Core:belle-sip: stun.linphone.org. resolved to 37.59.51.72
[11:21:32:391][Info]Core:belle-sip: A query finished for srv result [stun.linphone.org.]
[11:21:32:391][Info]Core:belle-sip: All A/AAAA results for combined resolution have arrived.
[11:21:32:391][Info]Core:linphone: Stun server resolution successful.
[11:21:32:441][Info]Core:linphone: ICE: gathering candidate from [stun.linphone.org] using STUN
[11:21:32:441][Info]Core:linphone: ice: Send STUN binding request: 0.0.0.0:7078 --> 37.59.51.72:3478 [57ce7729fe2c0aa8ab3fe77f]
[11:21:32:450][0xba7da0][Warning]:-1: <Unknown File>: QML VisualDataModel: Error creating delegate
[11:21:32:450][Warning]Core:belle-sip: QT: :-1: <Unknown File>: QML VisualDataModel: Error creating delegate
[11:21:32:480][Info]Core:linphone: First estimation
[11:21:32:651][Info]Core:linphone: ice: Send STUN binding request: 0.0.0.0:7078 --> 37.59.51.72:3478 [3e1de85159fdf61bc0d0d6a5]
[11:21:32:651][Info]Core:linphone: ice: Send STUN binding request: 0.0.0.0:7079 --> 37.59.51.72:3478 [fa71d908c9b0bc746c70dba6]
[11:21:32:651][Info]Core:linphone: ice: Recv binding response: 192.168.42.2:7078 <-- 37.59.51.72:3478 [57ce7729fe2c0aa8ab3fe77f]
[11:21:32:651][Info]Core:linphone: ice: Add candidate obtained by STUN/TURN: 187.24.5.131:7078:srflx
[11:21:32:691][Info]Core:linphone: ice: Recv binding response: 192.168.42.2:7078 <-- 37.59.51.72:3478 [3e1de85159fdf61bc0d0d6a5]
[11:21:32:691][Info]Core:linphone: ice: Add candidate obtained by STUN/TURN: 187.24.5.131:7078:srflx
[11:21:32:711][Info]Core:linphone: ice: Recv binding response: 192.168.42.2:7079 <-- 37.59.51.72:3478 [fa71d908c9b0bc746c70dba6]
[11:21:32:711][Info]Core:linphone: ice: Add candidate obtained by STUN/TURN: 187.24.5.131:7079:srflx
[11:21:32:711][Info]Core:linphone: ice: Finished candidates gathering for check list 0x1950270
[11:21:32:711][Info]Core:linphone: Local candidates:
[11:21:32:711][Info]Core:linphone: [0x1be9cd0]: type=host ip=192.168.42.2 port=7078 componentID=1 priority=2130706431 foundation= base=0x1be9cd0
[11:21:32:711][Info]Core:linphone: [0x19a2e20]: type=host ip=192.168.42.2 port=7079 componentID=2 priority=2130706430 foundation= base=0x19a2e20
[11:21:32:711][Info]Core:linphone: [0x1b72ae0]: type=srflx ip=187.24.5.131 port=7078 componentID=1 priority=1694498815 foundation= base=0x1be9cd0
[11:21:32:711][Info]Core:linphone: [0x1bdd0b0]: type=srflx ip=187.24.5.131 port=7079 componentID=2 priority=1694498814 foundation= base=0x19a2e20
[11:21:32:711][Info]Core:linphone: Remote candidates:
[11:21:32:711][Info]Core:linphone: ms_filter_unlink: MSRtpRecv:0x1ba1590,0-->MSVoidSink:0x1bb0250,0
[11:21:32:711][Info]Core:linphone: Contact has been fixed using proxy
[11:21:32:711][Info]Core:linphone: Found media local-ip from signaling.
[11:21:32:712][Info]Core:linphone: Don't put video stream on local offer for call [0x1975550]
[11:21:32:712][Info]Core:linphone: Don't put text stream on local offer for call [0x1975550]
[11:21:32:712][Info]Core:linphone: Call [0x1975550] New ICE state: audio: [IceStateInProgress] video: [IceStateNotActivated] text: [IceStateNotActivated]
[11:21:32:712][Info]Core:linphone: ms_filter_link: MSRtpRecv:0x1ad7cd0,0-->MSVoidSink:0x1ab7b20,0
[11:21:32:712][Info]Core:linphone: [sip:[email protected]] calling [sip:[email protected]] on op [0x1950380]
[11:21:32:712][Info]Core:linphone: Skipping top route of initial route-set because same as request-uri.
[11:21:32:712][Info]Core:belle-sip: Changing [client] [INVITE] transaction [0x1b97ea0], from state [INIT] to [CALLING]
[11:21:32:712][Info]Core:belle-sip: channel [0x185efd0]: message sent to [UDP://fritz.box:5060], size: [1478] bytes
INVITE sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.42.2:5060;branch=z9hG4bK.p7OkLwZwf;rport
From: <sip:[email protected]>;tag=axdePP--r
To: sip:[email protected]
CSeq: 20 INVITE
Call-ID: psrcN1Uw5R
Max-Forwards: 70
Supported: replaces, outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 898
Contact: <sip:[email protected];transport=udp>;+sip.instance="<urn:uuid:8e6361b4-0399-4af3-a0f0-505120f41f08>"
User-Agent: Linphone Desktop/4.1.1 (belle-sip/1.6.3)
v=0
o=620 240 1178 IN IP4 192.168.42.2
s=Talk
c=IN IP4 192.168.42.2
t=0 0
a=ice-pwd:042c717144d7c9103000d449
a=ice-ufrag:6aff5ff0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7078 RTP/AVPF 96 97 98 0 8 9 18 101 99 100
c=IN IP4 187.24.5.131
a=rtpmap:96 opus/48000/2
a=fmtp:96 useinbandfec=1
a=rtpmap:97 speex/16000
a=fmtp:97 vbr=on
a=rtpmap:98 speex/8000
a=fmtp:98 vbr=on
a=fmtp:18 annexb=yes
a=rtpmap:101 telephone-event/48000
a=rtpmap:99 telephone-event/16000
a=rtpmap:100 telephone-event/8000
a=candidate:1 1 UDP 2130706431 192.168.42.2 7078 typ host
a=candidate:1 2 UDP 2130706430 192.168.42.2 7079 typ host
a=candidate:2 1 UDP 1694498815 187.24.5.131 7078 typ srflx raddr 192.168.42.2 rport 7078
a=candidate:2 2 UDP 1694498814 187.24.5.131 7079 typ srflx raddr 192.168.42.2 rport 7079
a=rtcp-fb:* trr-int 5000
a=rtcp-fb:* ccm tmmbr
[11:21:32:712][Info]Core:linphone: Call 0x1975550: moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress
[11:21:32:712][Info]Core:linphone: Linphone core [0x7f27f4009780] notified [call_state_changed]
[11:21:32:731][Info]Core:belle-sip: channel [0x185efd0]: received [345] new bytes from [UDP://fritz.box:5060]:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.42.2:5060;branch=z9hG4bK.p7OkLwZwf;rport=5060
From: <sip:[email protected]>;tag=axdePP--r
To: <sip:[email protected]>;tag=B13DB70372FB4582
Call-ID: psrcN1Uw5R
CSeq: 20 INVITE
WWW-Authenticate: Digest realm="fritz.box", nonce="757EE0243ECB379B"
User-Agent: FRITZ!OS
Content-Length: 0
[11:21:32:731][Info]Core:belle-sip: channel [0x185efd0] [345] bytes parsed
[11:21:32:731][Info]Core:belle-sip: Found transaction matching response.
[11:21:32:731][Info]Core:belle-sip: Changing [client] [INVITE] transaction [0x1b97ea0], from state [CALLING] to [PROCEEDING]
[11:21:32:731][Info]Core:belle-sip: Changing [client] [INVITE] transaction [0x1b97ea0], from state [PROCEEDING] to [COMPLETED]
[11:21:32:732][Info]Core:belle-sip: channel [0x185efd0]: message sent to [UDP://fritz.box:5060], size: [369] bytes
ACK sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.42.2:5060;branch=z9hG4bK.p7OkLwZwf;rport
Call-ID: psrcN1Uw5R
From: <sip:[email protected]>;tag=axdePP--r
To: <sip:[email protected]>;tag=B13DB70372FB4582
Contact: <sip:[email protected];transport=udp>;+sip.instance="<urn:uuid:8e6361b4-0399-4af3-a0f0-505120f41f08>"
Max-Forwards: 70
CSeq: 20 ACK
[11:21:32:732][Info]Core:linphone: linphone_core_find_auth_info(): returning auth info username=620, realm=fritz.box
[11:21:32:732][Info]Core:belle-sip: Auth info found for [620] realm [fritz.box]
[11:21:32:732][Info]Core:belle-sip: Changing [client] [INVITE] transaction [0x1a1a540], from state [INIT] to [CALLING]
[11:21:32:732][Info]Core:belle-sip: channel [0x185efd0]: message sent to [UDP://fritz.box:5060], size: [1642] bytes
INVITE sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.42.2:5060;branch=z9hG4bK.En6E3LXLm;rport
From: <sip:[email protected]>;tag=axdePP--r
To: sip:[email protected]
CSeq: 21 INVITE
Call-ID: psrcN1Uw5R
Max-Forwards: 70
Supported: replaces, outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 898
Contact: <sip:[email protected];transport=udp>;+sip.instance="<urn:uuid:8e6361b4-0399-4af3-a0f0-505120f41f08>"
User-Agent: Linphone Desktop/4.1.1 (belle-sip/1.6.3)
Authorization: Digest realm="fritz.box", nonce="757EE0243ECB379B", username="620", uri="sip:[email protected]", response="8569ad9c0421a13360e0c7aa53f75bad"
v=0
o=620 240 1178 IN IP4 192.168.42.2
s=Talk
c=IN IP4 192.168.42.2
t=0 0
a=ice-pwd:042c717144d7c9103000d449
a=ice-ufrag:6aff5ff0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7078 RTP/AVPF 96 97 98 0 8 9 18 101 99 100
c=IN IP4 187.24.5.131
a=rtpmap:96 opus/48000/2
a=fmtp:96 useinbandfec=1
a=rtpmap:97 speex/16000
a=fmtp:97 vbr=on
a=rtpmap:98 speex/8000
a=fmtp:98 vbr=on
a=fmtp:18 annexb=yes
a=rtpmap:101 telephone-event/48000
a=rtpmap:99 telephone-event/16000
a=rtpmap:100 telephone-event/8000
a=candidate:1 1 UDP 2130706431 192.168.42.2 7078 typ host
a=candidate:1 2 UDP 2130706430 192.168.42.2 7079 typ host
a=candidate:2 1 UDP 1694498815 187.24.5.131 7078 typ srflx raddr 192.168.42.2 rport 7078
a=candidate:2 2 UDP 1694498814 187.24.5.131 7079 typ srflx raddr 192.168.42.2 rport 7079
a=rtcp-fb:* trr-int 5000
a=rtcp-fb:* ccm tmmbr
[11:21:32:771][Info]Core:belle-sip: channel [0x185efd0]: received [449] new bytes from [UDP://fritz.box:5060]:
SIP/2.0 415 Unsupported Media Type
Via: SIP/2.0/UDP 192.168.42.2:5060;branch=z9hG4bK.En6E3LXLm;rport=5060
From: <sip:[email protected]>;tag=axdePP--r
To: <sip:[email protected]>;tag=A265A14055E58A76
Call-ID: psrcN1Uw5R
CSeq: 21 INVITE
User-Agent: FRITZ!OS
Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH
Accept: application/sdp, multipart/mixed
Accept-Encoding: identity
Content-Length: 0
[11:21:32:772][Info]Core:belle-sip: channel [0x185efd0] [449] bytes parsed
[11:21:32:772][Info]Core:belle-sip: Found transaction matching response.
[11:21:32:772][Info]Core:belle-sip: Changing [client] [INVITE] transaction [0x1a1a540], from state [CALLING] to [PROCEEDING]
[11:21:32:772][Info]Core:belle-sip: Changing [client] [INVITE] transaction [0x1a1a540], from state [PROCEEDING] to [COMPLETED]
[11:21:32:772][Info]Core:belle-sip: channel [0x185efd0]: message sent to [UDP://fritz.box:5060], size: [369] bytes
ACK sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.42.2:5060;branch=z9hG4bK.En6E3LXLm;rport
Call-ID: psrcN1Uw5R
From: <sip:[email protected]>;tag=axdePP--r
To: <sip:[email protected]>;tag=A265A14055E58A76
Contact: <sip:[email protected];transport=udp>;+sip.instance="<urn:uuid:8e6361b4-0399-4af3-a0f0-505120f41f08>"
Max-Forwards: 70
CSeq: 21 ACK
[11:21:32:772][Info]Core:linphone: op [0x1950380] : set_or_update_dialog() current=[(nil)] new=[(nil)]
[11:21:32:772][Info]Core:linphone: Op [0x1950380] receiving call response [415], dialog is [(nil)] in state [BELLE_SIP_DIALOG_NULL]
[11:21:32:772][Info]Core:linphone: Outgoing call [0x1975550] failed with SRTP and/or AVPF enabled
[11:21:32:772][Info]Core:linphone: Retrying call [0x1975550] with AVP
[11:21:32:772][Info]Core:linphone: ms_filter_unlink: MSRtpRecv:0x1ad7cd0,0-->MSVoidSink:0x1ab7b20,0
[11:21:32:772][Info]Core:linphone: ===========================================================
[11:21:32:772][Info]Core:linphone: FILTER USAGE STATISTICS
[11:21:32:772][Info]Core:linphone: Name Count Time/tick (ms) CPU Usage
[11:21:32:772][Info]Core:linphone: -----------------------------------------------------------
[11:21:32:772][Info]Core:linphone: MSRtpRecv 49 0.0180773 99.9999
[11:21:32:772][Info]Core:linphone: MSVoidSink 0 0 0
[11:21:32:773][Info]Core:linphone: MSItcSink 0 0 0
[11:21:32:773][Info]Core:linphone: MSTee 0 0 0
[11:21:32:773][Info]Core:linphone: MSWebRTCAEC 0 0 0
[11:21:32:773][Info]Core:linphone: MSRtpSend 0 0 0
[11:21:32:773][Info]Core:linphone: ===========================================================
[11:21:32:773][Info]Core:linphone: ===========================================================
[11:21:32:773][Info]Core:linphone: FILTER USAGE STATISTICS
[11:21:32:773][Info]Core:linphone: Name Count Time/tick (ms) CPU Usage
[11:21:32:773][Info]Core:linphone: -----------------------------------------------------------
[11:21:32:773][Info]Core:linphone: MSRtpRecv 49 0.0180773 99.9999
[11:21:32:773][Info]Core:linphone: MSVoidSink 0 0 0
[11:21:32:773][Info]Core:linphone: MSItcSink 0 0 0
[11:21:32:773][Info]Core:linphone: MSTee 0 0 0
[11:21:32:773][Info]Core:linphone: MSWebRTCAEC 0 0 0
[11:21:32:773][Info]Core:linphone: MSRtpSend 0 0 0
[11:21:32:773][Info]Core:linphone: ===========================================================
[11:21:32:773][Info]Core:linphone: Stopping ZRTP context on session [(nil)]
[11:21:32:773][Info]Core:linphone: ZRTP context destroyed
[11:21:32:779][Info]Core:linphone: MSAudio MSTicker thread exiting
[11:21:32:779][Info]Core:linphone: Stopping ZRTP context on session [(nil)]
[11:21:32:779][Info]Core:linphone: ZRTP context destroyed
[11:21:32:779][Info]Core:linphone: Call [0x1975550], stream type [audio], multicast role is [inactive]
[11:21:32:780][Info]Core:linphone: RtpSession bound to [0.0.0.0] ports [7078] [7079]
[11:21:32:780][Info]Core:linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION
[11:21:32:780][Info]Core:linphone: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80
[11:21:32:780][Info]Core:linphone: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32
[11:21:32:780][Info]Core:linphone: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80
[11:21:32:780][Info]Core:linphone: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32
[11:21:32:780][Info]Core:linphone: Creating ZRTP engine on rtp session [0x1b03c70] ssrc 0x90bdd31e
[11:21:32:780][Info]Core:linphone: Setting DSCP to 46 for MSAudio stream.
[11:21:32:780][Info]Core:linphone: Equalizer location: hp
[11:21:32:780][Info]Core:linphone: cannot set noise gate mode to [0] because no volume send
[11:21:32:780][Info]Core:linphone: Call [0x1975550], stream type [video], multicast role is [inactive]
[11:21:32:780][Info]Core:linphone: RtpSession bound to [0.0.0.0] ports [9078] [9079]
[11:21:32:780][Info]Core:linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION
[11:21:32:780][Info]Core:linphone: Initializing multistream ZRTP context on rtp session [0x1a8b1f0] ssrc 0x140d11d9
[11:21:32:780][Info]Core:linphone: Setting DSCP to 0 for MSVideo stream.
[11:21:32:780][Info]Core:linphone: Call [0x1975550], stream type [text], multicast role is [inactive]
[11:21:32:780][Info]Core:linphone: RtpSession bound to [0.0.0.0] ports [11078] [11079]
[11:21:32:780][Info]Core:linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION
[11:21:32:780][Info]Core:linphone: Contact has been fixed using proxy
[11:21:32:780][Info]Core:linphone: Found media local-ip from signaling.
[11:21:32:780][Info]Core:linphone: Don't put video stream on local offer for call [0x1975550]
[11:21:32:780][Info]Core:linphone: Don't put text stream on local offer for call [0x1975550]
[11:21:32:780][Info]Core:linphone: Call [0x1975550] New ICE state: audio: [IceStateInProgress] video: [IceStateNotActivated] text: [IceStateNotActivated]
[11:21:32:780][Info]Core:linphone: ms_filter_link: MSRtpRecv:0x1b841e0,0-->MSVoidSink:0x18d8e70,0
[11:21:32:780][Info]Core:linphone: Priority used: 99
[11:21:32:780][Info]Core:linphone: MSAudio MSTicker priority set to SCHED_RR and value (99)
[11:21:32:780][Info]Core:linphone: [sip:[email protected]] calling [sip:[email protected]] on op [0x1ba01d0]
[11:21:32:780][Info]Core:linphone: Skipping top route of initial route-set because same as request-uri.
[11:21:32:780][Info]Core:belle-sip: Changing [client] [INVITE] transaction [0x1de3870], from state [INIT] to [CALLING]
[11:21:32:781][Info]Core:belle-sip: channel [0x185efd0]: message sent to [UDP://fritz.box:5060], size: [1477] bytes
INVITE sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.42.2:5060;branch=z9hG4bK.QxGBRSLwG;rport
From: <sip:[email protected]>;tag=QdY4Aegwm
To: sip:[email protected]
CSeq: 20 INVITE
Call-ID: 84kkX4y~cJ
Max-Forwards: 70
Supported: replaces, outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 897
Contact: <sip:[email protected];transport=udp>;+sip.instance="<urn:uuid:8e6361b4-0399-4af3-a0f0-505120f41f08>"
User-Agent: Linphone Desktop/4.1.1 (belle-sip/1.6.3)
v=0
o=620 240 1179 IN IP4 192.168.42.2
s=Talk
c=IN IP4 192.168.42.2
t=0 0
a=ice-pwd:042c717144d7c9103000d449
a=ice-ufrag:6aff5ff0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7078 RTP/AVP 96 97 98 0 8 9 18 101 99 100
c=IN IP4 187.24.5.131
a=rtpmap:96 opus/48000/2
a=fmtp:96 useinbandfec=1
a=rtpmap:97 speex/16000
a=fmtp:97 vbr=on
a=rtpmap:98 speex/8000
a=fmtp:98 vbr=on
a=fmtp:18 annexb=yes
a=rtpmap:101 telephone-event/48000
a=rtpmap:99 telephone-event/16000
a=rtpmap:100 telephone-event/8000
a=candidate:1 1 UDP 2130706431 192.168.42.2 7078 typ host
a=candidate:1 2 UDP 2130706430 192.168.42.2 7079 typ host
a=candidate:2 1 UDP 1694498815 187.24.5.131 7078 typ srflx raddr 192.168.42.2 rport 7078
a=candidate:2 2 UDP 1694498814 187.24.5.131 7079 typ srflx raddr 192.168.42.2 rport 7079
a=rtcp-fb:* trr-int 5000
a=rtcp-fb:* ccm tmmbr
[11:21:32:781][Info]Core:linphone: op [0x1950380] : set_or_update_dialog() current=[(nil)] new=[(nil)]
[11:21:32:791][Info]Core:belle-sip: channel [0x185efd0]: received [345] new bytes from [UDP://fritz.box:5060]:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.42.2:5060;branch=z9hG4bK.QxGBRSLwG;rport=5060
From: <sip:[email protected]>;tag=QdY4Aegwm
To: <sip:[email protected]>;tag=762D19C5896AAE5C
Call-ID: 84kkX4y~cJ
CSeq: 20 INVITE
WWW-Authenticate: Digest realm="fritz.box", nonce="DFAB0607BDB84DE5"
User-Agent: FRITZ!OS
Content-Length: 0
[11:21:32:791][Info]Core:belle-sip: channel [0x185efd0] [345] bytes parsed
[11:21:32:792][Info]Core:belle-sip: Found transaction matching response.
[11:21:32:792][Info]Core:belle-sip: Changing [client] [INVITE] transaction [0x1de3870], from state [CALLING] to [PROCEEDING]
[11:21:32:792][Info]Core:belle-sip: Changing [client] [INVITE] transaction [0x1de3870], from state [PROCEEDING] to [COMPLETED]
[11:21:32:792][Info]Core:belle-sip: channel [0x185efd0]: message sent to [UDP://fritz.box:5060], size: [369] bytes
ACK sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.42.2:5060;branch=z9hG4bK.QxGBRSLwG;rport
Call-ID: 84kkX4y~cJ
From: <sip:[email protected]>;tag=QdY4Aegwm
To: <sip:[email protected]>;tag=762D19C5896AAE5C
Contact: <sip:[email protected];transport=udp>;+sip.instance="<urn:uuid:8e6361b4-0399-4af3-a0f0-505120f41f08>"
Max-Forwards: 70
CSeq: 20 ACK
[11:21:32:792][Info]Core:linphone: linphone_core_find_auth_info(): returning auth info username=620, realm=fritz.box
[11:21:32:792][Info]Core:belle-sip: Auth info found for [620] realm [fritz.box]
[11:21:32:792][Info]Core:belle-sip: Changing [client] [INVITE] transaction [0x1de7de0], from state [INIT] to [CALLING]
[11:21:32:792][Info]Core:belle-sip: channel [0x185efd0]: message sent to [UDP://fritz.box:5060], size: [1641] bytes
INVITE sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.42.2:5060;branch=z9hG4bK.UrMduRzDN;rport
From: <sip:[email protected]>;tag=QdY4Aegwm
To: sip:[email protected]
CSeq: 21 INVITE
Call-ID: 84kkX4y~cJ
Max-Forwards: 70
Supported: replaces, outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 897
Contact: <sip:[email protected];transport=udp>;+sip.instance="<urn:uuid:8e6361b4-0399-4af3-a0f0-505120f41f08>"
User-Agent: Linphone Desktop/4.1.1 (belle-sip/1.6.3)
Authorization: Digest realm="fritz.box", nonce="DFAB0607BDB84DE5", username="620", uri="sip:[email protected]", response="2e07d64eaa78b68d5702d7d73e75044f"
v=0
o=620 240 1179 IN IP4 192.168.42.2
s=Talk
c=IN IP4 192.168.42.2
t=0 0
a=ice-pwd:042c717144d7c9103000d449
a=ice-ufrag:6aff5ff0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7078 RTP/AVP 96 97 98 0 8 9 18 101 99 100
c=IN IP4 187.24.5.131
a=rtpmap:96 opus/48000/2
a=fmtp:96 useinbandfec=1
a=rtpmap:97 speex/16000
a=fmtp:97 vbr=on
a=rtpmap:98 speex/8000
a=fmtp:98 vbr=on
a=fmtp:18 annexb=yes
a=rtpmap:101 telephone-event/48000
a=rtpmap:99 telephone-event/16000
a=rtpmap:100 telephone-event/8000
a=candidate:1 1 UDP 2130706431 192.168.42.2 7078 typ host
a=candidate:1 2 UDP 2130706430 192.168.42.2 7079 typ host
a=candidate:2 1 UDP 1694498815 187.24.5.131 7078 typ srflx raddr 192.168.42.2 rport 7078
a=candidate:2 2 UDP 1694498814 187.24.5.131 7079 typ srflx raddr 192.168.42.2 rport 7079
a=rtcp-fb:* trr-int 5000
a=rtcp-fb:* ccm tmmbr
[11:21:32:831][Info]Core:belle-sip: channel [0x185efd0]: received [298] new bytes from [UDP://fritz.box:5060]:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.42.2:5060;branch=z9hG4bK.UrMduRzDN;rport=5060
From: <sip:[email protected]>;tag=QdY4Aegwm
To: <sip:[email protected]>
Call-ID: 84kkX4y~cJ
CSeq: 21 INVITE
User-Agent: AVM FRITZ!Box 6490 Cable (kdg) 141.06.66 TAL (Oct 24 2016)
Content-Length: 0
[11:21:32:832][Info]Core:belle-sip: channel [0x185efd0] [298] bytes parsed
[11:21:32:832][Info]Core:belle-sip: Found transaction matching response.
[11:21:32:832][Info]Core:belle-sip: Changing [client] [INVITE] transaction [0x1de7de0], from state [CALLING] to [PROCEEDING]
[11:21:32:832][Info]Core:linphone: op [0x1ba01d0] : set_or_update_dialog() current=[(nil)] new=[(nil)]
[11:21:32:832][Info]Core:linphone: Op [0x1ba01d0] receiving call response [100], dialog is [(nil)] in state [BELLE_SIP_DIALOG_NULL]
[11:21:32:911][Info]Core:belle-sip: channel [0x185efd0]: received [685] new bytes from [UDP://fritz.box:5060]:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 192.168.42.2:5060;branch=z9hG4bK.UrMduRzDN;rport=5060
From: <sip:[email protected]>;tag=QdY4Aegwm
To: <sip:[email protected]>;tag=F5825075817F3F75
Call-ID: 84kkX4y~cJ
CSeq: 21 INVITE
Contact: <sip:[email protected]>
User-Agent: AVM FRITZ!Box 6490 Cable (kdg) 141.06.66 TAL (Oct 24 2016)
Content-Type: application/sdp
Content-Length: 262
v=0
o=user 4165989 4165989 IN IP4 192.168.42.1
s=Talk
c=IN IP4 192.168.42.1
t=0 0
m=audio 7078 RTP/AVP 9 0 8 100
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:100 telephone-event/8000
a=fmtp:100 0-15
a=sendrecv
a=rtcp:7079
[11:21:32:912][Info]Core:belle-sip: channel [0x185efd0] [423] bytes parsed
[11:21:32:912][Info]Core:belle-sip: channel [0x185efd0] read [262] bytes of body from [fritz.box:5060]
[11:21:32:912][Info]Core:belle-sip: Found transaction matching response.
[11:21:32:912][Info]Core:belle-sip: New client dialog [0x1dcc200] , local tag [QdY4Aegwm], remote tag [F5825075817F3F75]
[11:21:32:912][Info]Core:belle-sip: Dialog [0x1dcc200]: now updated by transaction [0x1de7de0].
[11:21:32:913][Info]Core:linphone: op [0x1ba01d0] : set_or_update_dialog() current=[(nil)] new=[0x1dcc200]
[11:21:32:913][Info]Core:linphone: Op [0x1ba01d0] receiving call response [183], dialog is [0x1dcc200] in state [BELLE_SIP_DIALOG_EARLY]
[11:21:32:913][Info]Core:linphone: Found payload G722/8000 fmtp=
[11:21:32:913][Info]Core:linphone: Found payload PCMU/8000 fmtp=
[11:21:32:913][Info]Core:linphone: Found payload PCMA/8000 fmtp=
[11:21:32:913][Info]Core:linphone: Found payload telephone-event/8000 fmtp=0-15
[11:21:32:913][Info]Core:linphone: Doing SDP offer/answer process of type outgoing
[11:21:32:913][Info]Core:linphone: Processing for stream 0
[11:21:32:913][Info]Core:linphone: Adding opus/48000 for compatibility, just in case.
[11:21:32:913][Info]Core:linphone: Adding speex/16000 for compatibility, just in case.
[11:21:32:913][Info]Core:linphone: Adding speex/8000 for compatibility, just in case.
[11:21:32:913][Info]Core:linphone: Adding G729/8000 for compatibility, just in case.
[11:21:32:913][Info]Core:linphone: Adding telephone-event/48000 for compatibility, just in case.
[11:21:32:913][Info]Core:linphone: Adding telephone-event/16000 for compatibility, just in case.
[11:21:32:913][Info]Core:linphone: Call 0x1975550: moving from state LinphoneCallOutgoingProgress to LinphoneCallOutgoingEarlyMedia
[11:21:32:913][Info]Core:linphone: Linphone core [0x7f27f4009780] notified [call_state_changed]
[11:21:32:913][Info]Core:linphone: Doing early media...
[11:21:32:913][Info]Core:linphone: linphone_call_start_media_streams() call=[0x1975550] local upload_bandwidth=[0] kbit/s; local download_bandwidth=[0] kbit/s
[11:21:32:913][Info]Core:linphone: Audio bandwidth for this call is 80
[11:21:32:913][Info]Core:linphone: RtpSession [0x1b03c70] sending to rtp 192.168.42.1:7078 rtcp 192.168.42.1:7079
[11:21:32:913][Info]Core:linphone: ms_filter_unlink: MSRtpRecv:0x1b841e0,0-->MSVoidSink:0x18d8e70,0
[11:21:32:914][Info]Core:linphone: MKVRecorder: initialisation
[11:21:32:915][Info]Core:linphone: speex_lib_ctl init with neon ? 0
[11:21:32:915][Info]Core:linphone: Configuring av recorder with audio format type=audio;encoding=opus;rate=48000;channels=1;fmtp=''
[11:21:32:915][Info]Core:linphone: MKVRecorder: set pin #1 format. type=audio;encoding=opus;rate=48000;channels=1;fmtp=''
[11:21:32:915][Info]Core:linphone: target bitrate not set for stream [0x1b1b670] using payload's bitrate is 80000
[11:21:32:915][Info]Core:linphone: Setting audio encoder network bitrate to [80000] on stream [0x1b1b670]
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSPulseRead:0x1b841e0,0-->MSEqualizer:0x1b10f60,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSEqualizer:0x1b10f60,0-->MSVolume:0x1b02750,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSVolume:0x1b02750,0-->MSAudioMixer:0x1de2c30,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSAudioMixer:0x1de2c30,0-->MSG722Enc:0x1ddf620,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSG722Enc:0x1ddf620,0-->MSRtpSend:0x1de5bd0,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSRtpRecv:0x1dd9cc0,0-->MSG722Dec:0x1ddf530,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSG722Dec:0x1ddf530,0-->MSAudioFlowControl:0x1d608a0,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSAudioFlowControl:0x1d608a0,0-->MSDtmfGen:0x1b11520,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSDtmfGen:0x1b11520,0-->MSVolume:0x1dd98a0,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSVolume:0x1dd98a0,0-->MSTee:0x1de7c20,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSTee:0x1de7c20,0-->MSEqualizer:0x1b10e70,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSEqualizer:0x1b10e70,0-->MSAudioMixer:0x1af9620,0
[11:21:32:915][Info]Core:linphone: speex_lib_ctl init with neon ? 0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSFilePlayer:0x1b11410,0-->MSResample:0x1dfa580,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSResample:0x1dfa580,0-->MSAudioMixer:0x1af9620,1
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSAudioMixer:0x1af9620,0-->MSPulseWrite:0x18d8e70,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSAudioMixer:0x1dd4770,1-->MSResample:0x1dfa850,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSResample:0x1dfa850,0-->MSOpusEnc:0x1d659d0,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSOpusEnc:0x1d659d0,0-->MSMKVRecorder:0x1de71d0,1
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSItcSource:0x1dfab20,0-->MSMKVRecorder:0x1de71d0,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSAudioMixer:0x1de2c30,1-->MSAudioMixer:0x1dd4770,0
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSTee:0x1de7c20,1-->MSAudioMixer:0x1dd4770,1
[11:21:32:915][Info]Core:linphone: ms_filter_link: MSAudioMixer:0x1dd4770,0-->MSFileRec:0x1ddb4f0,0
[11:21:32:916][Info]Core:linphone: pulseaudio record stream connected (16000Hz, 1ch)
[11:21:32:916][Info]Core:linphone: ms_ticker_set_time_func: ticker's time method updated.
[11:21:32:916][Info]Core:linphone: Initializing speex resampler in mode [voip]
[11:21:32:916][Info]Core:linphone: pulseaudio playback stream connected (16000Hz, 1ch)
[11:21:32:916][Info]Core:linphone: Initializing speex resampler in mode [voip]
[11:21:32:916][Info]Core:linphone: MSOpusEnc: codec bitrate set to [30000] with ptime [20]
[11:21:32:916][Info]Core:linphone: Setting opus codec bitrate to [30000] from network bitrate [46000] with ptime [20]
[11:21:32:916][Info]Core:linphone: Filter MSRtpRecv is already being scheduled; nothing to do.
[11:21:32:916][Error]Core:linphone: no such method on filter MSPulseWrite, fid=16394 method index=2
[11:21:32:916][Info]Core:linphone: MSVolume set gain to [0.000000] linear
[11:21:32:916][Info]Core:linphone: audio_stream_link_video() connecting itc filters
[11:21:32:916][Info]Core:linphone: No valid video stream defined.
[11:21:32:916][Info]Core:linphone: Candidate pairs foundations:
[11:21:32:916][Info]Core:linphone: Candidate pairs:
[11:21:32:916][Info]Core:linphone: Check list:
[11:21:32:916][Info]Core:linphone: LinphoneCall[0x1975550] : payload type 9 G722/8000 fmtp= added to frozen list.
[11:21:32:916][Info]Core:linphone: LinphoneCall[0x1975550] : payload type 0 PCMU/8000 fmtp= added to frozen list.
[11:21:32:916][Info]Core:linphone: LinphoneCall[0x1975550] : payload type 8 PCMA/8000 fmtp= added to frozen list.
[11:21:32:916][Info]Core:linphone: LinphoneCall[0x1975550] : payload type 100 telephone-event/8000 fmtp= added to frozen list.
[11:21:32:916][Info]Core:linphone: LinphoneCall[0x1975550] : payload type 96 opus/48000 fmtp=useinbandfec=1 added to frozen list.
[11:21:32:916][Info]Core:linphone: LinphoneCall[0x1975550] : payload type 97 speex/16000 fmtp=vbr=on added to frozen list.
[11:21:32:916][Info]Core:linphone: LinphoneCall[0x1975550] : payload type 98 speex/8000 fmtp=vbr=on added to frozen list.
[11:21:32:916][Info]Core:linphone: LinphoneCall[0x1975550] : payload type 18 G729/8000 fmtp=annexb=yes added to frozen list.
[11:21:32:916][Info]Core:linphone: LinphoneCall[0x1975550] : payload type 101 telephone-event/48000 fmtp= added to frozen list.
[11:21:32:916][Info]Core:linphone: LinphoneCall[0x1975550] : payload type 99 telephone-event/16000 fmtp= added to frozen list.
[11:21:32:916][Info]Core:belle-sip: Garbage collecting unowned object of type belle_sdp_session_description_t
[11:21:32:926][Info]Core:linphone: MSAudioMixer [0x1de2c30] is entering bypass mode.
[11:21:33:231][Info]Core:linphone: First estimation
[11:21:33:231][Info]Core:linphone: First estimation
[11:21:33:231][Info]Core:linphone: Bandwidth usage for call [0x1975550]:
RTP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:21:33:232][Info]Core:linphone: Thread processing load: audio=8.127195 video=0.000000 text=0.000000
[11:21:33:831][Info]Core:belle-sip: channel [0x190e0d0]: keep alive sent to [UDP://sip.linphone.org:5060]
[11:21:33:831][Info]Core:belle-sip: channel [0x185efd0]: keep alive sent to [UDP://fritz.box:5060]
[11:21:33:915][Info]Core:linphone: sound/wall clock skew is average=-1.715878 ms
[11:21:34:233][Info]Core:linphone: Bandwidth usage for call [0x1975550]:
RTP audio=[d= 0.0,u= 81.9], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:21:34:233][Info]Core:linphone: Thread processing load: audio=3.463560 video=0.000000 text=0.000000
[11:21:34:696][Info]Core:linphone: Sending RTCP SR compound message on session [0x1b03c70].
[11:21:34:711][Info]Core:linphone: MSAudio_stream_iterate[0x1b1b670], local statistics available:
Local current jitter buffer size: 0.0ms
[11:21:34:712][Info]Core:linphone: Linphone core [0x7f27f4009780] notified [call_stats_updated]
[11:21:34:916][Info]Core:linphone: sound/wall clock skew is average=-0.265844 ms
[11:21:35:231][Info]Core:linphone: Bandwidth usage for call [0x1975550]:
RTP audio=[d= 0.0,u= 78.4], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.0,u= 1.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:21:35:231][Info]Core:linphone: Thread processing load: audio=3.330335 video=0.000000 text=0.000000
[11:21:35:915][Info]Core:linphone: sound/wall clock skew is average=0.920139 ms
[11:21:36:231][Info]Core:linphone: Bandwidth usage for call [0x1975550]:
RTP audio=[d= 0.0,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:21:36:231][Info]Core:linphone: Thread processing load: audio=3.178072 video=0.000000 text=0.000000
[11:21:36:915][Info]Core:linphone: sound/wall clock skew is average=1.128692 ms
[11:21:37:231][Info]Core:linphone: Bandwidth usage for call [0x1975550]:
RTP audio=[d= 0.0,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:21:37:231][Info]Core:linphone: Thread processing load: audio=3.264164 video=0.000000 text=0.000000
[11:21:37:736][Info]Core:linphone: Sending RTCP SR compound message on session [0x1b03c70].
[11:21:37:752][Info]Core:linphone: MSAudio_stream_iterate[0x1b1b670], local statistics available:
Local current jitter buffer size: 0.0ms
[11:21:37:752][Info]Core:linphone: Linphone core [0x7f27f4009780] notified [call_stats_updated]
[11:21:37:915][Info]Core:linphone: sound/wall clock skew is average=1.062469 ms
[11:21:38:232][Info]Core:linphone: Bandwidth usage for call [0x1975550]:
RTP audio=[d= 0.0,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.0,u= 1.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:21:38:232][Info]Core:linphone: Thread processing load: audio=3.171360 video=0.000000 text=0.000000
[11:21:38:915][Info]Core:linphone: sound/wall clock skew is average=1.342186 ms
[11:21:39:231][Info]Core:linphone: Bandwidth usage for call [0x1975550]:
RTP audio=[d= 0.0,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:21:39:231][Info]Core:linphone: Thread processing load: audio=3.382904 video=0.000000 text=0.000000
[11:21:39:916][Info]Core:linphone: sound/wall clock skew is average=1.537500 ms
[11:21:40:232][Info]Core:linphone: Bandwidth usage for call [0x1975550]:
RTP audio=[d= 0.0,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:21:40:232][Info]Core:linphone: Thread processing load: audio=3.203110 video=0.000000 text=0.000000
[11:21:40:291][Info]Core:belle-sip: channel [0x185efd0]: received [882] new bytes from [UDP://fritz.box:5060]:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.42.2:5060;branch=z9hG4bK.UrMduRzDN;rport=5060
From: <sip:[email protected]>;tag=QdY4Aegwm
To: <sip:[email protected]>;tag=F5825075817F3F75
Call-ID: 84kkX4y~cJ
CSeq: 21 INVITE
Contact: <sip:[email protected]>
User-Agent: AVM FRITZ!Box 6490 Cable (kdg) 141.06.66 TAL (Oct 24 2016)
Supported: 100rel,replaces,timer
Allow-Events: telephone-event,refer
Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH
Content-Type: application/sdp
Accept: application/sdp, multipart/mixed
Accept-Encoding: identity
Content-Length: 238
v=0
o=user 4165989 4165990 IN IP4 192.168.42.1
s=Talk
c=IN IP4 192.168.42.1
t=0 0
m=audio 7078 RTP/AVP 0 8 100
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:100 telephone-event/8000
a=fmtp:100 0-15
a=sendrecv
a=rtcp:7079
[11:21:40:297][Info]Core:belle-sip: channel [0x185efd0] [644] bytes parsed
[11:21:40:297][Info]Core:belle-sip: channel [0x185efd0] read [238] bytes of body from [fritz.box:5060]
[11:21:40:297][Info]Core:belle-sip: Found transaction matching response.
[11:21:40:297][Info]Core:belle-sip: Changing [client] [INVITE] transaction [0x1de7de0], from state [PROCEEDING] to [ACCEPTED]
[11:21:40:297][Info]Core:belle-sip: Dialog [0x1dcc200]: now updated by transaction [0x1de7de0].
[11:21:40:298][Info]Core:linphone: op [0x1ba01d0] : set_or_update_dialog() current=[0x1dcc200] new=[0x1dcc200]
[11:21:40:298][Info]Core:linphone: Op [0x1ba01d0] receiving call response [200], dialog is [0x1dcc200] in state [BELLE_SIP_DIALOG_CONFIRMED]
[11:21:40:299][Info]Core:linphone: Found payload PCMU/8000 fmtp=
[11:21:40:299][Info]Core:linphone: Found payload PCMA/8000 fmtp=
[11:21:40:299][Info]Core:linphone: Found payload telephone-event/8000 fmtp=0-15
[11:21:40:299][Info]Core:linphone: Doing SDP offer/answer process of type outgoing
[11:21:40:300][Info]Core:linphone: Processing for stream 0
[11:21:40:300][Info]Core:linphone: Adding opus/48000 for compatibility, just in case.
[11:21:40:300][Info]Core:linphone: Adding speex/16000 for compatibility, just in case.
[11:21:40:300][Info]Core:linphone: Adding speex/8000 for compatibility, just in case.
[11:21:40:300][Info]Core:linphone: Adding G722/8000 for compatibility, just in case.
[11:21:40:300][Info]Core:linphone: Adding G729/8000 for compatibility, just in case.
[11:21:40:300][Info]Core:linphone: Adding telephone-event/48000 for compatibility, just in case.
[11:21:40:300][Info]Core:linphone: Adding telephone-event/16000 for compatibility, just in case.
[11:21:40:300][Info]Core:linphone: Call 0x1975550: moving from state LinphoneCallOutgoingEarlyMedia to LinphoneCallConnected
[11:21:40:347][Info]Core:linphone: Linphone core [0x7f27f4009780] notified [call_state_changed]
[11:21:40:347][Info]Core:linphone: Local description has changed: CODEC_CHANGED
[11:21:40:347][Info]Core:linphone: Other description has changed: CODEC_CHANGED
[11:21:40:347][Info]Core:linphone: Media descriptions are different, need to restart the streams.
[11:21:40:347][Info]Core:linphone: ms_ticker_set_time_func: ticker's time method updated.
[11:21:40:348][Info]Core:linphone: Filter MSRtpRecv is not scheduled; nothing to do.
[11:21:40:348][Info]Core:linphone: ===========================================================
[11:21:40:348][Info]Core:linphone: AUDIO SESSION'S RTP STATISTICS
[11:21:40:348][Info]Core:linphone: -----------------------------------------------------------
[11:21:40:348][Info]Core:linphone: sent 371 packets
[11:21:40:348][Info]Core:linphone: 0 duplicated packets
[11:21:40:348][Info]Core:linphone: 63812 bytes
[11:21:40:348][Info]Core:linphone: received 0 packets
[11:21:40:348][Info]Core:linphone: 0 duplicated packets
[11:21:40:348][Info]Core:linphone: 0 bytes
[11:21:40:348][Info]Core:linphone: incoming delivered to the app 0 bytes
[11:21:40:348][Info]Core:linphone: incoming cumulative lost 0 packets
[11:21:40:348][Info]Core:linphone: incoming received too late 0 packets
[11:21:40:348][Info]Core:linphone: incoming bad formatted 0 packets
[11:21:40:348][Info]Core:linphone: incoming discarded (queue overflow) 0 packets
[11:21:40:348][Info]Core:linphone: sent rtcp 0 packets
[11:21:40:348][Info]Core:linphone: received rtcp 0 packets
[11:21:40:348][Info]Core:linphone: ===========================================================
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSPulseRead:0x1b841e0,0-->MSEqualizer:0x1b10f60,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSEqualizer:0x1b10f60,0-->MSVolume:0x1b02750,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSVolume:0x1b02750,0-->MSAudioMixer:0x1de2c30,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSAudioMixer:0x1de2c30,0-->MSG722Enc:0x1ddf620,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSG722Enc:0x1ddf620,0-->MSRtpSend:0x1de5bd0,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSRtpRecv:0x1dd9cc0,0-->MSG722Dec:0x1ddf530,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSG722Dec:0x1ddf530,0-->MSAudioFlowControl:0x1d608a0,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSAudioFlowControl:0x1d608a0,0-->MSDtmfGen:0x1b11520,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSDtmfGen:0x1b11520,0-->MSVolume:0x1dd98a0,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSVolume:0x1dd98a0,0-->MSTee:0x1de7c20,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSTee:0x1de7c20,0-->MSEqualizer:0x1b10e70,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSEqualizer:0x1b10e70,0-->MSAudioMixer:0x1af9620,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSFilePlayer:0x1b11410,0-->MSResample:0x1dfa580,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSResample:0x1dfa580,0-->MSAudioMixer:0x1af9620,1
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSAudioMixer:0x1af9620,0-->MSPulseWrite:0x18d8e70,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSAudioMixer:0x1dd4770,1-->MSResample:0x1dfa850,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSResample:0x1dfa850,0-->MSOpusEnc:0x1d659d0,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSOpusEnc:0x1d659d0,0-->MSMKVRecorder:0x1de71d0,1
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSItcSource:0x1dfab20,0-->MSMKVRecorder:0x1de71d0,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSAudioMixer:0x1de2c30,1-->MSAudioMixer:0x1dd4770,0
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSTee:0x1de7c20,1-->MSAudioMixer:0x1dd4770,1
[11:21:40:348][Info]Core:linphone: ms_filter_unlink: MSAudioMixer:0x1dd4770,0-->MSFileRec:0x1ddb4f0,0
[11:21:40:348][Info]Core:linphone: ===========================================================
[11:21:40:348][Info]Core:linphone: FILTER USAGE STATISTICS
[11:21:40:348][Info]Core:linphone: Name Count Time/tick (ms) CPU Usage
[11:21:40:348][Info]Core:linphone: -----------------------------------------------------------
[11:21:40:348][Info]Core:linphone: MSG722Enc 357 0.450917 59.3018
[11:21:40:348][Info]Core:linphone: MSOpusEnc 99 0.47869 17.585
[11:21:40:348][Info]Core:linphone: MSRtpSend 744 0.0246639 6.75004
[11:21:40:348][Info]Core:linphone: MSRtpRecv 758 0.0193478 5.39462
[11:21:40:348][Info]Core:linphone: MSPulseRead 744 0.0176652 4.83463
[11:21:40:348][Info]Core:linphone: MSVolume 357 0.0149955 1.97212
[11:21:40:348][Info]Core:linphone: MSAudioMixer 2232 0.00227497 1.86618
[11:21:40:348][Info]Core:linphone: MSPulseWrite 99 0.0256144 0.940961
[11:21:40:348][Info]Core:linphone: MSItcSource 744 0.00104336 0.285547
[11:21:40:348][Info]Core:linphone: MSG722Dec 744 0.000967834 0.264877
[11:21:40:348][Info]Core:linphone: MSDtmfGen 744 0.000965101 0.264129
[11:21:40:348][Info]Core:linphone: MSEqualizer 357 0.0015785 0.207595
[11:21:40:348][Info]Core:linphone: MSFilePlayer 744 0.000713583 0.195294
[11:21:40:348][Info]Core:linphone: MSFileRec 99 0.00138908 0.0510288
[11:21:40:348][Info]Core:linphone: MSMKVRecorder 49 0.00262492 0.0482141
[11:21:40:348][Info]Core:linphone: MSResample 99 0.00103441 0.0379997
[11:21:40:348][Info]Core:linphone: MSAudioFlowControl 0 0 0
[11:21:40:348][Info]Core:linphone: MSVoidSink 0 0 0
[11:21:40:348][Info]Core:linphone: MSItcSink 0 0 0
[11:21:40:348][Info]Core:linphone: MSTee 0 0 0
[11:21:40:348][Info]Core:linphone: MSWebRTCAEC 0 0 0
[11:21:40:348][Info]Core:linphone: ===========================================================
[11:21:40:348][Info]Core:linphone: MKVRecorder: destroyed
[11:21:40:348][Info]Core:linphone: ===========================================================
[11:21:40:348][Info]Core:linphone: FILTER USAGE STATISTICS
[11:21:40:348][Info]Core:linphone: Name Count Time/tick (ms) CPU Usage
[11:21:40:348][Info]Core:linphone: -----------------------------------------------------------
[11:21:40:348][Info]Core:linphone: MSG722Enc 357 0.450917 59.3018
[11:21:40:348][Info]Core:linphone: MSOpusEnc 99 0.47869 17.585
[11:21:40:348][Info]Core:linphone: MSRtpSend 744 0.0246639 6.75004
[11:21:40:348][Info]Core:linphone: MSRtpRecv 758 0.0193478 5.39462
[11:21:40:348][Info]Core:linphone: MSPulseRead 744 0.0176652 4.83463
[11:21:40:348][Info]Core:linphone: MSVolume 357 0.0149955 1.97212
[11:21:40:348][Info]Core:linphone: MSAudioMixer 2232 0.00227497 1.86618
[11:21:40:348][Info]Core:linphone: MSPulseWrite 99 0.0256144 0.940961
[11:21:40:348][Info]Core:linphone: MSItcSource 744 0.00104336 0.285547
[11:21:40:348][Info]Core:linphone: MSG722Dec 744 0.000967834 0.264877
[11:21:40:348][Info]Core:linphone: MSDtmfGen 744 0.000965101 0.264129
[11:21:40:348][Info]Core:linphone: MSEqualizer 357 0.0015785 0.207595
[11:21:40:348][Info]Core:linphone: MSFilePlayer 744 0.000713583 0.195294
[11:21:40:348][Info]Core:linphone: MSFileRec 99 0.00138908 0.0510288
[11:21:40:348][Info]Core:linphone: MSMKVRecorder 49 0.00262492 0.0482141
[11:21:40:348][Info]Core:linphone: MSResample 99 0.00103441 0.0379997
[11:21:40:348][Info]Core:linphone: MSAudioFlowControl 0 0 0
[11:21:40:349][Info]Core:linphone: MSVoidSink 0 0 0
[11:21:40:349][Info]Core:linphone: MSItcSink 0 0 0
[11:21:40:349][Info]Core:linphone: MSTee 0 0 0
[11:21:40:349][Info]Core:linphone: MSWebRTCAEC 0 0 0
[11:21:40:349][Info]Core:linphone: ===========================================================
[11:21:40:349][Info]Core:linphone: Setting DSCP to 46 for MSAudio stream.
[11:21:40:349][Info]Core:linphone: Equalizer location: hp
[11:21:40:349][Info]Core:linphone: cannot set noise gate mode to [0] because no volume send
[11:21:40:349][Info]Core:linphone: Setting DSCP to 0 for MSVideo stream.
[11:21:40:349][Info]Core:linphone: linphone_call_start_media_streams() call=[0x1975550] local upload_bandwidth=[0] kbit/s; local download_bandwidth=[0] kbit/s
[11:21:40:349][Info]Core:linphone: Audio bandwidth for this call is 80
[11:21:40:349][Info]Core:linphone: RtpSession [0x1b03c70] sending to rtp 192.168.42.1:7078 rtcp 192.168.42.1:7079
[11:21:40:349][Info]Core:linphone: Stun packet sent for session [0x1b03c70]
[11:21:40:349][Info]Core:linphone: Stun packet sent on rtcp for session [0x1b03c70]
[11:21:40:349][Info]Core:linphone: MKVRecorder: initialisation
[11:21:40:349][Info]Core:linphone: speex_lib_ctl init with neon ? 0
[11:21:40:349][Info]Core:linphone: Configuring av recorder with audio format type=audio;encoding=opus;rate=48000;channels=1;fmtp=''
[11:21:40:349][Info]Core:linphone: MKVRecorder: set pin #1 format. type=audio;encoding=opus;rate=48000;channels=1;fmtp=''
[11:21:40:349][Info]Core:linphone: target bitrate not set for stream [0x1dd2470] using payload's bitrate is 80000
[11:21:40:349][Info]Core:linphone: Setting audio encoder network bitrate to [80000] on stream [0x1dd2470]
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSPulseRead:0x1b72ae0,0-->MSEqualizer:0x1dd91f0,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSEqualizer:0x1dd91f0,0-->MSVolume:0x1de2ff0,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSVolume:0x1de2ff0,0-->MSAudioMixer:0x197dd90,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSAudioMixer:0x197dd90,0-->MSUlawEnc:0x1be9cd0,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSUlawEnc:0x1be9cd0,0-->MSRtpSend:0x1bdd0b0,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSRtpRecv:0x1dfa580,0-->MSUlawDec:0x1fe87e0,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSUlawDec:0x1fe87e0,0-->MSGenericPLC:0x1be9200,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSGenericPLC:0x1be9200,0-->MSAudioFlowControl:0x1dd98a0,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSAudioFlowControl:0x1dd98a0,0-->MSDtmfGen:0x1d659d0,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSDtmfGen:0x1d659d0,0-->MSVolume:0x1de2c30,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSVolume:0x1de2c30,0-->MSTee:0x19724d0,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSTee:0x19724d0,0-->MSEqualizer:0x1dd9990,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSEqualizer:0x1dd9990,0-->MSAudioMixer:0x1dd1bd0,0
[11:21:40:349][Info]Core:linphone: speex_lib_ctl init with neon ? 0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSFilePlayer:0x1dd1450,0-->MSResample:0x1dd1360,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSResample:0x1dd1360,0-->MSAudioMixer:0x1dd1bd0,1
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSAudioMixer:0x1dd1bd0,0-->MSPulseWrite:0x19a2e20,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSAudioMixer:0x1abcae0,1-->MSResample:0x1dce410,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSResample:0x1dce410,0-->MSOpusEnc:0x1ddf620,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSOpusEnc:0x1ddf620,0-->MSMKVRecorder:0x1af9620,1
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSItcSource:0x1de5bd0,0-->MSMKVRecorder:0x1af9620,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSAudioMixer:0x197dd90,1-->MSAudioMixer:0x1abcae0,0
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSTee:0x19724d0,1-->MSAudioMixer:0x1abcae0,1
[11:21:40:349][Info]Core:linphone: ms_filter_link: MSAudioMixer:0x1abcae0,0-->MSFileRec:0x1aaf2c0,0
[11:21:40:350][Info]Core:linphone: pulseaudio record stream connected (8000Hz, 1ch)
[11:21:40:350][Info]Core:linphone: ms_ticker_set_time_func: ticker's time method updated.
[11:21:40:350][Info]Core:linphone: Initializing speex resampler in mode [voip]
[11:21:40:350][Info]Core:linphone: pulseaudio playback stream connected (8000Hz, 1ch)
[11:21:40:350][Info]Core:linphone: Initializing speex resampler in mode [voip]
[11:21:40:350][Info]Core:linphone: MSOpusEnc: codec bitrate set to [30000] with ptime [20]
[11:21:40:350][Info]Core:linphone: Setting opus codec bitrate to [30000] from network bitrate [46000] with ptime [20]
[11:21:40:350][Info]Core:linphone: Filter MSRtpRecv is already being scheduled; nothing to do.
[11:21:40:350][Error]Core:linphone: no such method on filter MSPulseWrite, fid=16394 method index=2
[11:21:40:350][Info]Core:linphone: MSVolume set gain to [0.000000 db], [1.000000] linear
[11:21:40:350][Info]Core:linphone: audio_stream_link_video() connecting itc filters
[11:21:40:350][Info]Core:linphone: No valid video stream defined.
[11:21:40:350][Info]Core:linphone: audio stream index found: 0, updating main audio stream index
[11:21:40:350][Info]Core:linphone: Call 0x1975550: moving from state LinphoneCallConnected to LinphoneCallStreamsRunning
[11:21:40:351][Info]Core:linphone: Linphone core [0x7f27f4009780] notified [call_state_changed]
[11:21:40:351][Info]Core:belle-sip: Computing branch id z9hG4bK.0M1xUcujk for message sent statelessly
[11:21:40:351][Info]Core:belle-sip: channel [0x185efd0]: message sent to [UDP://fritz.box:5060], size: [496] bytes
ACK sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.42.2:5060;rport;branch=z9hG4bK.0M1xUcujk
From: <sip:[email protected]>;tag=QdY4Aegwm
To: <sip:[email protected]>;tag=F5825075817F3F75
CSeq: 21 ACK
Call-ID: 84kkX4y~cJ
Max-Forwards: 70
Authorization: Digest realm="fritz.box", nonce="DFAB0607BDB84DE5", username="620", uri="sip:[email protected]", response="2e07d64eaa78b68d5702d7d73e75044f"
User-Agent: Linphone Desktop/4.1.1 (belle-sip/1.6.3)
[11:21:40:351][Info]Core:belle-sip: Garbage collecting unowned object of type belle_sip_hop_t
[11:21:40:351][Info]Core:belle-sip: Garbage collecting unowned object of type belle_sdp_session_description_t
[11:21:40:356][Info]Core:linphone: MSAudioMixer [0x197dd90] is entering bypass mode.
[11:21:40:356][Info]Core:linphone: Stun packet sent for session [0x1b03c70]
[11:21:40:356][Info]Core:linphone: Stun packet sent on rtcp for session [0x1b03c70]
[11:21:40:719][Info]Core:linphone: Sending RTCP SR compound message on session [0x1b03c70].
[11:21:40:733][Info]Core:linphone: MSAudio_stream_iterate[0x1dd2470], local statistics available:
Local current jitter buffer size: 0.0ms
[11:21:40:733][Info]Core:linphone: Linphone core [0x7f27f4009780] notified [call_stats_updated]
[11:21:40:860][Info]Core:linphone: Stun packet sent on rtcp for session [0x1b03c70]
[11:21:41:232][Info]Core:linphone: Bandwidth usage for call [0x1975550]:
RTP audio=[d= 0.0,u= 81.2], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.0,u= 1.8], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:21:41:232][Info]Core:linphone: Thread processing load: audio=4.521949 video=0.000000 text=0.000000
[11:21:41:360][Info]Core:linphone: Stun packet sent on rtcp for session [0x1b03c70]
[11:21:41:369][Info]Core:linphone: sound/wall clock skew is average=5.264422 ms
[11:21:41:859][Info]Core:linphone: Stun packet sent on rtcp for session [0x1b03c70]
[11:21:42:232][Info]Core:linphone: Bandwidth usage for call [0x1975550]:
RTP audio=[d= 0.0,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.0,u= 0.8], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:21:42:232][Info]Core:linphone: Thread processing load: audio=0.917742 video=0.000000 text=0.000000
[11:21:42:360][Info]Core:linphone: Stun packet sent on rtcp for session [0x1b03c70]
[11:21:42:370][Info]Core:linphone: sound/wall clock skew is average=9.331976 ms
[11:21:42:879][Info]Core:linphone: Stun packet sent on rtcp for session [0x1b03c70]
[11:21:43:232][Info]Core:linphone: Bandwidth usage for call [0x1975550]:
RTP audio=[d= 0.0,u= 79.2], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.0,u= 0.8], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:21:43:232][Info]Core:linphone: Thread processing load: audio=0.928618 video=0.000000 text=0.000000
[11:21:43:369][Info]Core:linphone: sound/wall clock skew is average=11.130369 ms
[11:21:43:380][Info]Core:linphone: Stun packet sent on rtcp for session [0x1b03c70]
[11:21:43:690][Info]Core:linphone: Sending RTCP SR compound message on session [0x1b03c70].
[11:21:43:692][Info]Core:linphone: MSAudio_stream_iterate[0x1dd2470], local statistics available:
Local current jitter buffer size: 0.0ms
[11:21:43:692][Info]Core:linphone: Linphone core [0x7f27f4009780] notified [call_stats_updated]
[11:21:43:832][Info]Core:belle-sip: channel [0x190e0d0]: keep alive sent to [UDP://sip.linphone.org:5060]
[11:21:43:832][Info]Core:belle-sip: channel [0x185efd0]: keep alive sent to [UDP://fritz.box:5060]
[11:21:43:854][Warning]Core:belle-sip: There were still [1] object pools for thread [139809808733952] while the thread exited.
[11:21:43:854][Info]Core:belle-sip: Garbage collecting unowned object of type LinphoneVideoDefinition
[11:21:43:880][Info]Core:linphone: Stun packet sent on rtcp for session [0x1b03c70]
[11:21:44:232][Info]Core:linphone: Bandwidth usage for call [0x1975550]:
RTP audio=[d= 0.0,u= 80.1], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.0,u= 1.8], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:21:44:232][Info]Core:linphone: Thread processing load: audio=0.857276 video=0.000000 text=0.000000
[11:21:44:369][Info]Core:linphone: sound/wall clock skew is average=11.729667 ms
[11:21:44:380][Info]Core:linphone: Stun packet sent on rtcp for session [0x1b03c70]
[11:21:44:879][Info]Core:linphone: Stun packet sent on rtcp for session [0x1b03c70]
[11:21:45:232][Info]Core:linphone: Bandwidth usage for call [0x1975550]:
RTP audio=[d= 0.0,u= 79.9], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.0,u= 0.8], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:21:45:232][Info]Core:linphone: Thread processing load: audio=0.915319 video=0.000000 text=0.000000
[11:21:45:369][Info]Core:linphone: sound/wall clock skew is average=11.882409 ms
[11:21:45:380][Info]Core:linphone: Stun packet sent on rtcp for session [0x1b03c70]
[11:21:45:879][Info]Core:linphone: Stun packet sent on rtcp for session [0x1b03c70]
[11:21:46:173][Info]Core:belle-sip: channel [0x185efd0]: received [691] new bytes from [UDP://fritz.box:5060]:
BYE sip:[email protected];transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.42.1:5060;branch=z9hG4bK4B45811B7FC061CD
From: <sip:[email protected]>;tag=F5825075817F3F75
To: <sip:[email protected]>;tag=QdY4Aegwm
Call-ID: 84kkX4y~cJ
CSeq: 22 BYE
X-RTP-Stat: CS=0;PS=268;ES=665;OS=42880;SP=0/0;SO=0;QS=-;PR=0;ER=665;OR=0;CR=0;SR=0;QR=-;PL=0,0;BL=0;LS=0;RB=0/0;SB=0/0;EN=PCMU,G722;DE=;JI=0,0;DL=0,0,0;IP=192.168.42.1:7078,187.24.5.131:7078
X-RTP-Stat-Add: DQ=0;DSS=0;DS=0;PLCS=0;JS=0
Reason: Q.850; cause=16
Max-Forwards: 70
User-Agent: AVM FRITZ!Box 6490 Cable (kdg) 141.06.66 TAL (Oct 24 2016)
Supported: 100rel,replaces,timer
Allow-Events: telephone-event,refer
Content-Length: 0
[11:21:46:179][Info]Core:belle-sip: channel [0x185efd0] [691] bytes parsed
[11:21:46:180][Info]Core:belle-sip: Changing [server] [BYE] transaction [0x20b4880], from state [INIT] to [TRYING]
[11:21:46:181][Info]Core:belle-sip: Changing [server] [BYE] transaction [0x20b4880], from state [TRYING] to [COMPLETED]
[11:21:46:181][Info]Core:belle-sip: channel [0x185efd0]: message sent to [UDP://fritz.box:5060], size: [301] bytes
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 192.168.42.1:5060;branch=z9hG4bK4B45811B7FC061CD
From: <sip:[email protected]>;tag=F5825075817F3F75
To: <sip:[email protected]>;tag=QdY4Aegwm
Call-ID: 84kkX4y~cJ
CSeq: 22 BYE
User-Agent: Linphone Desktop/4.1.1 (belle-sip/1.6.3)
Supported: replaces, outbound
[11:21:46:181][Info]Core:belle-sip: Dialog [0x1dcc200]: now updated by transaction [0x20b4880].
[11:21:46:181][Info]Core:belle-sip: Dialog [0x1dcc200] deleted (is_expired=0)
[11:21:46:181][Info]Core:linphone: Current call terminated...
[11:21:46:184][Info]Core:linphone: ms_ticker_set_time_func: ticker's time method updated.
[11:21:46:186][Info]Core:linphone: Filter MSRtpRecv is not scheduled; nothing to do.
[11:21:46:186][Info]Core:linphone: ===========================================================
[11:21:46:186][Info]Core:linphone: AUDIO SESSION'S RTP STATISTICS
[11:21:46:186][Info]Core:linphone: -----------------------------------------------------------
[11:21:46:186][Info]Core:linphone: sent 662 packets
[11:21:46:186][Info]Core:linphone: 0 duplicated packets
[11:21:46:186][Info]Core:linphone: 113864 bytes
[11:21:46:186][Info]Core:linphone: received 0 packets
[11:21:46:186][Info]Core:linphone: 0 duplicated packets
[11:21:46:186][Info]Core:linphone: 0 bytes
[11:21:46:186][Info]Core:linphone: incoming delivered to the app 0 bytes
[11:21:46:186][Info]Core:linphone: incoming cumulative lost 0 packets
[11:21:46:186][Info]Core:linphone: incoming received too late 0 packets
[11:21:46:186][Info]Core:linphone: incoming bad formatted 0 packets
[11:21:46:186][Info]Core:linphone: incoming discarded (queue overflow) 0 packets
[11:21:46:186][Info]Core:linphone: sent rtcp 0 packets
[11:21:46:186][Info]Core:linphone: received rtcp 0 packets
[11:21:46:186][Info]Core:linphone: ===========================================================
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSPulseRead:0x1b72ae0,0-->MSEqualizer:0x1dd91f0,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSEqualizer:0x1dd91f0,0-->MSVolume:0x1de2ff0,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSVolume:0x1de2ff0,0-->MSAudioMixer:0x197dd90,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSAudioMixer:0x197dd90,0-->MSUlawEnc:0x1be9cd0,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSUlawEnc:0x1be9cd0,0-->MSRtpSend:0x1bdd0b0,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSRtpRecv:0x1dfa580,0-->MSUlawDec:0x1fe87e0,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSUlawDec:0x1fe87e0,0-->MSGenericPLC:0x1be9200,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSGenericPLC:0x1be9200,0-->MSAudioFlowControl:0x1dd98a0,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSAudioFlowControl:0x1dd98a0,0-->MSDtmfGen:0x1d659d0,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSDtmfGen:0x1d659d0,0-->MSVolume:0x1de2c30,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSVolume:0x1de2c30,0-->MSTee:0x19724d0,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSTee:0x19724d0,0-->MSEqualizer:0x1dd9990,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSEqualizer:0x1dd9990,0-->MSAudioMixer:0x1dd1bd0,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSFilePlayer:0x1dd1450,0-->MSResample:0x1dd1360,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSResample:0x1dd1360,0-->MSAudioMixer:0x1dd1bd0,1
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSAudioMixer:0x1dd1bd0,0-->MSPulseWrite:0x19a2e20,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSAudioMixer:0x1abcae0,1-->MSResample:0x1dce410,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSResample:0x1dce410,0-->MSOpusEnc:0x1ddf620,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSOpusEnc:0x1ddf620,0-->MSMKVRecorder:0x1af9620,1
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSItcSource:0x1de5bd0,0-->MSMKVRecorder:0x1af9620,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSAudioMixer:0x197dd90,1-->MSAudioMixer:0x1abcae0,0
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSTee:0x19724d0,1-->MSAudioMixer:0x1abcae0,1
[11:21:46:187][Info]Core:linphone: ms_filter_unlink: MSAudioMixer:0x1abcae0,0-->MSFileRec:0x1aaf2c0,0
[11:21:46:187][Info]Core:linphone: ===========================================================
[11:21:46:187][Info]Core:linphone: FILTER USAGE STATISTICS
[11:21:46:187][Info]Core:linphone: Name Count Time/tick (ms) CPU Usage
[11:21:46:187][Info]Core:linphone: -----------------------------------------------------------
[11:21:46:187][Info]Core:linphone: MSOpusEnc 99 0.186382 28.3573
[11:21:46:187][Info]Core:linphone: MSRtpSend 583 0.0243429 21.6295
[11:21:46:187][Info]Core:linphone: MSRtpRecv 583 0.0178804 15.8873
[11:21:46:188][Info]Core:linphone: MSPulseRead 583 0.0146957 13.0576
[11:21:46:188][Info]Core:linphone: MSUlawEnc 269 0.0166129 6.82448
[11:21:46:188][Info]Core:linphone: MSAudioMixer 1749 0.00191994 5.11196
[11:21:46:188][Info]Core:linphone: MSPulseWrite 99 0.017826 2.71216
[11:21:46:188][Info]Core:linphone: MSVolume 269 0.006362 2.61348
[11:21:46:188][Info]Core:linphone: MSItcSource 583 0.000932688 0.828725
[11:21:46:188][Info]Core:linphone: MSGenericPLC 583 0.000833284 0.740401
[11:21:46:188][Info]Core:linphone: MSDtmfGen 583 0.000792264 0.703953
[11:21:46:188][Info]Core:linphone: MSFilePlayer 583 0.000686253 0.609759
[11:21:46:188][Info]Core:linphone: MSEqualizer 269 0.00143084 0.58778
[11:21:46:188][Info]Core:linphone: MSFileRec 99 0.00092748 0.141113
[11:21:46:188][Info]Core:linphone: MSMKVRecorder 49 0.00130392 0.0991932
[11:21:46:188][Info]Core:linphone: MSResample 99 0.00062661 0.0953363
[11:21:46:188][Info]Core:linphone: MSUlawDec 0 0 0
[11:21:46:188][Info]Core:linphone: MSAudioFlowControl 0 0 0
[11:21:46:188][Info]Core:linphone: MSG722Dec 0 0 0
[11:21:46:188][Info]Core:linphone: MSG722Enc 0 0 0
[11:21:46:188][Info]Core:linphone: MSVoidSink 0 0 0
[11:21:46:188][Info]Core:linphone: MSItcSink 0 0 0
[11:21:46:188][Info]Core:linphone: MSTee 0 0 0
[11:21:46:188][Info]Core:linphone: MSWebRTCAEC 0 0 0
[11:21:46:188][Info]Core:linphone: ===========================================================
[11:21:46:188][Info]Core:linphone: MKVRecorder: destroyed
[11:21:46:188][Info]Core:linphone: ===========================================================
[11:21:46:189][Info]Core:linphone: FILTER USAGE STATISTICS
[11:21:46:189][Info]Core:linphone: Name Count Time/tick (ms) CPU Usage
[11:21:46:189][Info]Core:linphone: -----------------------------------------------------------
[11:21:46:189][Info]Core:linphone: MSOpusEnc 99 0.186382 28.3573
[11:21:46:189][Info]Core:linphone: MSRtpSend 583 0.0243429 21.6295
[11:21:46:189][Info]Core:linphone: MSRtpRecv 583 0.0178804 15.8873
[11:21:46:189][Info]Core:linphone: MSPulseRead 583 0.0146957 13.0576
[11:21:46:189][Info]Core:linphone: MSUlawEnc 269 0.0166129 6.82448
[11:21:46:189][Info]Core:linphone: MSAudioMixer 1749 0.00191994 5.11196
[11:21:46:189][Info]Core:linphone: MSPulseWrite 99 0.017826 2.71216
[11:21:46:189][Info]Core:linphone: MSVolume 269 0.006362 2.61348
[11:21:46:189][Info]Core:linphone: MSItcSource 583 0.000932688 0.828725
[11:21:46:189][Info]Core:linphone: MSGenericPLC 583 0.000833284 0.740401
[11:21:46:189][Info]Core:linphone: MSDtmfGen 583 0.000792264 0.703953
[11:21:46:189][Info]Core:linphone: MSFilePlayer 583 0.000686253 0.609759
[11:21:46:189][Info]Core:linphone: MSEqualizer 269 0.00143084 0.58778
[11:21:46:189][Info]Core:linphone: MSFileRec 99 0.00092748 0.141113
[11:21:46:189][Info]Core:linphone: MSMKVRecorder 49 0.00130392 0.0991932
[11:21:46:189][Info]Core:linphone: MSResample 99 0.00062661 0.0953363
[11:21:46:189][Info]Core:linphone: MSUlawDec 0 0 0
[11:21:46:189][Info]Core:linphone: MSAudioFlowControl 0 0 0
[11:21:46:189][Info]Core:linphone: MSG722Dec 0 0 0
[11:21:46:189][Info]Core:linphone: MSG722Enc 0 0 0
[11:21:46:189][Info]Core:linphone: MSVoidSink 0 0 0
[11:21:46:189][Info]Core:linphone: MSItcSink 0 0 0
[11:21:46:189][Info]Core:linphone: MSTee 0 0 0
[11:21:46:189][Info]Core:linphone: MSWebRTCAEC 0 0 0
[11:21:46:189][Info]Core:linphone: ===========================================================
[11:21:46:190][Info]Core:linphone: Call 0x1975550: moving from state LinphoneCallStreamsRunning to LinphoneCallEnd
[11:21:46:190][Info]Core:linphone: Notifying soundcard that we don't need it anymore for calls.
[11:21:46:190][Info]Core:linphone: Stopping ZRTP context on session [(nil)]
[11:21:46:190][Info]Core:linphone: ZRTP context destroyed
[11:21:46:190][Info]Core:linphone: MSAudio MSTicker thread exiting
[11:21:46:190][Info]Core:linphone: Stopping ZRTP context on session [(nil)]
[11:21:46:190][Info]Core:linphone: ZRTP context destroyed
[11:21:46:221][Info]Core:linphone: Linphone core [0x7f27f4009780] notified [call_log_updated]
[11:21:46:221][Info]Core:linphone: Resetting the current call
[11:21:46:239][Info]Core:linphone: Linphone core [0x7f27f4009780] notified [call_state_changed]
[11:21:46:241][Info]Core:linphone: Publishing report with custom route sip:[email protected];transport=tls
[11:21:46:241][Info]Core:linphone: Skipping top route of initial route-set because same as request-uri.
[11:21:46:241][Error]Core:belle-sip: No listening point matching for [tls://sip.linphone.org:5061]
[11:21:46:241][Error]Core:belle-sip: belle_sip_client_transaction_send_request(): no channel available
[11:21:46:241][Info]Core:belle-sip: Changing [client] [PUBLISH] transaction [0x19ceaa0], from state [INIT] to [TERMINATED]
[11:21:46:241][Info]Core:belle-sip: Client PUBLISH transaction [0x19ceaa0] terminated
[11:21:46:241][Info]Core:linphone: Unhandled transaction terminated [0x19ceaa0]
[11:21:46:241][Info]Core:linphone: LinphoneEvent [0x1a0bcc0] moving to publish state LinphonePublishError
[11:21:46:241][Info]Core:linphone: Destroying op [0x1a1a870] of type [SalOpUnknown]
[11:21:46:241][Info]Core:belle-sip: Transaction [0x19ceaa0] deleted
[11:21:46:241][Info]Core:linphone: QualityReporting[0x1975550]: Send 'VQSessionReport: CallTerm' with status 4
[11:21:46:250][Info]Core:linphone: Dialog [0x1dcc200] terminated for op [0x1ba01d0]
[11:21:46:279][Info]Core:linphone: Call 0x1975550: moving from state LinphoneCallEnd to LinphoneCallReleased
[11:21:46:279][Info]Core:linphone: Linphone core [0x7f27f4009780] notified [call_state_changed]
[11:21:46:279][Info]Core:linphone: op [0x1ba01d0] : set_or_update_dialog() current=[0x1dcc200] new=[(nil)]
[11:21:49:313][0xba7da0][Info]/run/build/linphone-qt/src/components/calls/CallsListModel.cpp:221: "Removing call:" CallModel(0x1a91040)
[11:21:49:313][Info]Core:belle-sip: QT: /run/build/linphone-qt/src/components/calls/CallsListModel.cpp:221: "Removing call:" CallModel(0x1a91040)
[11:21:49:315][0xba7da0][Warning]:0: DelegateModel::cancel: index out range 2 0
[11:21:49:315][Warning]Core:belle-sip: QT: :0: DelegateModel::cancel: index out range 2 0
[11:21:49:319][Info]Core:linphone: Call [0x1975550] freed.
incoming call (working):
[11:16:44:197][Info]Core:belle-sip: Changing [client] [REGISTER] transaction [0x21ddde0], from state [COMPLETED] to [TERMINATED]
[11:16:44:197][Info]Core:belle-sip: Client internal REGISTER transaction [0x21ddde0] terminated
[11:16:44:197][Info]Core:belle-sip: Transaction [0x21ddde0] deleted
[11:16:44:216][Info]Core:belle-sip: Changing [client] [REGISTER] transaction [0x2e6ba10], from state [COMPLETED] to [TERMINATED]
[11:16:44:216][Info]Core:belle-sip: Client internal REGISTER transaction [0x2e6ba10] terminated
[11:16:49:156][Info]Core:belle-sip: channel [0x26b6680]: keep alive sent to [UDP://sip.linphone.org:5060]
[11:16:49:156][Info]Core:belle-sip: channel [0x21c18c0]: keep alive sent to [UDP://fritz.box:5060]
[11:16:51:336][Info]Core:belle-sip: channel [0x21c18c0]: received [958] new bytes from [UDP://fritz.box:5060]:
INVITE sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.42.1:5060;branch=z9hG4bK8BE755874C8BB629
From: <sip:[email protected]>;tag=EF4ECED9F6794341
To: <sip:[email protected]>;+sip.instance="<urn:uuid:8e6361b4-0399-4af3-a0f0-505120f41f08>"
Call-ID: [email protected]
CSeq: 96 INVITE
Contact: <sip:[email protected]>
Max-Forwards: 70
Expires: 120
User-Agent: AVM FRITZ!Box 6490 Cable (kdg) 141.06.66 TAL (Oct 24 2016)
Supported: 100rel,replaces,timer
Allow-Events: telephone-event,refer
Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH
Content-Type: application/sdp
Accept: application/sdp, multipart/mixed
Accept-Encoding: identity
Content-Length: 194
v=0
o=user 1635462 1635462 IN IP4 192.168.42.1
s=call
c=IN IP4 192.168.42.1
t=0 0
m=audio 7094 RTP/AVP 8 0 101
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtcp:7095
[11:16:51:343][Info]Core:belle-sip: channel [0x21c18c0] [764] bytes parsed
[11:16:51:343][Info]Core:belle-sip: channel [0x21c18c0] read [194] bytes of body from [fritz.box:5060]
[11:16:51:345][Info]Core:belle-sip: Changing [server] [INVITE] transaction [0x2b9e420], from state [INIT] to [PROCEEDING]
[11:16:51:346][Info]Core:belle-sip: channel [0x21c18c0]: message sent to [UDP://fritz.box:5060], size: [295] bytes
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.42.1:5060;branch=z9hG4bK8BE755874C8BB629
From: <sip:[email protected]>;tag=EF4ECED9F6794341
To: <sip:[email protected]>;+sip.instance="<urn:uuid:8e6361b4-0399-4af3-a0f0-505120f41f08>"
Call-ID: [email protected]
CSeq: 96 INVITE
[11:16:51:346][Info]Core:belle-sip: New server dialog [0x27b47c0] , local tag [YL2gy8p], remote tag [EF4ECED9F6794341]
[11:16:51:346][Info]Core:linphone: op [0x233e260] : set_or_update_dialog() current=[(nil)] new=[0x27b47c0]
[11:16:51:346][Info]Core:linphone: new incoming call from [sip:[email protected]] to [sip:[email protected]]
[11:16:51:347][Info]Core:linphone: Found payload PCMA/8000 fmtp=
[11:16:51:347][Info]Core:linphone: Found payload PCMU/8000 fmtp=
[11:16:51:347][Info]Core:linphone: Found payload telephone-event/8000 fmtp=0-15
[11:16:51:348][Info]Core:linphone: Searching for already_a_call_with_remote_address.
[11:16:51:348][Info]Core:linphone: Found media local-ip from signaling.
[11:16:51:348][Info]Core:linphone: New LinphoneCall [0x279bed0] initialized (LinphoneCore version: 3.12.0)
[11:16:51:348][Info]Core:linphone: audio stream index found: 0, updating main audio stream index
[11:16:51:348][Info]Core:linphone: Call [0x279bed0]: disabling video in our call params because the remote doesn't want it.
[11:16:51:348][Info]Core:linphone: Call [0x279bed0], stream type [audio], multicast role is [inactive]
[11:16:51:349][Info]Core:linphone: RtpSession bound to [0.0.0.0] ports [7078] [7079]
[11:16:51:349][Info]Core:linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION
[11:16:51:349][Info]Core:linphone: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80
[11:16:51:349][Info]Core:linphone: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32
[11:16:51:349][Info]Core:linphone: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80
[11:16:51:349][Info]Core:linphone: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32
[11:16:51:349][Info]Core:linphone: Creating ZRTP engine on rtp session [0x246b470] ssrc 0x70689b02
[11:16:51:350][Info]Core:linphone: Setting DSCP to 46 for MSAudio stream.
[11:16:51:350][Info]Core:linphone: Equalizer location: hp
[11:16:51:350][Info]Core:linphone: cannot set noise gate mode to [0] because no volume send
[11:16:51:350][Info]Core:linphone: Call [0x279bed0], stream type [video], multicast role is [inactive]
[11:16:51:350][Info]Core:linphone: RtpSession bound to [0.0.0.0] ports [9078] [9079]
[11:16:51:350][Info]Core:linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION
[11:16:51:350][Info]Core:linphone: Initializing multistream ZRTP context on rtp session [0x273fb70] ssrc 0x3cd34013
[11:16:51:350][Info]Core:linphone: Setting DSCP to 0 for MSVideo stream.
[11:16:51:350][Info]Core:linphone: Call [0x279bed0], stream type [text], multicast role is [inactive]
[11:16:51:350][Info]Core:linphone: RtpSession bound to [0.0.0.0] ports [11078] [11079]
[11:16:51:350][Info]Core:linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION
[11:16:51:350][Info]Core:linphone: Created new ICE check list for stream [0]
[11:16:51:351][Info]Core:linphone: Found media local-ip from signaling.
[11:16:51:351][Info]Core:linphone: Don't put video stream on local offer for call [0x279bed0]
[11:16:51:351][Info]Core:linphone: Don't put text stream on local offer for call [0x279bed0]
[11:16:51:351][Info]Core:linphone: Doing SDP offer/answer process of type incoming
[11:16:51:351][Info]Core:linphone: Linphone core [0x7fc1cc009780] notified [call_created]
[11:16:51:352][Info]Core:linphone: Starting local ringtone...
[11:16:51:352][Info]Core:linphone: speex_lib_ctl init with neon ? 0
[11:16:51:352][Info]Core:linphone: MKVPlayer: opening /app/share/sounds/linphone/rings/notes_of_the_optimistic.mkv
[11:16:51:353][Warning]Core:linphone: MKVPlayer: no default video track. Looking for first video track
[11:16:51:354][Warning]Core:linphone: MKVPlayer: no video track found
[11:16:51:358][Info]Core:linphone: Configuring resampler input with rate=[48000], nchannels=[2]
[11:16:51:358][Info]Core:linphone: configuring resampler output to rate=[48000], nchannels=[2]
[11:16:51:358][Info]Core:linphone: ms_filter_link: MSMKVPlayer:0x2c90950,1-->MSOpusDec:0x29f1f70,0
[11:16:51:358][Info]Core:linphone: ms_filter_link: MSOpusDec:0x29f1f70,0-->MSDtmfGen:0x2b97ad0,0
[11:16:51:358][Info]Core:linphone: ms_filter_link: MSDtmfGen:0x2b97ad0,0-->MSResample:0x2bec4d0,0
[11:16:51:358][Info]Core:linphone: ms_filter_link: MSResample:0x2bec4d0,0-->MSPulseWrite:0x1e14220,0
[11:16:51:358][Info]Core:linphone: Priority used: 99
[11:16:51:358][Info]Core:linphone: Initializing speex resampler in mode [voip]
[11:16:51:358][Info]Core:linphone: Ring MSTicker priority set to SCHED_RR and value (99)
[11:16:51:360][Info]Core:linphone: pulseaudio playback stream connected (48000Hz, 2ch)
[11:16:51:360][Info]Core:linphone: Call 0x279bed0: moving from state LinphoneCallIdle to LinphoneCallIncomingReceived
[11:16:51:360][0x1509da0][Info]/run/build/linphone-qt/src/components/calls/CallsListModel.cpp:187: "Add call:" CallModel(0x2c624a0)
[11:16:51:360][Info]Core:belle-sip: QT: /run/build/linphone-qt/src/components/calls/CallsListModel.cpp:187: "Add call:" CallModel(0x2c624a0)
[11:16:51:376][0x1509da0][Info]/run/build/linphone-qt/src/components/notifier/Notifier.cpp:127: "Create notification:" NotificationReceivedCall_QMLTYPE_268(0x2795e20)
[11:16:51:377][Info]Core:belle-sip: QT: /run/build/linphone-qt/src/components/notifier/Notifier.cpp:127: "Create notification:" NotificationReceivedCall_QMLTYPE_268(0x2795e20)
[11:16:51:384][Info]Core:linphone: Linphone core [0x7fc1cc009780] notified [call_state_changed]
[11:16:51:384][Info]Core:linphone: Contact has been fixed using proxy
[11:16:51:384][Info]Core:belle-sip: channel [0x21c18c0]: message sent to [UDP://fritz.box:5060], size: [393] bytes
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.42.1:5060;branch=z9hG4bK8BE755874C8BB629
From: <sip:[email protected]>;tag=EF4ECED9F6794341
To: <sip:[email protected]>;+sip.instance="<urn:uuid:8e6361b4-0399-4af3-a0f0-505120f41f08>";tag=YL2gy8p
Call-ID: [email protected]
CSeq: 96 INVITE
User-Agent: Linphone Desktop/4.1.1 (belle-sip/1.6.3)
Supported: replaces, outbound
[11:16:51:384][Info]Core:belle-sip: Dialog [0x27b47c0]: now updated by transaction [0x2b9e420].
[11:16:52:057][Info]Core:linphone: incoming call ringing for 1 seconds
[11:16:53:058][Info]Core:linphone: incoming call ringing for 2 seconds
[11:16:53:073][Info]Core:linphone: Stop ringing
[11:16:53:074][Info]Core:linphone: opus decoder stats: fec 0 packets - plc 0 packets.
[11:16:53:075][Info]Core:linphone: ms_filter_unlink: MSMKVPlayer:0x2c90950,1-->MSOpusDec:0x29f1f70,0
[11:16:53:075][Info]Core:linphone: ms_filter_unlink: MSOpusDec:0x29f1f70,0-->MSDtmfGen:0x2b97ad0,0
[11:16:53:075][Info]Core:linphone: ms_filter_unlink: MSDtmfGen:0x2b97ad0,0-->MSResample:0x2bec4d0,0
[11:16:53:075][Info]Core:linphone: ms_filter_unlink: MSResample:0x2bec4d0,0-->MSPulseWrite:0x1e14220,0
[11:16:53:078][Info]Core:linphone: Ring MSTicker thread exiting
[11:16:53:080][Info]Core:linphone: Found media local-ip from signaling.
[11:16:53:080][Info]Core:linphone: Don't put video stream on local offer for call [0x279bed0]
[11:16:53:080][Info]Core:linphone: Don't put text stream on local offer for call [0x279bed0]
[11:16:53:080][Info]Core:linphone: Configuring prefered card sampling rate to [48000]
[11:16:53:080][Info]Core:linphone: Accepting server transaction [0x2b9e420] on op [0x233e260]
[11:16:53:080][Info]Core:linphone: Doing SDP offer/answer process of type incoming
[11:16:53:081][Info]Core:belle-sip: channel [0x21c18c0]: message sent to [UDP://fritz.box:5060], size: [787] bytes
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 192.168.42.1:5060;branch=z9hG4bK8BE755874C8BB629
From: <sip:[email protected]>;tag=EF4ECED9F6794341
To: <sip:[email protected]>;+sip.instance="<urn:uuid:8e6361b4-0399-4af3-a0f0-505120f41f08>";tag=YL2gy8p
Call-ID: [email protected]
CSeq: 96 INVITE
User-Agent: Linphone Desktop/4.1.1 (belle-sip/1.6.3)
Supported: replaces, outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Contact: <sip:[email protected];transport=udp>;+sip.instance="<urn:uuid:8e6361b4-0399-4af3-a0f0-505120f41f08>"
Content-Type: application/sdp
Content-Length: 145
v=0
o=620 2627 2610 IN IP4 192.168.42.2
s=Talk
c=IN IP4 192.168.42.2
t=0 0
m=audio 7078 RTP/AVP 8 0 101
a=rtpmap:101 telephone-event/8000
[11:16:53:081][Info]Core:belle-sip: Changing [server] [INVITE] transaction [0x2b9e420], from state [PROCEEDING] to [ACCEPTED]
[11:16:53:081][Info]Core:belle-sip: Dialog [0x27b47c0]: now updated by transaction [0x2b9e420].
[11:16:53:081][Info]Core:linphone: Call 0x279bed0: moving from state LinphoneCallIncomingReceived to LinphoneCallConnected
[11:16:53:111][0x1509da0][Info]/run/build/linphone-qt/src/components/notifier/Notifier.cpp:193: "Delete notification:" NotificationReceivedCall_QMLTYPE_268(0x2795e20)
[11:16:53:111][Info]Core:belle-sip: QT: /run/build/linphone-qt/src/components/notifier/Notifier.cpp:193: "Delete notification:" NotificationReceivedCall_QMLTYPE_268(0x2795e20)
[11:16:53:111][Info]Core:linphone: Linphone core [0x7fc1cc009780] notified [call_state_changed]
[11:16:53:111][Info]Core:linphone: linphone_call_start_media_streams() call=[0x279bed0] local upload_bandwidth=[0] kbit/s; local download_bandwidth=[0] kbit/s
[11:16:53:111][Info]Core:linphone: Audio bandwidth for this call is 80
[11:16:53:111][Info]Core:linphone: RtpSession [0x246b470] sending to rtp 192.168.42.1:7094 rtcp 192.168.42.1:7095
[11:16:53:111][Info]Core:linphone: Stun packet sent for session [0x246b470]
[11:16:53:111][Info]Core:linphone: Stun packet sent on rtcp for session [0x246b470]
[11:16:53:112][Info]Core:linphone: MKVRecorder: initialisation
[11:16:53:112][Info]Core:linphone: speex_lib_ctl init with neon ? 0
[11:16:53:112][Info]Core:linphone: Configuring av recorder with audio format type=audio;encoding=opus;rate=48000;channels=1;fmtp=''
[11:16:53:112][Info]Core:linphone: MKVRecorder: set pin #1 format. type=audio;encoding=opus;rate=48000;channels=1;fmtp=''
[11:16:53:112][Info]Core:linphone: target bitrate not set for stream [0x2e00fd0] using payload's bitrate is 80000
[11:16:53:112][Info]Core:linphone: Setting audio encoder network bitrate to [80000] on stream [0x2e00fd0]
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSPulseRead:0x3090960,0-->MSEqualizer:0x3439550,0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSEqualizer:0x3439550,0-->MSVolume:0x2bf7360,0
[11:16:53:112][Info]Core:linphone: Priority used: 99
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSVolume:0x2bf7360,0-->MSAudioMixer:0x3091260,0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSAudioMixer:0x3091260,0-->MSAlawEnc:0x3090e20,0
[11:16:53:112][Info]Core:linphone: MSAudio MSTicker priority set to SCHED_RR and value (99)
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSAlawEnc:0x3090e20,0-->MSRtpSend:0x2764d20,0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSRtpRecv:0x3090780,0-->MSAlawDec:0x3091050,0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSAlawDec:0x3091050,0-->MSGenericPLC:0x343a350,0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSGenericPLC:0x343a350,0-->MSAudioFlowControl:0x343a420,0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSAudioFlowControl:0x343a420,0-->MSDtmfGen:0x3090830,0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSDtmfGen:0x3090830,0-->MSVolume:0x1d91410,0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSVolume:0x1d91410,0-->MSTee:0x3091640,0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSTee:0x3091640,0-->MSEqualizer:0x3439c10,0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSEqualizer:0x3439c10,0-->MSAudioMixer:0x343a550,0
[11:16:53:112][Info]Core:linphone: speex_lib_ctl init with neon ? 0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSFilePlayer:0x34690d0,0-->MSResample:0x3469160,0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSResample:0x3469160,0-->MSAudioMixer:0x343a550,1
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSAudioMixer:0x343a550,0-->MSPulseWrite:0x3090bd0,0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSAudioMixer:0x3091450,1-->MSResample:0x2166fc0,0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSResample:0x2166fc0,0-->MSOpusEnc:0x2164550,0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSOpusEnc:0x2164550,0-->MSMKVRecorder:0x3091770,1
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSItcSource:0x2166f10,0-->MSMKVRecorder:0x3091770,0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSAudioMixer:0x3091260,1-->MSAudioMixer:0x3091450,0
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSTee:0x3091640,1-->MSAudioMixer:0x3091450,1
[11:16:53:112][Info]Core:linphone: ms_filter_link: MSAudioMixer:0x3091450,0-->MSFileRec:0x30906b0,0
[11:16:53:113][Info]Core:linphone: pulseaudio record stream connected (8000Hz, 1ch)
[11:16:53:113][Info]Core:linphone: ms_ticker_set_time_func: ticker's time method updated.
[11:16:53:114][Info]Core:linphone: Initializing speex resampler in mode [voip]
[11:16:53:114][Info]Core:linphone: pulseaudio playback stream connected (8000Hz, 1ch)
[11:16:53:114][Info]Core:linphone: Initializing speex resampler in mode [voip]
[11:16:53:114][Info]Core:linphone: MSOpusEnc: codec bitrate set to [30000] with ptime [20]
[11:16:53:114][Info]Core:linphone: Setting opus codec bitrate to [30000] from network bitrate [46000] with ptime [20]
[11:16:53:114][Info]Core:linphone: Filter MSRtpRecv is already being scheduled; nothing to do.
[11:16:53:114][Error]Core:linphone: no such method on filter MSPulseWrite, fid=16394 method index=2
[11:16:53:114][Info]Core:linphone: MSVolume set gain to [0.000000 db], [1.000000] linear
[11:16:53:114][Info]Core:linphone: audio_stream_link_video() connecting itc filters
[11:16:53:114][Info]Core:linphone: No valid video stream defined.
[11:16:53:114][Info]Core:linphone: LinphoneCall[0x279bed0] : payload type 8 PCMA/8000 fmtp= added to frozen list.
[11:16:53:114][Info]Core:linphone: LinphoneCall[0x279bed0] : payload type 0 PCMU/8000 fmtp= added to frozen list.
[11:16:53:114][Info]Core:linphone: LinphoneCall[0x279bed0] : payload type 101 telephone-event/8000 fmtp= added to frozen list.
[11:16:53:114][Info]Core:linphone: Call 0x279bed0: moving from state LinphoneCallConnected to LinphoneCallStreamsRunning
[11:16:53:118][Info]Core:linphone: Linphone core [0x7fc1cc009780] notified [call_state_changed]
[11:16:53:118][Info]Core:linphone: Call answered
[11:16:53:122][Info]Core:linphone: Stun packet sent for session [0x246b470]
[11:16:53:122][Info]Core:linphone: Stun packet sent on rtcp for session [0x246b470]
[11:16:53:123][Info]Core:linphone: MSAudioMixer [0x3091260] is entering bypass mode.
[11:16:53:184][Info]Core:linphone: First estimation
[11:16:53:194][Info]Core:belle-sip: channel [0x21c18c0]: received [500] new bytes from [UDP://fritz.box:5060]:
ACK sip:[email protected];transport=udp SIP/2.0
Via: SIP/2.0/udp 192.168.42.1:5060;branch=z9hG4bK146E7CBF63D91A5E
From: <sip:[email protected]>;tag=EF4ECED9F6794341
To: <sip:[email protected]>;+sip.instance="<urn:uuid:8e6361b4-0399-4af3-a0f0-505120f41f08>";tag=YL2gy8p
Call-ID: [email protected]
CSeq: 96 ACK
Contact: <sip:[email protected]>
Max-Forwards: 70
User-Agent: AVM FRITZ!Box 6490 Cable (kdg) 141.06.66 TAL (Oct 24 2016)
Content-Length: 0
[11:16:53:195][Info]Core:belle-sip: channel [0x21c18c0] [500] bytes parsed
[11:16:53:195][Info]Core:belle-sip: Incoming INVITE has ACK, dialog is happy
[11:16:53:624][Info]Core:linphone: Stun packet sent on rtcp for session [0x246b470]
[11:16:54:055][Info]Core:linphone: First estimation
[11:16:54:055][Info]Core:linphone: Bandwidth usage for call [0x279bed0]:
RTP audio=[d= 75.5,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:16:54:055][Info]Core:linphone: Thread processing load: audio=4.917971 video=0.000000 text=0.000000
[11:16:54:114][Info]Core:linphone: sound/wall clock skew is average=-1.634010 ms
[11:16:54:114][Info]Core:linphone: MSAudioMixer [0x343a550] is entering bypass mode.
[11:16:54:123][Info]Core:linphone: Stun packet sent on rtcp for session [0x246b470]
[11:16:54:624][Info]Core:linphone: Stun packet sent on rtcp for session [0x246b470]
[11:16:54:783][Info]Core:linphone: Sending RTCP SR compound message on session [0x246b470].
[11:16:54:794][Info]Core:linphone: MSAudio_stream_iterate[0x2e00fd0], local statistics available:
Local current jitter buffer size: 58.2ms
[11:16:54:798][Info]Core:linphone: Linphone core [0x7fc1cc009780] notified [call_stats_updated]
[11:16:55:054][Info]Core:linphone: Bandwidth usage for call [0x279bed0]:
RTP audio=[d= 76.5,u= 79.8], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.0,u= 1.2], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:16:55:055][Info]Core:linphone: Thread processing load: audio=1.446715 video=0.000000 text=0.000000
[11:16:55:113][Info]Core:linphone: sound/wall clock skew is average=-1.304937 ms
[11:16:55:124][Info]Core:linphone: Stun packet sent on rtcp for session [0x246b470]
[11:16:55:594][Info]Core:linphone: MSAudio stream [0x2e00fd0]: receiving RTCP SR
[11:16:55:599][Info]Core:linphone: Linphone core [0x7fc1cc009780] notified [call_stats_updated]
[11:16:56:054][Info]Core:linphone: Bandwidth usage for call [0x279bed0]:
RTP audio=[d= 76.6,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.6,u= 2.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:16:56:054][Info]Core:linphone: Thread processing load: audio=1.341884 video=0.000000 text=0.000000
[11:16:56:114][Info]Core:linphone: sound/wall clock skew is average=-0.970456 ms
[11:16:57:054][Info]Core:linphone: Bandwidth usage for call [0x279bed0]:
RTP audio=[d= 76.5,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
RTCP audio=[d= 0.0,u= 0.4], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec
[11:16:57:054][Info]Core:linphone: Thread processing load: audio=1.393845 video=0.000000 text=0.000000
[11:16:57:087][Info]Core:linphone: Terminate call [0x279bed0] which is currently in state LinphoneCallStreamsRunning
[11:16:57:087][Info]Core:belle-sip: Dialog [0x27b47c0]: now updated by transaction [0x22afae0].
[11:16:57:087][Info]Core:belle-sip: Changing [client] [BYE] transaction [0x22afae0], from state [INIT] to [TRYING]
[11:16:57:087][Info]Core:belle-sip: channel [0x21c18c0]: message sent to [UDP://fritz.box:5060], size: [417] bytes
BYE sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.42.2:5060;branch=z9hG4bK.a0A74dCa-;rport
From: <sip:[email protected]>;+sip.instance="<urn:uuid:8e6361b4-0399-4af3-a0f0-505120f41f08>";tag=YL2gy8p
To: <sip:[email protected]>;tag=EF4ECED9F6794341
CSeq: 111 BYE
Call-ID: [email protected]
Max-Forwards: 70
User-Agent: Linphone Desktop/4.1.1 (belle-sip/1.6.3)
[11:16:57:088][Info]Core:linphone: ms_ticker_set_time_func: ticker's time method updated.
[11:16:57:089][Info]Core:linphone: Filter MSRtpRecv is not scheduled; nothing to do.
[11:16:57:089][Info]Core:linphone: ===========================================================
[11:16:57:089][Info]Core:linphone: AUDIO SESSION'S RTP STATISTICS
[11:16:57:089][Info]Core:linphone: -----------------------------------------------------------
[11:16:57:090][Info]Core:linphone: sent 198 packets
[11:16:57:090][Info]Core:linphone: 0 duplicated packets
[11:16:57:090][Info]Core:linphone: 34056 bytes
[11:16:57:090][Info]Core:linphone: received 196 packets
[11:16:57:090][Info]Core:linphone: 0 duplicated packets
[11:16:57:090][Info]Core:linphone: 33712 bytes
[11:16:57:090][Info]Core:linphone: incoming delivered to the app 33196 bytes
[11:16:57:090][Info]Core:linphone: incoming cumulative lost 0 packets
[11:16:57:090][Info]Core:linphone: incoming received too late 0 packets
[11:16:57:090][Info]Core:linphone: incoming bad formatted 0 packets
[11:16:57:090][Info]Core:linphone: incoming discarded (queue overflow) 0 packets
[11:16:57:090][Info]Core:linphone: sent rtcp 1 packets
[11:16:57:090][Info]Core:linphone: received rtcp 1 packets
[11:16:57:090][Info]Core:linphone: ===========================================================
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSPulseRead:0x3090960,0-->MSEqualizer:0x3439550,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSEqualizer:0x3439550,0-->MSVolume:0x2bf7360,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSVolume:0x2bf7360,0-->MSAudioMixer:0x3091260,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSAudioMixer:0x3091260,0-->MSAlawEnc:0x3090e20,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSAlawEnc:0x3090e20,0-->MSRtpSend:0x2764d20,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSRtpRecv:0x3090780,0-->MSAlawDec:0x3091050,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSAlawDec:0x3091050,0-->MSGenericPLC:0x343a350,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSGenericPLC:0x343a350,0-->MSAudioFlowControl:0x343a420,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSAudioFlowControl:0x343a420,0-->MSDtmfGen:0x3090830,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSDtmfGen:0x3090830,0-->MSVolume:0x1d91410,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSVolume:0x1d91410,0-->MSTee:0x3091640,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSTee:0x3091640,0-->MSEqualizer:0x3439c10,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSEqualizer:0x3439c10,0-->MSAudioMixer:0x343a550,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSFilePlayer:0x34690d0,0-->MSResample:0x3469160,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSResample:0x3469160,0-->MSAudioMixer:0x343a550,1
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSAudioMixer:0x343a550,0-->MSPulseWrite:0x3090bd0,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSAudioMixer:0x3091450,1-->MSResample:0x2166fc0,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSResample:0x2166fc0,0-->MSOpusEnc:0x2164550,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSOpusEnc:0x2164550,0-->MSMKVRecorder:0x3091770,1
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSItcSource:0x2166f10,0-->MSMKVRecorder:0x3091770,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSAudioMixer:0x3091260,1-->MSAudioMixer:0x3091450,0
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSTee:0x3091640,1-->MSAudioMixer:0x3091450,1
[11:16:57:090][Info]Core:linphone: ms_filter_unlink: MSAudioMixer:0x3091450,0-->MSFileRec:0x30906b0,0
[11:16:57:091][Info]Core:linphone: ===========================================================
[11:16:57:091][Info]Core:linphone: FILTER USAGE STATISTICS
[11:16:57:091][Info]Core:linphone: Name Count Time/tick (ms) CPU Usage
[11:16:57:091][Info]Core:linphone: -----------------------------------------------------------
[11:16:57:091][Info]Core:linphone: MSOpusEnc 99 0.239856 26.9132
[11:16:57:091][Info]Core:linphone: MSRtpRecv 398 0.0318543 14.2612
[11:16:57:091][Info]Core:linphone: MSRtpSend 398 0.0264899 11.8596
[11:16:57:091][Info]Core:linphone: MSOpusDec 171 0.0599792 11.5756
[11:16:57:091][Info]Core:linphone: MSPulseWrite 335 0.0228068 8.59844
[11:16:57:091][Info]Core:linphone: MSPulseRead 398 0.0180096 8.06292
[11:16:57:091][Info]Core:linphone: MSAlawEnc 250 0.0131654 3.70787
[11:16:57:091][Info]Core:linphone: MSAudioMixer 1194 0.00271772 3.64408
[11:16:57:091][Info]Core:linphone: MSVolume 444 0.00507771 2.53538
[11:16:57:091][Info]Core:linphone: MSMKVPlayer 171 0.0120985 2.33494
[11:16:57:091][Info]Core:linphone: MSAlawDec 193 0.00965515 2.10173
[11:16:57:091][Info]Core:linphone: MSGenericPLC 398 0.00293094 1.31219
[11:16:57:091][Info]Core:linphone: MSEqualizer 444 0.00112566 0.56206
[11:16:57:091][Info]Core:linphone: MSTee 194 0.00252651 0.552804
[11:16:57:091][Info]Core:linphone: MSDtmfGen 569 0.000852833 0.54545
[11:16:57:091][Info]Core:linphone: MSItcSource 398 0.00105105 0.470555
[11:16:57:091][Info]Core:linphone: MSFilePlayer 398 0.000688045 0.308039
[11:16:57:091][Info]Core:linphone: MSAudioFlowControl 194 0.00112173 0.245437
[11:16:57:091][Info]Core:linphone: MSResample 185 0.000744113 0.155299
[11:16:57:091][Info]Core:linphone: MSFileRec 99 0.00125615 0.140947
[11:16:57:091][Info]Core:linphone: MSMKVRecorder 49 0.00199966 0.112187
[11:16:57:091][Info]Core:linphone: MSUlawDec 0 0 0
[11:16:57:091][Info]Core:linphone: MSUlawEnc 0 0 0
[11:16:57:091][Info]Core:linphone: MSG722Dec 0 0 0
[11:16:57:091][Info]Core:linphone: MSG722Enc 0 0 0
[11:16:57:091][Info]Core:linphone: MSVoidSink 0 0 0
[11:16:57:091][Info]Core:linphone: MSItcSink 0 0 0
[11:16:57:091][Info]Core:linphone: MSWebRTCAEC 0 0 0
[11:16:57:091][Info]Core:linphone: ===========================================================
[11:16:57:092][Info]Core:linphone: MKVRecorder: destroyed
[11:16:57:092][Info]Core:linphone: ===========================================================
[11:16:57:092][Info]Core:linphone: FILTER USAGE STATISTICS
[11:16:57:092][Info]Core:linphone: Name Count Time/tick (ms) CPU Usage
[11:16:57:092][Info]Core:linphone: -----------------------------------------------------------
[11:16:57:092][Info]Core:linphone: MSOpusEnc 99 0.239856 26.9132
[11:16:57:092][Info]Core:linphone: MSRtpRecv 398 0.0318543 14.2612
[11:16:57:092][Info]Core:linphone: MSRtpSend 398 0.0264899 11.8596
[11:16:57:092][Info]Core:linphone: MSOpusDec 171 0.0599792 11.5756
[11:16:57:092][Info]Core:linphone: MSPulseWrite 335 0.0228068 8.59844
[11:16:57:092][Info]Core:linphone: MSPulseRead 398 0.0180096 8.06292
[11:16:57:092][Info]Core:linphone: MSAlawEnc 250 0.0131654 3.70787
[11:16:57:092][Info]Core:linphone: MSAudioMixer 1194 0.00271772 3.64408
[11:16:57:092][Info]Core:linphone: MSVolume 444 0.00507771 2.53538
[11:16:57:092][Info]Core:linphone: MSMKVPlayer 171 0.0120985 2.33494
[11:16:57:092][Info]Core:linphone: MSAlawDec 193 0.00965515 2.10173
[11:16:57:092][Info]Core:linphone: MSGenericPLC 398 0.00293094 1.31219
[11:16:57:092][Info]Core:linphone: MSEqualizer 444 0.00112566 0.56206
[11:16:57:092][Info]Core:linphone: MSTee 194 0.00252651 0.552804
[11:16:57:092][Info]Core:linphone: MSDtmfGen 569 0.000852833 0.54545
[11:16:57:092][Info]Core:linphone: MSItcSource 398 0.00105105 0.470555
[11:16:57:092][Info]Core:linphone: MSFilePlayer 398 0.000688045 0.308039
[11:16:57:092][Info]Core:linphone: MSAudioFlowControl 194 0.00112173 0.245437
[11:16:57:092][Info]Core:linphone: MSResample 185 0.000744113 0.155299
[11:16:57:092][Info]Core:linphone: MSFileRec 99 0.00125615 0.140947
[11:16:57:092][Info]Core:linphone: MSMKVRecorder 49 0.00199966 0.112187
[11:16:57:092][Info]Core:linphone: MSUlawDec 0 0 0
[11:16:57:092][Info]Core:linphone: MSUlawEnc 0 0 0
[11:16:57:092][Info]Core:linphone: MSG722Dec 0 0 0
[11:16:57:092][Info]Core:linphone: MSG722Enc 0 0 0
[11:16:57:093][Info]Core:linphone: MSVoidSink 0 0 0
[11:16:57:093][Info]Core:linphone: MSItcSink 0 0 0
[11:16:57:093][Info]Core:linphone: MSWebRTCAEC 0 0 0
[11:16:57:093][Info]Core:linphone: ===========================================================
[11:16:57:093][Info]Core:linphone: Call 0x279bed0: moving from state LinphoneCallStreamsRunning to LinphoneCallEnd
[11:16:57:093][Info]Core:linphone: Notifying soundcard that we don't need it anymore for calls.
[11:16:57:093][Info]Core:linphone: Stopping ZRTP context on session [(nil)]
[11:16:57:093][Info]Core:linphone: ZRTP context destroyed
[11:16:57:093][Info]Core:linphone: MSAudio MSTicker thread exiting
[11:16:57:093][Info]Core:linphone: Stopping ZRTP context on session [(nil)]
[11:16:57:094][Info]Core:linphone: ZRTP context destroyed
[11:16:57:127][Info]Core:linphone: Linphone core [0x7fc1cc009780] notified [call_log_updated]
[11:16:57:127][Info]Core:linphone: Resetting the current call
[11:16:57:131][Info]Core:linphone: Linphone core [0x7fc1cc009780] notified [call_state_changed]
[11:16:57:131][Info]Core:linphone: Publishing report with custom route sip:[email protected];transport=tls
[11:16:57:132][Info]Core:linphone: Skipping top route of initial route-set because same as request-uri.
[11:16:57:132][Error]Core:belle-sip: No listening point matching for [tls://sip.linphone.org:5061]
[11:16:57:132][Error]Core:belle-sip: belle_sip_client_transaction_send_request(): no channel available
[11:16:57:132][Info]Core:belle-sip: Changing [client] [PUBLISH] transaction [0x22777b0], from state [INIT] to [TERMINATED]
[11:16:57:132][Info]Core:belle-sip: Client PUBLISH transaction [0x22777b0] terminated
[11:16:57:132][Info]Core:linphone: Unhandled transaction terminated [0x22777b0]
[11:16:57:132][Info]Core:linphone: LinphoneEvent [0x2764d20] moving to publish state LinphonePublishError
[11:16:57:132][Info]Core:linphone: Destroying op [0x245e6b0] of type [SalOpUnknown]
[11:16:57:132][Info]Core:belle-sip: Transaction [0x22777b0] deleted
[11:16:57:132][Info]Core:linphone: QualityReporting[0x279bed0]: Send 'VQSessionReport: CallTerm' with status 4
Hi,
In your case, you might probably work-around this issue by disabling STUN/ICE from Linphone's settings.
The udp payload for the invite is huge the initial one is border line too big the second one with the authentication header is way to big. Switch to tcp or prune all but the necessary codecs. could be getting a 415 response because fritzbox is trying to parse a truncated invite. Better yet, don't try to inter-operate with a fritzbox.
I am seeing exactly this too.
My workaround is to hold and then resume(unhold) the call. After that I have perfect audio.
Maybe it has to do with this (where I got the workaround): https://github.com/BelledonneCommunications/linphone-android/issues/174
The same workarround works for me, but that shouldn't be the end of the line...
After I do the "workaround" I get choppy audio. On incoming calls the audio is fine. Does somebody experience the same problem?
The better question is: What do the devs need to fix that issue, so it won't happen in the first place...
Blocking
127.0.0.1 stun.linphone.org
127.0.0.1 sip.linphone.org
didn't help me at all but @rtreleaven pointed me in the right direction:
After disabling all audio codecs in Linphone which are not supported by my FritzBox outgoing calls are working with audio again.
@Eusebius1920 Only G.711a (A-Law), G.711u (µ-Law) and G.722 but you could've just compared the link I posted above and the Linphones audio settings panel.
@l1m3r I tried it and it did not work for me. My FritzBox supports the exact same codecs, but I have to pause and unpause the call in order to receive audio.
I suspect Fritzbox is the problem.
On Wed, Feb 20, 2019 at 10:00 AM Eusebius1920 [email protected] wrote:
@l1m3r https://github.com/l1m3r I tried it and it did not work for me. My FritzBox supports the exact same codecs, but I have to pause and unpause the call in order to receive audio.
— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/BelledonneCommunications/linphone-desktop/issues/118#issuecomment-465613118, or mute the thread https://github.com/notifications/unsubscribe-auth/AdEjOzxBfvSo8hsybQXxGUJPHbsA5835ks5vPWMAgaJpZM4RFRea .
-- Sincerely,
Russell Treleaven sip:[email protected];transport=tcp
@l1m3r I tried it and it did not work for me. My FritzBox supports the exact same codecs, but I have to pause and unpause the call in order to receive audio.
Same here, linphone 4.1.1 via flatpak on Fedora 30 to a FritzBox.
Also, IPv6 has to be disabled, otherwise even the pause trick does not help.
I followed SIP traffic with wireshark, and did some experiments, and now it works!
The main problem is this: linphone uses STUN although it is disabled in preferences. This yields to the INVITES establishing the call having two c=IN IP4 ... fields, one for the internal (private) network, and one for the external IP (btw, this can also be seen in @bhelm's log). There are also a=candidate fields (ICE?), but anyway, the FritzBox doesn't like that, and the result is no audio.
But: the later INVITE that resumes the call, does only have one single c=IN IP4 ... field, this time for the internal IP, and now the FritzBox knows where to send the RTP stream, result: audio.
Clearly, linphone shouldn't use STUN/ICE in that case, especially when it's disabled.
For me, the workaround of adding
127.0.0.1 stun.linphone.org
127.0.0.1 sip.linphone.org
to /etc/hosts helps, however, I had to fully restart linphone for it to take effect.
See also https://gitlab.linphone.org/BC/public/linphone-desktop/issues/5, btw. Not sure which one gets more attention by the upstream developers.
I am seeing exactly this too.
My workaround is to hold and then resume(unhold) the call. After that I have perfect audio.
Maybe it has to do with this (where I got the workaround): BelledonneCommunications/linphone-android#174
It is not working for me. Can you suggest some other solution? I can not hear voice from other party but my other party can hear my voice properly
@yudhisthira123 check the workarround @thmo proposed. this is working for me aswell.
I am also using @thmo s workaround since he posted it. Works like a charm (with my fritzbox)!
This started happening all the sudden for me. Linphone was working fine then all the sudden two way audio disappeared. When I make a call, the receiver hears me but I can't hear them. Then when I put the call on hold, and pick it back up, then audio is normal. I have tried using only supported audio codecs but that does not seem to help.
Backup linphonerc to linphonerc.bak then completely close linphone delete the contents of linphonerc and reload Linphone. You will have to re resetup the SIP account but then then audio should be fixed. I am not sure what has caused this but this fix worked for me. linphonerc is in c:/User/%USER%/AppData/local/linphone on windows and ~/.linphonerc. @thmo is exactly right about the sip invite headers however this behavior seems to come from one of the variables in the config file and rebuilding it causes it to stop using the stun settings if it is not supposed to.
Had the same problem with the windows version of linphone and FritzBox 7360 . Adding
127.0.0.1 stun.linphone.org
127.0.0.1 sip.linphone.org
in C:\Windows\System32\drivers\etc\hosts solved the problem. No need to disable audio codecs etc.
Hi,
In your case, you might probably work-around this issue by disabling STUN/ICE from Linphone's settings.
I was facing same issue and it was fixed when I disabled ICE from Linphone settings. @jehandelalune Thank you for you suggestion.
I have the same problem with Linphone (Version 4.2.3, OS X 10.15/11.0) behind a FritzBox (7560 / Firmware 7.12). Sometimes, I could not hear the caller and the caller can not hear me. The problem occurs with incoming and outgoing calls.
I already tried to:
- Add the
127.0.0.1entries to the hosts file - Enabled / disabled IPv6
- Enabled / disabled codecs
- Putting the caller on hold
- Deleting the configuration
- Enabled / disabled stun
- Switching between UDP / TCP as transport
I restarted Linphone between all of these actions. With the same settings, sometimes audio works, and sometimes audio does not work. I enabled the logging for the calls. In case when audio works, I don't see any error in the logfile. In case audio does not work, I see a lot of [linphone/ortp] ERROR RtpSession [0x7fb2add20c00] error sending [rtp] packet [...] Operation not permitted errors in the logfile. The first of these errors is logged right after accepting the call (marked with ---> in the logfile).
Has anybody an idea why some of the calls are failing?
Thank you very much Jan
Incoming call - with audio
2020-11-15 14:55:23:541 [linphone/mediastreamer] MESSAGE Format for [lpcm] rate [48000] channels [1]
2020-11-15 14:55:23:541 [linphone/mediastreamer] MESSAGE Format for [lpcm] rate [48000] channels [1]
2020-11-15 14:55:23:541 [linphone/mediastreamer] MESSAGE Number of frames per buffer = 512
2020-11-15 14:55:23:934 [linphone/mediastreamer] MESSAGE Initializing speex resampler in mode [voip]
2020-11-15 14:55:23:934 [linphone/bctbx] MESSAGE Initializing WebRTC echo canceler with framesize=160, delay_ms=70, delay_samples=1120
2020-11-15 14:55:23:934 [linphone/mediastreamer] MESSAGE Initializing speex resampler in mode [voip]
2020-11-15 14:55:23:934 [linphone/mediastreamer] MESSAGE MSOpusEnc: codec bitrate set to [30000] with ptime [20]
2020-11-15 14:55:23:934 [linphone/mediastreamer] MESSAGE Setting opus codec bitrate to [30000] from network bitrate [46000] with ptime [20]
2020-11-15 14:55:23:934 [linphone/mediastreamer] MESSAGE Initializing speex resampler in mode [voip]
2020-11-15 14:55:23:934 [linphone/mediastreamer] MESSAGE Initializing speex resampler in mode [voip]
2020-11-15 14:55:23:938 [linphone/mediastreamer] MESSAGE Format for [lpcm] rate [48000] channels [2]
2020-11-15 14:55:23:939 [linphone/mediastreamer] MESSAGE Format for [lpcm] rate [48000] channels [1]
2020-11-15 14:55:23:939 [linphone/mediastreamer] MESSAGE Number of frames per buffer = 512
2020-11-15 14:55:24:347 [linphone/mediastreamer] MESSAGE Filter MSRtpRecv is already being scheduled; nothing to do.
2020-11-15 14:55:24:347 [linphone/mediastreamer] MESSAGE MSVolume set gain to [0.000000 db], [1.000000] linear
2020-11-15 14:55:24:347 [linphone/mediastreamer] MESSAGE MSVolume set gain to [0.000000 db], [1.000000] linear
2020-11-15 14:55:24:347 [linphone/liblinphone] MESSAGE CallSession[0x600001fa3798] : payload type 9 G722/8000 fmtp= added to frozen list
2020-11-15 14:55:24:348 [linphone/liblinphone] MESSAGE CallSession[0x600001fa3798] : payload type 8 PCMA/8000 fmtp= added to frozen list
2020-11-15 14:55:24:348 [linphone/liblinphone] MESSAGE CallSession[0x600001fa3798] : payload type 0 PCMU/8000 fmtp= added to frozen list
2020-11-15 14:55:24:348 [linphone/liblinphone] MESSAGE CallSession[0x600001fa3798] : payload type 101 telephone-event/8000 fmtp= added to frozen list
2020-11-15 14:55:24:348 [linphone/liblinphone] MESSAGE CallSession [0x600001fa3798] moving from state LinphoneCallConnected to LinphoneCallStreamsRunning
2020-11-15 14:55:24:349 [linphone/liblinphone] MESSAGE Linphone core [0x7fb2ad8d8e00] notified [call_state_changed]
2020-11-15 14:53:51:298 [linphone/liblinphone] MESSAGE CallSession accepted
2020-11-15 14:53:51:301 [linphone/ortp] MESSAGE First estimation
2020-11-15 14:53:51:301 [linphone/mediastreamer] MESSAGE Stun packet sent for session [0x7fb2adc0e800]
2020-11-15 14:53:51:302 [linphone/mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x7fb2adc0e800]
2020-11-15 14:53:51:304 [linphone/belle-sip] MESSAGE channel [0x7fb2b3979000]: starting recv background task with id=[21].
2020-11-15 14:53:51:304 [linphone/belle-sip] MESSAGE channel [0x7fb2b3979000]: received [577] new bytes from [TCP://192.168.178.1:5060]:
ACK sip:[email protected]:60311;transport=tcp SIP/2.0
Via: SIP/2.0/TCP 192.168.178.1:5060;branch=z9hG4bKDB5A069474D99EF5;alias
From: "XXXX" <sip:[email protected]>;tag=0AA5B9AE2D5AE591
To: "XXXX" <sip:XXXXXXXXXX@XXXXXXXXXX:60311;avm_transport=tcp>;+sip.instance="<urn:uuid:e61fcbdd-335c-0011-8132-a71267c1851e>";tag=76sfa8H
Call-ID: [email protected]
CSeq: 55 ACK
Contact: <sip:XXXXXXXXXX@XXXXXXXXXX>
Max-Forwards: 70
User-Agent: AVM FRITZ!Box 7560 149.07.12 (Jul 3 2019)
Content-Length: 0
2020-11-15 14:53:51:308 [linphone/belle-sip] MESSAGE channel [0x7fb2b3979000] [577] bytes parsed
2020-11-15 14:53:51:308 [linphone/belle-sip] MESSAGE Incoming INVITE has ACK, dialog is happy
2020-11-15 14:53:51:308 [linphone/belle-sip] MESSAGE channel [0x7fb2b3979000]: ending recv background task with id=[21].
2020-11-15 14:53:51:311 [linphone/bctbx] WARNING Getting reference signal but no echo to synchronize on.
2020-11-15 14:53:51:311 [linphone/bctbx] WARNING Not enough ref samples, using zeroes
2020-11-15 14:53:51:311 [linphone/mediastreamer] MESSAGE MSAudioMixer [0x600000486da0] is entering bypass mode.
2020-11-15 14:53:51:321 [linphone/bctbx] MESSAGE Samples are back.
2020-11-15 14:53:51:605 [linphone/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=XXXXXXXXXX, realm=fritz.box
2020-11-15 14:53:51:605 [linphone/liblinphone] MESSAGE AuthStack::authFound() for Username[XXXXXXXXXX];Userid[];Realm[fritz.box];Domain[192.168.178.1];Algorithm[];
2020-11-15 14:53:51:605 [linphone/belle-sip] MESSAGE Auth info found for [XXXXXXXXXX] realm [fritz.box]
2020-11-15 14:53:51:605 [linphone/belle-sip] MESSAGE transaction [0x600004f78480]: starting transaction background task with id=[22].
2020-11-15 14:53:51:605 [linphone/belle-sip] MESSAGE Transaction [0x600004050180] deleted
2020-11-15 14:53:51:605 [linphone/belle-sip] MESSAGE Changing [client] [PUBLISH] transaction [0x600004f78480], from state [INIT] to [TRYING]
2020-11-15 14:53:51:605 [linphone/belle-sip] MESSAGE channel [0x7fb2b3979000]: message sent to [TCP://192.168.178.1:5060], size: [1224] bytes
PUBLISH sip:[email protected] SIP/2.0
Incoming call - without audio
2020-11-15 14:55:23:541 [linphone/mediastreamer] MESSAGE Format for [lpcm] rate [48000] channels [1]
2020-11-15 14:55:23:541 [linphone/mediastreamer] MESSAGE Format for [lpcm] rate [48000] channels [1]
2020-11-15 14:55:23:541 [linphone/mediastreamer] MESSAGE Number of frames per buffer = 512
2020-11-15 14:55:23:934 [linphone/mediastreamer] MESSAGE Initializing speex resampler in mode [voip]
2020-11-15 14:55:23:934 [linphone/bctbx] MESSAGE Initializing WebRTC echo canceler with framesize=160, delay_ms=70, delay_samples=1120
2020-11-15 14:55:23:934 [linphone/mediastreamer] MESSAGE Initializing speex resampler in mode [voip]
2020-11-15 14:55:23:934 [linphone/mediastreamer] MESSAGE MSOpusEnc: codec bitrate set to [30000] with ptime [20]
2020-11-15 14:55:23:934 [linphone/mediastreamer] MESSAGE Setting opus codec bitrate to [30000] from network bitrate [46000] with ptime [20]
2020-11-15 14:55:23:934 [linphone/mediastreamer] MESSAGE Initializing speex resampler in mode [voip]
2020-11-15 14:55:23:934 [linphone/mediastreamer] MESSAGE Initializing speex resampler in mode [voip]
2020-11-15 14:55:23:938 [linphone/mediastreamer] MESSAGE Format for [lpcm] rate [48000] channels [2]
2020-11-15 14:55:23:939 [linphone/mediastreamer] MESSAGE Format for [lpcm] rate [48000] channels [1]
2020-11-15 14:55:23:939 [linphone/mediastreamer] MESSAGE Number of frames per buffer = 512
2020-11-15 14:55:24:347 [linphone/mediastreamer] MESSAGE Filter MSRtpRecv is already being scheduled; nothing to do.
2020-11-15 14:55:24:347 [linphone/mediastreamer] MESSAGE MSVolume set gain to [0.000000 db], [1.000000] linear
2020-11-15 14:55:24:347 [linphone/mediastreamer] MESSAGE MSVolume set gain to [0.000000 db], [1.000000] linear
2020-11-15 14:55:24:347 [linphone/liblinphone] MESSAGE CallSession[0x600001fa3798] : payload type 9 G722/8000 fmtp= added to frozen list
2020-11-15 14:55:24:348 [linphone/liblinphone] MESSAGE CallSession[0x600001fa3798] : payload type 8 PCMA/8000 fmtp= added to frozen list
2020-11-15 14:55:24:348 [linphone/liblinphone] MESSAGE CallSession[0x600001fa3798] : payload type 0 PCMU/8000 fmtp= added to frozen list
2020-11-15 14:55:24:348 [linphone/liblinphone] MESSAGE CallSession[0x600001fa3798] : payload type 101 telephone-event/8000 fmtp= added to frozen list
2020-11-15 14:55:24:348 [linphone/liblinphone] MESSAGE CallSession [0x600001fa3798] moving from state LinphoneCallConnected to LinphoneCallStreamsRunning
2020-11-15 14:55:24:349 [linphone/liblinphone] MESSAGE Linphone core [0x7fb2ad8d8e00] notified [call_state_changed]
2020-11-15 14:55:24:349 [linphone/liblinphone] MESSAGE CallSession accepted
2020-11-15 14:55:24:349 [linphone/mediastreamer] MESSAGE Stun packet sent for session [0x7fb2add20c00]
---> 2020-11-15 14:55:24:349 [linphone/ortp] ERROR RtpSession [0x7fb2add20c00] error sending [rtp] packet [0x600000a21770] to 192.168.178.1:7090: Operation not permitted [1]
2020-11-15 14:55:24:349 [linphone/mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x7fb2add20c00]
2020-11-15 14:55:24:354 [linphone/belle-sip] MESSAGE channel [0x7fb2b3979000]: starting recv background task with id=[2f].
2020-11-15 14:55:24:354 [linphone/belle-sip] MESSAGE channel [0x7fb2b3979000]: received [577] new bytes from [TCP://192.168.178.1:5060]:
ACK sip:[email protected]:60311;transport=tcp SIP/2.0
Via: SIP/2.0/TCP 192.168.178.1:5060;branch=z9hG4bK63AFEBB7D2584448;alias
From: "XXXX" <sip:[email protected]>;tag=4FDB0BA7AA15D592
To: "XXXX" <sip:XXXXXXXXXX@XXXXXXXXXX:60311;avm_transport=tcp>;+sip.instance="<urn:uuid:e61fcbdd-335c-0011-8132-a71267c1851e>";tag=KPuE4W2
Call-ID: [email protected]
CSeq: 57 ACK
Contact: <sip:XXXXXXXXXX@XXXXXXXXXX>
Max-Forwards: 70
User-Agent: AVM FRITZ!Box 7560 149.07.12 (Jul 3 2019)
Content-Length: 0
2020-11-15 14:55:24:357 [linphone/belle-sip] MESSAGE channel [0x7fb2b3979000] [577] bytes parsed
2020-11-15 14:55:24:357 [linphone/belle-sip] MESSAGE Incoming INVITE has ACK, dialog is happy
2020-11-15 14:55:24:358 [linphone/belle-sip] MESSAGE channel [0x7fb2b3979000]: ending recv background task with id=[2f].
2020-11-15 14:55:24:359 [linphone/bctbx] WARNING Getting reference signal but no echo to synchronize on.
2020-11-15 14:55:24:359 [linphone/bctbx] WARNING Not enough ref samples, using zeroes
2020-11-15 14:55:24:359 [linphone/mediastreamer] MESSAGE MSAudioMixer [0x60000036abc0] is entering bypass mode.
2020-11-15 14:55:24:370 [linphone/bctbx] MESSAGE Samples are back.
2020-11-15 14:55:24:370 [linphone/ortp] ERROR RtpSession [0x7fb2add20c00] error sending [rtp] packet [0x600000a1bed0] to 192.168.178.1:7090: Operation not permitted [1]
2020-11-15 14:55:24:389 [linphone/ortp] ERROR RtpSession [0x7fb2add20c00] error sending [rtp] packet [0x600000a190e0] to 192.168.178.1:7090: Operation not permitted [1]
2020-11-15 14:55:24:410 [linphone/ortp] ERROR RtpSession [0x7fb2add20c00] error sending [rtp] packet [0x600000a1b750] to 192.168.178.1:7090: Operation not permitted [1]
2020-11-15 14:55:24:429 [linphone/ortp] ERROR RtpSession [0x7fb2add20c00] error sending [rtp] packet [0x600000a18780] to 192.168.178.1:7090: Operation not permitted [1]
2020-11-15 14:55:24:450 [linphone/ortp] ERROR RtpSession [0x7fb2add20c00] error sending [rtp] packet [0x600000a23660] to 192.168.178.1:7090: Operation not permitted [1]
2020-11-15 14:55:24:470 [linphone/ortp] ERROR RtpSession [0x7fb2add20c00] error sending [rtp] packet [0x600000a1a940] to 192.168.178.1:7090: Operation not permitted [1]
2020-11-15 14:55:24:490 [linphone/ortp] ERROR RtpSession [0x7fb2add20c00] error sending [rtp] packet [0x600000a22b20] to 192.168.178.1:7090: Operation not permitted [1]
2020-11-15 14:55:24:510 [linphone/ortp] ERROR RtpSession [0x7fb2add20c00] error sending [rtp] packet [0x600000a14a50] to 192.168.178.1:7090: Operation not permitted [1]
Hi everyone,
I had same problem. I tried most of the methods mentioned above. But finally, I just changed Preferences->Audio->Playback device and Capture device configurations from Pulse Audio to ALSA Unknown:default and now I have successful conversations.
Backup linphonerc to linphonerc.bak then completely close linphone delete the contents of linphonerc and reload Linphone. You will have to re resetup the SIP account but then then audio should be fixed. I am not sure what has caused this but this fix worked for me. linphonerc is in c:/User/%USER%/AppData/local/linphone on windows and ~/.linphonerc. @thmo is exactly right about the sip invite headers however this behavior seems to come from one of the variables in the config file and rebuilding it causes it to stop using the stun settings if it is not supposed to.
@sudo-RmRf If you have done this on your setup and it worked, could you try to figure out what variables/settings in the original linphonerc might have caused the issue by comparing the linphonerc.bak to linphonerc? This might not also help devs figuring out the problem, it could also provide a simpler workaround if we can only change the lines that actually cause the problem. Then you dont need to resetup the SIP account(s). :)
So, I tried this suggestion:
Backup linphonerc to linphonerc.bak then completely close linphone delete the contents of linphonerc and reload Linphone. You will have to re resetup the SIP account but then then audio should be fixed.
Sadly, it didn't do anything different for me. However, I then simply deleted alle lines like
stun_server=stun.linphone.org
protocols=stun,ice
in all [nat*] blocks, and fiddled around with some realm=... lines, and eventuelly got it to work. Hopefully this is of use to anyone. :)
So, I tried this suggestion:
Backup linphonerc to linphonerc.bak then completely close linphone delete the contents of linphonerc and reload Linphone. You will have to re resetup the SIP account but then then audio should be fixed.
Sadly, it didn't do anything different for me. However, I then simply deleted alle lines like
stun_server=stun.linphone.org protocols=stun,icein all
[nat*]blocks, and fiddled around with somerealm=...lines, and eventuelly got it to work. Hopefully this is of use to anyone. :)
Hello I am developing my android app useing Linphone SDK and I face the same issue with no voice I think your workaround will help Can you please explain to me in more details how to apply it? Under which files? What to change?
Hello I am developing my android app useing Linphone SDK and I face the same issue with no voice I think your workaround will help Can you please explain to me in more details how to apply it? Under which files? What to change?
So, in linphonerc which is situated in C:\User\%USER%\AppData\Local\linphone\linphonerc there are several configuration blocks whose name start with something like nat-. I think one of the most important ones will be [nat_policy_default_values]. And, since it has been theorized in this thread (or even proven), that the problem is due to spurious usage of STUN, I deleted the lines I mentioned in my cemmont above that I found in these config blocks, esp. in [nat_policy_default_values]. So, the diff for this particular thing would look something like:
< [nat_policy_default_values]
< stun_server=stun.linphone.org
< protocols=stun,ice
---
> [nat_policy_default_values]
I also remember, that I did something to lines starting with realm=, but I dont remember exactly I also was not consistent with my changes there, so it probably did nothing anyway.
@JosephMourice Let us know, if in developement you can narrow down even further what exactly has to be done. :)