Viish
commented
5 months ago Hi @Alessandro-v,
Please attach debug logs from app showing the issue.
Cheers,
Closed Alessandro-v closed 5 months ago
Viish
commented
5 months ago Hi @Alessandro-v,
Please attach debug logs from app showing the issue.
Cheers,
Alessandro-v
commented
5 months ago Those are the logs from a caller device, when the other device respond it immediately ends the call, let me know if you need more logs!
Thanks
2024-01-10 17:31:14:792 belle-sip-message- Changing [client] [BYE] transaction [0x2807a40c0], from state [COMPLETED] to [TERMINATED] 2024-01-10 17:31:14:792 belle-sip-message- Client BYE transaction [0x2807a40c0] terminated 2024-01-10 17:31:14:792 belle-sip-message- transaction [0x2807a40c0]: ending transaction background task with id=[2a].
2024-01-10 17:31:18:649 mediastreamer-message- au_update_ms_snd_card 2024-01-10 17:31:18:652 mediastreamer-message- Not activating the AVAudioSession because it is CallKit's job. 2024-01-10 17:31:18:652 mediastreamer-message- MSAURead/MSAUWrite: configureAudioSession() took 2 ms. 2024-01-10 17:31:18:659 liblinphone-message- New MediaSession [0x2819fd7b8] initialized (liblinphone version: 5.3.7) 2024-01-10 17:31:18:659 liblinphone-message- Rtp bundle is disabled. 2024-01-10 17:31:18:659 liblinphone-message- Found media local-ip from signaling connection: 192.187.1.1 2024-01-10 17:31:18:659 liblinphone-message- Guessed media local ip address is 192.187.1.1 2024-01-10 17:31:18:660 bctbx-warning- BctbxException occurred: 2024-01-10 17:31:18:662 liblinphone-message- [LIME] ../../../../../lime/src/lime_localStorage.cpp:269 Cannot find Lime User sip:mk2@151.4.4.2:52032;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip&B1AF1587770AACG7693K65HGDF6783CF89CC87E6649C43D15FF8:remote;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip&remote;pn-silent=1;pn-timeout=0;transport=udp in DB while setting up identity key for ZRTP auxiliary secret 2024-01-10 17:31:18:662 liblinphone-message- stream#0 [audio] in state [Stopped]: multicast role is [inactive] 2024-01-10 17:31:18:663 ortp-message- RtpSession bound to [::0] ports [60379] [64943] 2024-01-10 17:31:18:663 liblinphone-message- Configured srtp crypto suite: AEAD_AES_128_GCM 2024-01-10 17:31:18:663 liblinphone-message- Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80 2024-01-10 17:31:18:663 liblinphone-message- Configured srtp crypto suite: AEAD_AES_256_GCM 2024-01-10 17:31:18:663 liblinphone-message- Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80 2024-01-10 17:31:18:663 mediastreamer-message- Creating ZRTP engine on rtp session [0x144052000] ssrc 0xe5796d41 2024-01-10 17:31:18:664 ortp-message- rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2024-01-10 17:31:18:664 mediastreamer-message- Setting DSCP to 46 for MSAudio stream. 2024-01-10 17:31:18:664 liblinphone-message- Created stream of type audio at index 0: stream#0 [audio] in state [Stopped] 2024-01-10 17:31:18:665 liblinphone-message- AudioStream[0x14ae7f010]: mic is [enabled]. 2024-01-10 17:31:18:665 mediastreamer-warning- Could not apply gain on sent RTP packets: gain control wasn't activated. Use audio_stream_enable_gain_control() before starting the stream. 2024-01-10 17:31:18:665 liblinphone-message- [MS2AudioStream] setting type of soundcard 0x281694840 to voice 2024-01-10 17:31:18:665 liblinphone-message- Call's input audio device is iPhone Microfono 2024-01-10 17:31:18:665 liblinphone-message- [MS2AudioStream] setting type of soundcard 0x281694840 to voice 2024-01-10 17:31:18:665 liblinphone-message- [ToneManager] stopDtmfStream 2024-01-10 17:31:18:665 liblinphone-message- Linphone core [0x14600e800] notified [call_created] 2024-01-10 17:31:18:665 liblinphone-message- Notifying sound card that it is going to be used. 2024-01-10 17:31:18:665 liblinphone-message- CallSession [0x2819fd7b8] moving from state LinphoneCallIdle to LinphoneCallOutgoingInit 2024-01-10 17:31:18:665 bctbx-message- Background task Liblinphone cpu lock started. Unknown remaining time since application is not fully in background. 2024-01-10 17:31:18:665 liblinphone-message- Linphone core [0x14600e800] notified [first_call_started] 2024-01-10 17:31:18:665 liblinphone-message- Linphone core [0x14600e800] notified [call_state_changed] 2024-01-10 17:31:18:666 liblinphone-message- [ToneManager] stopDtmfStream 2024-01-10 17:31:18:666 ortp-warning- Fail to set IPv4 packet info on RTP socket: Invalid argument. 2024-01-10 17:31:18:666 ortp-warning- Fail to set IPv4 packet info on RTCP socket: Invalid argument. 2024-01-10 17:31:18:666 liblinphone-message- ICE state is IceStateNotActivated for stream#0 [audio] in state [Stopped] 2024-01-10 17:31:18:666 liblinphone-message- Contact Address(sip:com.test.app@151.4.4.2:52032;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip&B1AF1587770AACG7693K65HGDF6783CF89CC87E6649C43D15FF8:remote;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip&remote;pn-silent=1;pn-timeout=0;transport=udp;expires=3600) has been fixed using account 2024-01-10 17:31:18:667 liblinphone-message- Setting contact address for session 0x14ae548b0 to Address(sip:mk2@151.4.4.2:52032;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip&B1AF1587770AACG7693K65HGDF6783CF89CC87E6649C43D15FF8:remote;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip&remote;pn-silent=1;pn-timeout=0;transport=udp;expires=3600) 2024-01-10 17:31:18:667 liblinphone-message- [sip:com.test.app@sip.test.com] calling [sip:1033@sip.test.com] on op [0x1448c3800] 2024-01-10 17:31:18:667 liblinphone-message- Skipping top route of initial route-set because same as request-uri 2024-01-10 17:31:18:667 bctbx-message- Background task belle-sip transaction(0x2809b5d40) started. Unknown remaining time since application is not fully in background. 2024-01-10 17:31:18:667 belle-sip-message- transaction [0x2809b5d40]: starting transaction background task with id=[32]. 2024-01-10 17:31:18:667 belle-sip-message- Changing [client] [INVITE] transaction [0x2809b5d40], from state [INIT] to [CALLING] 2024-01-10 17:31:18:667 belle-sip-message- channel [0x149000000]: message sent to [UDP://sip.test.com:5060], size: [1295] bytes INVITE sip:1033@sip.test.com SIP/2.0
Via: SIP/2.0/UDP 192.187.1.1:52032;branch=z9hG4bK.pL5Po9ZvK;rport
From: sip:com.test.app@sip.test.com;tag=A5e4lUJxN
To: sip:1033@sip.test.com
CSeq: 20 INVITE
Call-ID: t6WBeRskTV
Max-Forwards: 70
Supported: replaces, outbound, gruu, path
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, PRACK, UPDATE
Content-Type: application/sdp
Content-Length: 509
User-Agent: Unknown
v=0
o=com.test.app 2092 3892 IN IP4 192.187.1.1
s=Talk
c=IN IP4 192.187.1.1
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 60379 RTP/AVP 96 97 98 0 8 101 99 100
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=rtpmap:101 telephone-event/48000
a=rtpmap:99 telephone-event/16000
a=rtpmap:100 telephone-event/8000
a=rtcp:64943
a=rtcp-fb:* trr-int 1000
a=rtcp-fb:* ccm tmmbr
2024-01-10 17:31:18:667 liblinphone-message- CallSession [0x2819fd7b8] moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress
2024-01-10 17:31:18:667 liblinphone-message- Unable to find audio video conference with conference ID ConferenceId(peer=sip:1033@sip.test.com, local=sip:1033@sip.test.com) in RAM.
2024-01-10 17:31:18:667 liblinphone-message- Linphone core [0x14600e800] notified [call_state_changed]
2024-01-10 17:31:18:668 liblinphone-message- MainDb::addEvent() of type ConferenceCallStarted (value 3)
2024-01-10 17:31:18:668 liblinphone-message- Insert new conference call in database: t6WBeRskTV
2024-01-10 17:31:18:668 liblinphone-message- Insert new sip address in database: sip:com.test.app@sip.test.com.
2024-01-10 17:31:18:668 liblinphone-message- Insert new sip address in database: sip:1033@sip.test.com.
2024-01-10 17:31:18:669 liblinphone-message- Linphone core [0x14600e800] notified [call_log_updated]
2024-01-10 17:31:18:713 bctbx-message- Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background.
2024-01-10 17:31:18:713 belle-sip-message- channel [0x149000000]: starting recv background task with id=[33].
2024-01-10 17:31:18:713 belle-sip-message- channel [0x149000000]: received [507] new bytes from [UDP://sip.test.com:5060]:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.187.1.4:52032;branch=z9hG4bK.pL5Po9ZvK;received=151.0.1.1;rport=52032
From: sip:com.test.app@sip.test.com;tag=A5e4lUJxN
To: sip:1033@sip.test.com;tag=as0f4e8ea8
Call-ID: t6WBeRskTV
CSeq: 20 INVITE
Server: Asterisk PBX 18.16.0
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="5a3f4969"
Content-Length: 0
2024-01-10 17:31:18:714 belle-sip-message- channel [0x149000000] [507] bytes parsed 2024-01-10 17:31:18:714 belle-sip-message- Found transaction matching response. 2024-01-10 17:31:18:714 belle-sip-message- Changing [client] [INVITE] transaction [0x2809b5d40], from state [CALLING] to [PROCEEDING] 2024-01-10 17:31:18:714 belle-sip-message- Changing [client] [INVITE] transaction [0x2809b5d40], from state [PROCEEDING] to [COMPLETED] 2024-01-10 17:31:18:714 belle-sip-message- channel [0x149000000]: message sent to [UDP://sip.test.com:5060], size: [583] bytes ACK sip:1033@sip.test.com SIP/2.0
Via: SIP/2.0/UDP 192.187.1.1:52032;branch=z9hG4bK.pL5Po9ZvK;rport
Call-ID: t6WBeRskTV
From: sip:com.test.app@sip.test.com;tag=A5e4lUJxN
To: sip:1033@sip.test.com;tag=as0f4e8ea8
Max-Forwards: 70
CSeq: 20 ACK
2024-01-10 17:31:18:714 liblinphone-message- linphone_core_find_auth_info(): returning auth info username=com.test.app, realm=asterisk 2024-01-10 17:31:18:714 liblinphone-message- AuthStack::authFound() for Username[com.test.app];Userid[];Realm[asterisk];Domain[sip.test.com];Algorithm[];AvailableAlgorithms[]; 2024-01-10 17:31:18:714 belle-sip-message- Auth info found for [mk2] realm [asterisk] 2024-01-10 17:31:18:714 bctbx-message- Background task belle-sip transaction(0x2809b6150) started. Unknown remaining time since application is not fully in background. 2024-01-10 17:31:18:714 belle-sip-message- transaction [0x2809b6150]: starting transaction background task with id=[34]. 2024-01-10 17:31:18:714 belle-sip-message- Changing [client] [INVITE] transaction [0x2809b6150], from state [INIT] to [CALLING] 2024-01-10 17:31:18:714 belle-sip-message- channel [0x149000000]: message sent to [UDP://sip.test.com:5060], size: [1468] bytes INVITE sip:1033@sip.test.com SIP/2.0
Via: SIP/2.0/UDP 192.187.1.1:52032;branch=z9hG4bK.ljBiJoF8J;rport
From: sip:com.test.app@sip.test.com;tag=A5e4lUJxN
To: sip:1033@sip.test.com
CSeq: 21 INVITE
Call-ID: t6WBeRskTV
Max-Forwards: 70
Supported: replaces, outbound, gruu, path
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, PRACK, UPDATE
Content-Type: application/sdp
Content-Length: 509
User-Agent: Unknown
Authorization: Digest realm="asterisk", nonce="5a3f4969", algorithm=MD5, username="com.test.app", uri="sip:1033@sip.test.com", response="10f7421aaff756b0cb7d0a1d5f9c23bf"
v=0
o=com.test.app 2092 3892 IN IP4 192.187.1.1
s=Talk
c=IN IP4 192.187.1.1
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 60379 RTP/AVP 96 97 98 0 8 101 99 100
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=rtpmap:101 telephone-event/48000
a=rtpmap:99 telephone-event/16000
a=rtpmap:100 telephone-event/8000
a=rtcp:64943
a=rtcp-fb:* trr-int 1000
a=rtcp-fb:* ccm tmmbr
2024-01-10 17:31:18:714 belle-sip-message- channel [0x149000000]: ending recv background task with id=[33]. 2024-01-10 17:31:18:737 mediastreamer-message- [IOS Audio Route Change] msiounit audio route change callback 2024-01-10 17:31:18:737 mediastreamer-message- [IOS Audio Route Change] Previous audio route: input=No input, output=Altoparlante, New audio route: input=iPhone Microfono, output=Ricevitore 2024-01-10 17:31:18:737 mediastreamer-message- [IOS Audio Route Change] Audio unit not initialized, ignore route change 2024-01-10 17:31:18:752 bctbx-message- Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2024-01-10 17:31:18:752 belle-sip-message- channel [0x149000000]: starting recv background task with id=[35]. 2024-01-10 17:31:18:752 belle-sip-message- channel [0x149000000]: received [450] new bytes from [UDP://sip.test.com:5060]: SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.187.1.1:52032;branch=z9hG4bK.ljBiJoF8J;received=151.4.4.2;rport=52032
From: sip:com.test.app@sip.test.com;tag=A5e4lUJxN
To: sip:1033@sip.test.com
Call-ID: t6WBeRskTV
CSeq: 21 INVITE
Server: Asterisk PBX 18.16.0
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: sip:1033@15.160.122.98:5060
Content-Length: 0
2024-01-10 17:31:18:753 belle-sip-message- channel [0x149000000] [450] bytes parsed 2024-01-10 17:31:18:753 belle-sip-message- Found transaction matching response. 2024-01-10 17:31:18:753 belle-sip-message- Changing [client] [INVITE] transaction [0x2809b6150], from state [CALLING] to [PROCEEDING] 2024-01-10 17:31:18:753 liblinphone-message- op [0x1448c3800] : setOrUpdateDialog() current=[0x0] new=[0x0] 2024-01-10 17:31:18:753 liblinphone-message- Op [0x1448c3800] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2024-01-10 17:31:18:753 belle-sip-message- channel [0x149000000]: ending recv background task with id=[35]. 2024-01-10 17:31:18:771 mediastreamer-message- AVAudioSession activated: 1 2024-01-10 17:31:18:993 bctbx-message- Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2024-01-10 17:31:18:993 belle-sip-message- channel [0x149000000]: starting recv background task with id=[36]. 2024-01-10 17:31:18:993 belle-sip-message- channel [0x149000000]: received [466] new bytes from [UDP://sip.test.com:5060]: SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.187.1.1:52032;branch=z9hG4bK.ljBiJoF8J;received=151.4.4.2;rport=52032
From: sip:com.test.app@sip.test.com;tag=A5e4lUJxN
To: sip:1033@sip.test.com;tag=as0ff46b3e
Call-ID: t6WBeRskTV
CSeq: 21 INVITE
Server: Asterisk PBX 18.16.0
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: sip:1033@15.160.122.98:5060
Content-Length: 0
2024-01-10 17:31:18:996 belle-sip-message- channel [0x149000000] [466] bytes parsed 2024-01-10 17:31:18:996 belle-sip-message- Found transaction matching response. 2024-01-10 17:31:18:996 belle-sip-message- New client dialog [0x280e96900] , local tag [A5e4lUJxN], remote tag [as0ff46b3e] 2024-01-10 17:31:18:996 belle-sip-message- Dialog [0x280e96900]: now updated by transaction [0x2809b6150]. 2024-01-10 17:31:18:997 liblinphone-message- op [0x1448c3800] : setOrUpdateDialog() current=[0x0] new=[0x280e96900] 2024-01-10 17:31:18:997 liblinphone-message- Op [0x1448c3800] receiving call response [180], dialog is [0x280e96900] in state [BELLE_SIP_DIALOG_EARLY] 2024-01-10 17:31:18:997 liblinphone-message- [ToneManager] startRingbackTone 2024-01-10 17:31:18:997 liblinphone-message- [ToneManager] destroyRingStream 2024-01-10 17:31:18:997 mediastreamer-message- ms_au_write_new, sound card : 0x281694840 (iPhone Microfono) 2024-01-10 17:31:18:997 mediastreamer-message- au_update_ms_snd_card 2024-01-10 17:31:18:997 mediastreamer-message- speex_lib_ctl init with neon ? 1 2024-01-10 17:31:19:002 mediastreamer-message- Configuring resampler input with rate=[8000], nchannels=[1] 2024-01-10 17:31:19:002 mediastreamer-message- MSFilePlayer[0x280384e60]: /private/var/containers/Bundle/Application/B273E258-C92F-4435-B94B-00963B1A90BD/XCally.app/Frameworks/linphone.framework/ringback.wav opened: rate=8000,channel=1, length=1535 ms 2024-01-10 17:31:19:003 mediastreamer-message- Audio session already correctly configured. 2024-01-10 17:31:19:003 mediastreamer-message- MSAURead/MSAUWrite: AVAudioSession is configured at sample rate 48000. 2024-01-10 17:31:19:004 mediastreamer-message- Audio session already correctly configured. 2024-01-10 17:31:19:004 mediastreamer-message- MSAURead/MSAUWrite: AVAudioSession is configured at sample rate 48000. 2024-01-10 17:31:19:004 mediastreamer-message- configuring resampler output to rate=[48000], nchannels=[1] 2024-01-10 17:31:19:004 mediastreamer-message- ms_filter_link: MSFilePlayer:0x280384e60,0-->MSDtmfGen:0x2803854a0,0 2024-01-10 17:31:19:004 mediastreamer-message- ms_filter_link: MSDtmfGen:0x2803854a0,0-->MSResample:0x2803857c0,0 2024-01-10 17:31:19:004 mediastreamer-message- ms_filter_link: MSResample:0x2803857c0,0-->MSAUWrite:0x280385c20,0 2024-01-10 17:31:19:004 mediastreamer-message- Initializing speex resampler in mode [voip] from 1 channels 2024-01-10 17:31:19:004 mediastreamer-message- Priority used: 47 2024-01-10 17:31:19:004 mediastreamer-message- Ring MSTicker priority set to SCHED_RR and value (47) 2024-01-10 17:31:19:005 mediastreamer-message- Audio session already correctly configured. 2024-01-10 17:31:19:005 mediastreamer-message- MSAURead/MSAUWrite: AVAudioSession is configured at sample rate 48000. 2024-01-10 17:31:19:011 mediastreamer-message- AudioUnit created with type kAudioUnitSubType_VoiceProcessingIO. 2024-01-10 17:31:19:011 mediastreamer-message- configure_audio_unit() now called. 2024-01-10 17:31:19:011 mediastreamer-message- apply_sound_card_to_audio_session() 2024-01-10 17:31:19:013 mediastreamer-message- apply_sound_card_to_audio_session(): change AVAudioSession preferred input to iPhone Microfono. 2024-01-10 17:31:19:016 mediastreamer-message- configure_audio_unit() took 6 ms. 2024-01-10 17:31:19:016 mediastreamer-message- start_audio_unit(): about to start audio unit. 2024-01-10 17:31:19:079 mediastreamer-message- I/O unit latency [0.000000], quality [64] 2024-01-10 17:31:19:177 mediastreamer-message- AudioUnit started, current hw output latency [0.001500] input [0.001375] iobuf[0.021333] hw sample rate [48000.000000] 2024-01-10 17:31:19:177 mediastreamer-message- start_audio_unit() took 160 ms. 2024-01-10 17:31:19:177 mediastreamer-message- check_audio_unit_is_up(): audio unit is started. 2024-01-10 17:31:19:177 liblinphone-message- CallSession [0x2819fd7b8] moving from state LinphoneCallOutgoingProgress to LinphoneCallOutgoingRinging 2024-01-10 17:31:19:177 liblinphone-message- Unable to find audio video conference with conference ID ConferenceId(peer=sip:1033@sip.test.com, local=sip:1033@sip.test.com) in RAM. 2024-01-10 17:31:19:177 liblinphone-message- Linphone core [0x14600e800] notified [call_state_changed] 2024-01-10 17:31:19:177 belle-sip-message- channel [0x149000000]: ending recv background task with id=[36]. 2024-01-10 17:31:19:178 mediastreamer-message- [IOS Audio Route Change] msiounit audio route change callback 2024-01-10 17:31:19:178 mediastreamer-message- [IOS Audio Route Change] Previous audio route: input=iPhone Microfono, output=Ricevitore, New audio route: input=iPhone Microfono, output=Ricevitore 2024-01-10 17:31:19:178 mediastreamer-message- [IOS Audio Route Change] Audio unit not initialized, ignore route change
2024-01-10 17:31:21:733 bctbx-message- Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2024-01-10 17:31:21:734 belle-sip-message- channel [0x149000000]: starting recv background task with id=[37]. 2024-01-10 17:31:21:734 belle-sip-message- channel [0x149000000]: received [438] new bytes from [UDP://sip.test.com:5060]: SIP/2.0 488 Not Acceptable Here
Via: SIP/2.0/UDP 192.187.1.1:52032;branch=z9hG4bK.ljBiJoF8J;received=151.4.4.2;rport=52032
From: sip:com.test.app@sip.test.com;tag=A5e4lUJxN
To: sip:1033@sip.test.com;tag=as0ff46b3e
Call-ID: t6WBeRskTV
CSeq: 21 INVITE
Server: Asterisk PBX 18.16.0
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0
2024-01-10 17:31:21:740 belle-sip-message- channel [0x149000000] [438] bytes parsed 2024-01-10 17:31:21:740 belle-sip-message- Found transaction matching response. 2024-01-10 17:31:21:740 belle-sip-message- Changing [client] [INVITE] transaction [0x2809b6150], from state [PROCEEDING] to [COMPLETED] 2024-01-10 17:31:21:741 belle-sip-message- channel [0x149000000]: message sent to [UDP://sip.test.com:5060], size: [583] bytes ACK sip:1033@sip.test.com SIP/2.0
Via: SIP/2.0/UDP 192.187.1.1:52032;branch=z9hG4bK.ljBiJoF8J;rport
Call-ID: t6WBeRskTV
From: sip:mk2@sip.test.com;tag=A5e4lUJxN
To: sip:1033@sip.test.com;tag=as0ff46b3e
Max-Forwards: 70
CSeq: 21 ACK
2024-01-10 17:31:21:741 belle-sip-message- Dialog [0x280e96900]: now updated by transaction [0x2809b6150].
2024-01-10 17:31:21:741 belle-sip-message- Dialog [0x280e96900] deleted (is_expired=0)
2024-01-10 17:31:21:741 liblinphone-message- op [0x1448c3800] : setOrUpdateDialog() current=[0x280e96900] new=[0x280e96900]
2024-01-10 17:31:21:741 liblinphone-message- Op [0x1448c3800] receiving call response [488], dialog is [0x280e96900] in state [BELLE_SIP_DIALOG_TERMINATED]
2024-01-10 17:31:21:741 liblinphone-message- [ToneManager] stopRingbackTone
2024-01-10 17:31:21:741 liblinphone-message- [ToneManager] destroyRingStream
2024-01-10 17:31:21:741 mediastreamer-message- ms_filter_unlink: MSFilePlayer:0x280384e60,0-->MSDtmfGen:0x2803854a0,0
2024-01-10 17:31:21:741 mediastreamer-message- ms_filter_unlink: MSDtmfGen:0x2803854a0,0-->MSResample:0x2803857c0,0
2024-01-10 17:31:21:741 mediastreamer-message- ms_filter_unlink: MSResample:0x2803857c0,0-->MSAUWrite:0x280385c20,0
2024-01-10 17:31:21:746 mediastreamer-message- Ring MSTicker thread exiting
2024-01-10 17:31:21:746 mediastreamer-message- msasync.c: worker thread is exiting.
2024-01-10 17:31:21:746 liblinphone-message- [ToneManager] notifyToneIndication reason Not acceptable here
2024-01-10 17:31:21:746 liblinphone-message- [ToneManager] startNamedTone
2024-01-10 17:31:21:746 liblinphone-warning- [ToneManager] Unhandled tone id.
2024-01-10 17:31:21:746 liblinphone-message- [ToneManager] playTone playing DTMF tone
2024-01-10 17:31:21:746 mediastreamer-message- ms_au_write_new, sound card : 0x281694840 (iPhone Microfono)
2024-01-10 17:31:21:746 mediastreamer-message- au_update_ms_snd_card
2024-01-10 17:31:21:746 mediastreamer-message- speex_lib_ctl init with neon ? 1
2024-01-10 17:31:21:746 mediastreamer-message- configure_audio_session(): AudioUnit is already started, skipping this process.
2024-01-10 17:31:21:746 mediastreamer-message- configure_audio_session(): AudioUnit is already started, skipping this process.
2024-01-10 17:31:21:746 mediastreamer-message- configuring resampler output to rate=[48000], nchannels=[1]
2024-01-10 17:31:21:746 mediastreamer-message- ms_filter_link: MSFilePlayer:0x28038c820,0-->MSDtmfGen:0x28038d040,0
2024-01-10 17:31:21:746 mediastreamer-message- ms_filter_link: MSDtmfGen:0x28038d040,0-->MSResample:0x28038c6e0,0
2024-01-10 17:31:21:746 mediastreamer-message- ms_filter_link: MSResample:0x28038c6e0,0-->MSAUWrite:0x28038cc80,0
2024-01-10 17:31:21:746 mediastreamer-message- Initializing speex resampler in mode [voip] from 1 channels
2024-01-10 17:31:21:747 mediastreamer-message- Priority used: 47
2024-01-10 17:31:21:747 mediastreamer-message- Ring MSTicker priority set to SCHED_RR and value (47)
2024-01-10 17:31:21:747 mediastreamer-message- configure_audio_session(): AudioUnit is already started, skipping this process.
2024-01-10 17:31:21:747 mediastreamer-message- check_audio_unit_is_up(): audio unit is started.
2024-01-10 17:31:21:747 liblinphone-message- CallSession [0x2819fd7b8] moving from state LinphoneCallOutgoingRinging to LinphoneCallError
2024-01-10 17:31:21:747 liblinphone-message- StreamsGroup::finish() called.
2024-01-10 17:31:21:747 mediastreamer-message- =================================================================================
2024-01-10 17:31:21:747 mediastreamer-message- FILTER USAGE STATISTICS
2024-01-10 17:31:21:747 mediastreamer-message- Name Count Time/tick (ms) CPU Usage
2024-01-10 17:31:21:747 mediastreamer-message- min mean max sd
2024-01-10 17:31:21:747 mediastreamer-message- ---------------------------------------------------------------------------------
2024-01-10 17:31:21:747 mediastreamer-message- MSResample 256 0.09 0.59 0.89 0.19 98.0
2024-01-10 17:31:21:747 mediastreamer-message- MSFilePlayer 256 0.00 0.01 0.07 0.01 1.5
2024-01-10 17:31:21:747 mediastreamer-message- MSAUWrite 256 0.00 0.00 0.01 0.00 0.3
2024-01-10 17:31:21:747 mediastreamer-message- MSDtmfGen 256 0.00 0.00 0.01 0.00 0.2
2024-01-10 17:31:21:747 mediastreamer-message- MSAudioFlowControl 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:747 mediastreamer-message- MSGenericPLC 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:747 mediastreamer-message- MSEqualizer 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:747 mediastreamer-message- MSAudioMixer 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:747 mediastreamer-message- MSVolume 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:747 mediastreamer-message- MSUlawDec 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:747 mediastreamer-message- MSUlawEnc 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:747 mediastreamer-message- MSAURead 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:747 mediastreamer-message- MSRtpRecv 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:747 mediastreamer-message- MSSpeexEC 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:747 mediastreamer-message- MSRtpSend 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:747 mediastreamer-message- =================================================================================
2024-01-10 17:31:21:747 mediastreamer-message- =================================================================================
2024-01-10 17:31:21:747 mediastreamer-message- FILTER USAGE STATISTICS
2024-01-10 17:31:21:747 mediastreamer-message- Name Count Time/tick (ms) CPU Usage
2024-01-10 17:31:21:747 mediastreamer-message- min mean max sd
2024-01-10 17:31:21:747 mediastreamer-message- ---------------------------------------------------------------------------------
2024-01-10 17:31:21:747 mediastreamer-message- MSFilePlayer 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:747 mediastreamer-message- MSAudioFlowControl 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:747 mediastreamer-message- MSGenericPLC 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:747 mediastreamer-message- MSEqualizer 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:748 mediastreamer-message- MSAudioMixer 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:748 mediastreamer-message- MSVolume 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:748 mediastreamer-message- MSResample 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:748 mediastreamer-message- MSUlawDec 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:748 mediastreamer-message- MSUlawEnc 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:748 mediastreamer-message- MSAUWrite 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:748 mediastreamer-message- MSAURead 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:748 mediastreamer-message- MSDtmfGen 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:748 mediastreamer-message- MSRtpRecv 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:748 mediastreamer-message- MSSpeexEC 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:748 mediastreamer-message- MSRtpSend 0 0.00 0.00 0.00 0.00 0.0
2024-01-10 17:31:21:748 mediastreamer-message- =================================================================================
2024-01-10 17:31:21:748 mediastreamer-message- Stopping ZRTP context on session [0x0]
2024-01-10 17:31:21:748 mediastreamer-message- ZRTP context destroyed
2024-01-10 17:31:21:749 liblinphone-message- MainDb::addEvent() of type ConferenceCallEnded (value 4)
2024-01-10 17:31:21:750 liblinphone-message- Update conference call in database: t6WBeRskTV
2024-01-10 17:31:21:757 liblinphone-message- Linphone core [0x14600e800] notified [call_log_updated]
2024-01-10 17:31:21:757 liblinphone-message- Resetting the current call
2024-01-10 17:31:21:757 liblinphone-message- Notifying sound card that is no longer needed.
2024-01-10 17:31:21:757 liblinphone-message- Removing the call (local address sip:mk2@sip.test.com remote address sip:1033@sip.test.com) from the list attached to the core
2024-01-10 17:31:21:758 liblinphone-message- Linphone core [0x14600e800] notified [last_call_ended]
2024-01-10 17:31:21:758 liblinphone-message- Unable to find audio video conference with conference ID ConferenceId(peer=sip:1033@sip.test.com, local=sip:1033@sip.test.com) in RAM.
2024-01-10 17:31:21:759 liblinphone-message- Linphone core [0x14600e800] notified [call_state_changed]
2024-01-10 17:31:21:759 belle-sip-message- channel [0x149000000]: ending recv background task with id=[37].callUUID=3EA352D4-7E3E-44BD-9359-5DE292BC95C9 dateEnded=(null)>)
2024-01-10 17:31:21:809 liblinphone-message- Dialog [0x280e96900] terminated for op [0x1448c3800]
2024-01-10 17:31:21:812 liblinphone-message- CallSession [0x2819fd7b8] moving from state LinphoneCallError to LinphoneCallReleased
2024-01-10 17:31:21:812 liblinphone-message- Unable to find audio video conference with conference ID ConferenceId(peer=sip:1033@sip.test.com, local=sip:1033@sip.test.com) in RAM.
2024-01-10 17:31:21:812 liblinphone-message- Linphone core [0x14600e800] notified [call_state_changed]
2024-01-10 17:31:21:812 liblinphone-message- op [0x1448c3800] : setOrUpdateDialog() current=[0x280e96900] new=[0x0]
2024-01-10 17:31:22:492 mediastreamer-message- [IOS Audio Route Change] msiounit audio route change callback
2024-01-10 17:31:22:493 mediastreamer-message- [IOS Audio Route Change] Previous audio route: input=iPhone Microfono, output=Ricevitore, New audio route: input=No input, output=Altoparlante
2024-01-10 17:31:22:493 mediastreamer-message- [IOS Audio Route Change] Audio unit not initialized, ignore route change
2024-01-10 17:31:22:499 mediastreamer-message- [IOS Audio Route Change] msiounit audio route change callback
2024-01-10 17:31:22:500 mediastreamer-message- [IOS Audio Route Change] Previous audio route: input=iPhone Microfono, output=Ricevitore, New audio route: input=No input, output=Altoparlante
2024-01-10 17:31:22:500 mediastreamer-message- [IOS Audio Route Change] Audio unit not initialized, ignore route change
2024-01-10 17:31:22:515 mediastreamer-message- AVAudioSession activated: 0
2024-01-10 17:31:22:516 mediastreamer-message- AudioUnit stopped
2024-01-10 17:31:22:516 mediastreamer-message- AudioSession is deactivated while AudioUnit was created. Mark AudioUnit as zombified.
2024-01-10 17:31:22:753 liblinphone-message- RingStream no longer needed.
2024-01-10 17:31:22:753 liblinphone-message- [ToneManager] destroyRingStream
2024-01-10 17:31:22:753 mediastreamer-message- ms_filter_unlink: MSFilePlayer:0x28038c820,0-->MSDtmfGen:0x28038d040,0
2024-01-10 17:31:22:753 mediastreamer-message- ms_filter_unlink: MSDtmfGen:0x28038d040,0-->MSResample:0x28038c6e0,0
2024-01-10 17:31:22:753 mediastreamer-message- ms_filter_unlink: MSResample:0x28038c6e0,0-->MSAUWrite:0x28038cc80,0
2024-01-10 17:31:22:758 mediastreamer-message- Ring MSTicker thread exiting
2024-01-10 17:31:22:758 mediastreamer-message- au_destroy_audio_unit set holder card to NULL
2024-01-10 17:31:22:758 mediastreamer-message- AudioUnit destroyed
2024-01-10 17:31:29:333 bctbx-message- Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2024-01-10 17:31:29:333 belle-sip-message- channel [0x149000000]: starting recv background task with id=[38]. 2024-01-10 17:31:29:333 belle-sip-message- channel [0x149000000]: received [1091] new bytes from [UDP://sip.test.com:5060]: OPTIONS sip:com.test.app@151.4.4.2:52032;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip&B1AF1587770AACG7693K65HGDF6783CF89CC87E6649C43D15FF8:remote;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip&remote;pn-silent=1;pn-timeout=0;transport=udp SIP/2.0
Via: SIP/2.0/UDP 15.160.122.98:5060;branch=z9hG4bK01994d70;rport
Max-Forwards: 70
From: "asterisk" sip:asterisk@15.160.122.98;tag=as627c929c
Contact: sip:asterisk@15.160.122.98:5060
Call-ID: 562b782b7ffb85fb31d249594f1bbfd5@15.160.122.98:5060
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX 18.16.0
Date: Wed, 10 Jan 2024 16:31:29 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0
2024-01-10 17:31:29:428 belle-sip-message- channel [0x149000000] [1091] bytes parsed 2024-01-10 17:31:29:429 belle-sip-message- channel [0x149000000]: message sent to [UDP://sip.test.com:5060], size: [536] bytes SIP/2.0 200 Ok
Via: SIP/2.0/UDP 15.160.122.98:5060;branch=z9hG4bK01994d70;rport
From: "asterisk" sip:asterisk@15.160.122.98;tag=as627c929c
Call-ID: 562b782b7ffb85fb31d249594f1bbfd5@15.160.122.98:5060
CSeq: 102 OPTIONS
Viish
commented
5 months ago Hi @Alessandro-v,
Please attach logs from the other device, this one receives the 488 Not Acceptable Here but we don't know why.
Cheers,
Alessandro-v
commented
5 months ago Here are the logs from the other device. Cheers
2024-01-11 11:45:33:449 bctbx-message- Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2024-01-11 11:45:33:449 belle-sip-message- channel [0x10a854000]: starting recv background task with id=[16]. 2024-01-11 11:45:33:449 belle-sip-message- channel [0x10a854000]: received [1252] new bytes from [UDP://sip.test.com:5060]: INVITE sip:mk@151.1.84.567:32689;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip;pn-silent=1;pn-timeout=0;transport=udp SIP/2.0
Via: SIP/2.0/UDP 151.4.4.2:52032;branch=z9hG4bK09512d4e;rport
Max-Forwards: 70
From: "mk2" sip:1036@15.160.122.98;tag=as215b6007
Contact: sip:1036@151.4.4.2:52032
Call-ID: 07224b1a1a0e63082b9d95323790fdae@151.4.4.2:52032
CSeq: 102 INVITE
User-Agent: Asterisk PBX 18.16.0
Date: Thu, 11 Jan 2024 10:45:33 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 301
v=0
o=root 1836333937 1836333937 IN IP4 15.160.122.98
s=Asterisk PBX 18.16.0
c=IN IP4 15.160.122.98
t=0 0
m=audio 13106 RTP/AVP 0 8 3 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=maxptime:150
a=rtcp-mux
a=sendrecv
2024-01-11 11:45:33:538 belle-sip-message- channel [0x10a854000] [951] bytes parsed 2024-01-11 11:45:33:538 belle-sip-message- channel [0x10a854000] read [301] bytes of body from [sip.test.com:5060] 2024-01-11 11:45:33:540 bctbx-message- Background task belle-sip transaction(0x297c14000) started. Unknown remaining time since application is not fully in background. 2024-01-11 11:45:33:540 belle-sip-message- transaction [0x297c14000]: starting transaction background task with id=[17]. 2024-01-11 11:45:33:540 belle-sip-message- Changing [server] [INVITE] transaction [0x297c14000], from state [INIT] to [PROCEEDING] 2024-01-11 11:45:33:540 belle-sip-message- channel [0x10a854000]: message sent to [UDP://sip.test.com:5060], size: [442] bytes SIP/2.0 100 Trying
Via: SIP/2.0/UDP 151.4.4.2:52032;branch=z9hG4bK09512d4e;rport
From: "mk2" sip:1036@15.160.122.98;tag=as215b6007
Call-ID: 07224b1a1a0e63082b9d95323790fdae@151.4.4.2:52032
CSeq: 102 INVITE
2024-01-11 11:45:33:540 belle-sip-message- New server dialog [0x283afcb00] , local tag [AKUGoC7], remote tag [as215b6007]
2024-01-11 11:45:33:540 liblinphone-message- op [0x103018800] : setOrUpdateDialog() current=[0x0] new=[0x283afcb00]
2024-01-11 11:45:33:540 liblinphone-message- New incoming call from ["mk2" sip:1036@15.160.122.98] to [sip:mk@151.1.84.567:32689;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip;pn-silent=1;pn-timeout=0;transport=udp]
2024-01-11 11:45:33:565 liblinphone-message- Found payload PCMU/8000 fmtp=
2024-01-11 11:45:33:565 liblinphone-message- Found payload PCMA/8000 fmtp=
2024-01-11 11:45:33:565 liblinphone-message- Found payload GSM/8000 fmtp=
2024-01-11 11:45:33:565 liblinphone-message- Found payload telephone-event/8000 fmtp=0-16
2024-01-11 11:45:33:565 liblinphone-message- Start measurement of [Get call log.].
2024-01-11 11:45:33:566 liblinphone-message- Duration of [Get call log.]: 0ms.
2024-01-11 11:45:33:567 liblinphone-message- New MediaSession [0x282dad898] initialized (liblinphone version: 5.3.7)
2024-01-11 11:45:33:568 liblinphone-message- MainDb::addEvent() of type ConferenceCallStarted (value 3)
2024-01-11 11:45:33:568 liblinphone-message- Insert new conference call in database: 07224b1a1a0e63082b9d95323790fdae@151.4.4.2:52032
2024-01-11 11:45:33:569 liblinphone-message- Updating sip address display name in database: sip:1036@15.160.122.98.
2024-01-11 11:45:33:571 liblinphone-message- Linphone core [0x103030800] notified [call_log_updated]
2024-01-11 11:45:33:571 liblinphone-message- Rtp bundle is disabled.
2024-01-11 11:45:33:571 liblinphone-message- Local IP address guessed from SDP is: ::ffff:192.187.1.1
2024-01-11 11:45:33:571 liblinphone-message- Guessed media local ip address is ::ffff:192.187.1.1
2024-01-11 11:45:33:571 liblinphone-warning- [LIME] No account available, unable to setup identity key for ZRTP auxiliary shared secret
2024-01-11 11:45:33:572 liblinphone-message- stream#0 [audio] in state [Stopped]: multicast role is [inactive]
2024-01-11 11:45:33:572 ortp-message- RtpSession bound to [::0] ports [50951] [54658]
2024-01-11 11:45:33:573 liblinphone-message- Configured srtp crypto suite: AEAD_AES_128_GCM
2024-01-11 11:45:33:573 liblinphone-message- Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80
2024-01-11 11:45:33:573 liblinphone-message- Configured srtp crypto suite: AEAD_AES_256_GCM
2024-01-11 11:45:33:573 liblinphone-message- Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80
2024-01-11 11:45:33:573 mediastreamer-message- Creating ZRTP engine on rtp session [0x1030f3800] ssrc 0x66f31e3c
2024-01-11 11:45:33:574 ortp-message- rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION
2024-01-11 11:45:33:575 mediastreamer-message- Setting DSCP to 46 for MSAudio stream.
2024-01-11 11:45:33:575 liblinphone-message- Created stream of type audio at index 0: stream#0 [audio] in state [Stopped]
2024-01-11 11:45:33:575 liblinphone-message- [MS2AudioStream] setting type of soundcard 0x2822f8480 to voice
2024-01-11 11:45:33:575 liblinphone-message- Call's input audio device is iPhone Microfono
2024-01-11 11:45:33:575 liblinphone-message- [MS2AudioStream] setting type of soundcard 0x2822f8480 to voice
2024-01-11 11:45:33:575 liblinphone-message- Doing SDP offer/answer process of type incoming
2024-01-11 11:45:33:575 liblinphone-message- No match for GSM/8000/1
2024-01-11 11:45:33:575 liblinphone-message- Found matching configurations: local configuration index 0 remote offered configuration index 0
2024-01-11 11:45:33:575 liblinphone-error- Unable to retrieve contact address from op or account for call session 0x282dad898 (local address Address(sip:mk@151.1.84.567:32689;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip;pn-silent=1;pn-timeout=0;transport=udp) remote address "mk2" sip:1036@15.160.122.98).
2024-01-11 11:45:33:575 liblinphone-message- [ToneManager] stopDtmfStream
2024-01-11 11:45:33:576 liblinphone-message- Linphone core [0x103030800] notified [call_created]
2024-01-11 11:45:33:576 bctbx-message- Background task Liblinphone call notification started. Unknown remaining time since application is not fully in background.
2024-01-11 11:45:33:576 liblinphone-message- Starting background task [24] with name: [Liblinphone call notification] and expiration of [30]
2024-01-11 11:45:33:576 liblinphone-message- Notifying sound card that it is going to be used.
2024-01-11 11:45:33:576 liblinphone-message- [ToneManager] destroyRingStream
2024-01-11 11:45:33:576 liblinphone-message- Callkit mode is enabled, will not play ring tone from liblinphone.
2024-01-11 11:45:33:576 liblinphone-message- CallSession [0x282dad898] moving from state LinphoneCallIdle to LinphoneCallIncomingReceived
2024-01-11 11:45:33:577 liblinphone-message- Unable to find audio video conference with conference ID ConferenceId(peer=sip:mk@151.1.84.567:32689;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip;pn-silent=1;pn-timeout=0;transport=udp, local=sip:mk@151.1.84.567:32689;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip;pn-silent=1;pn-timeout=0;transport=udp) in RAM.
2024-01-11 11:45:33:577 bctbx-message- Background task Liblinphone cpu lock started. Unknown remaining time since application is not fully in background.
2024-01-11 11:45:33:577 liblinphone-message- Linphone core [0x103030800] notified [first_call_started]
2024-01-11 11:45:33:581 liblinphone-message- Linphone core [0x103030800] notified [call_state_changed]
2024-01-11 11:45:33:581 liblinphone-message- Ending background task [24] with name: [Liblinphone call notification]
2024-01-11 11:45:33:581 liblinphone-message- Contact has not been fixed, stack will do
2024-01-11 11:45:33:581 liblinphone-warning- Unable to set contact address for session 0x102f87240 to as it is not valid
2024-01-11 11:45:33:582 belle-sip-message- channel [0x10a854000]: message sent to [UDP://sip.test.com:5060], size: [519] bytes
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 151.4.4.2:52032;branch=z9hG4bK09512d4e;rport
From: "mk2" sip:1036@15.160.122.98;tag=as215b6007
Call-ID: 07224b1a1a0e63082b9d95323790fdae@151.4.4.2:52032
CSeq: 102 INVITE
User-Agent: Unknown
Supported: replaces, outbound, gruu, path
2024-01-11 11:45:33:582 belle-sip-message- Dialog [0x283afcb00]: now updated by transaction [0x297c14000]. 2024-01-11 11:45:33:582 belle-sip-message- channel [0x10a854000]: ending recv background task with id=[16]. 2024-01-11 11:45:33:704 liblinphone-message- Incoming call ringing for 0 seconds 2024-01-11 11:45:33:774 mediastreamer-message- [IOS Audio Route Change] msiounit audio route change callback 2024-01-11 11:45:33:775 mediastreamer-message- [IOS Audio Route Change] Previous audio route: input=No input, output=Altoparlante, New audio route: input=No input, output=No output 2024-01-11 11:45:33:775 mediastreamer-message- [IOS Audio Route Change] Audio unit not initialized, ignore route change 2024-01-11 11:45:34:703 liblinphone-message- Incoming call ringing for 1 seconds 2024-01-11 11:45:35:704 liblinphone-message- Incoming call ringing for 2 seconds 2024-01-11 11:45:35:763 mediastreamer-message- au_update_ms_snd_card 2024-01-11 11:45:35:765 mediastreamer-message- Not activating the AVAudioSession because it is CallKit's job. 2024-01-11 11:45:35:765 mediastreamer-message- MSAURead/MSAUWrite: configureAudioSession() took 2 ms. 2024-01-11 11:45:35:766 liblinphone-warning- [LIME] No account available, unable to setup identity key for ZRTP auxiliary shared secret 2024-01-11 11:45:35:766 liblinphone-warning- [LIME] No account available, unable to setup identity key for ZRTP auxiliary shared secret 2024-01-11 11:45:35:767 liblinphone-message- Configuring prefered card sampling rate to [48000] 2024-01-11 11:45:35:767 liblinphone-message- [LIME] Missing identity keys for mutual authentication, do not set auxiliary secret from identity keys 2024-01-11 11:45:35:767 liblinphone-message- Contact has not been fixed, stack will do 2024-01-11 11:45:35:767 liblinphone-warning- Unable to set contact address for session 0x102f87240 to as it is not valid 2024-01-11 11:45:35:767 liblinphone-message- Accepting server transaction [0x297c14000] on op [0x103018800] 2024-01-11 11:45:35:767 liblinphone-message- Doing SDP offer/answer process of type incoming 2024-01-11 11:45:35:767 liblinphone-message- No match for GSM/8000/1 2024-01-11 11:45:35:767 liblinphone-message- Found matching configurations: local configuration index 0 remote offered configuration index 0 2024-01-11 11:45:35:767 belle-sip-message- channel [0x10a854000]: message sent to [UDP://sip.test.com:5060], size: [915] bytes SIP/2.0 200 Ok
Via: SIP/2.0/UDP 151.4.4.2:52032;branch=z9hG4bK09512d4e;rport
From: "mk2" sip:1036@15.160.122.98;tag=as215b6007
Call-ID: 07224b1a1a0e63082b9d95323790fdae@151.4.4.2:52032
CSeq: 102 INVITE
User-Agent: Unknown
Supported: replaces, outbound, gruu, path
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, PRACK, UPDATE
Contact: <sip:151.1.84.567:32689;transport=udp>;+org.linphone.specs="lime"
Content-Type: application/sdp
Content-Length: 175
v=0
o=mk 1480 3974 IN IP6 ::ffff:192.187.1.1
s=Talk
c=IN IP6 ::ffff:192.187.1.1
t=0 0
m=audio 50951 RTP/AVP 0 8 101
a=rtpmap:101 telephone-event/8000
a=rtcp:54658
2024-01-11 11:45:35:767 belle-sip-message- Changing [server] [INVITE] transaction [0x297c14000], from state [PROCEEDING] to [ACCEPTED] 2024-01-11 11:45:35:767 belle-sip-message- Dialog [0x283afcb00]: now updated by transaction [0x297c14000]. 2024-01-11 11:45:35:767 liblinphone-message- CallSession [0x282dad898] moving from state LinphoneCallIncomingReceived to LinphoneCallConnected 2024-01-11 11:45:35:768 liblinphone-message- MainDb::addEvent() of type ConferenceCallConnected (value 21) 2024-01-11 11:45:35:768 liblinphone-message- Update conference call in database: 07224b1a1a0e63082b9d95323790fdae@151.4.4.2:52032 2024-01-11 11:45:35:776 liblinphone-message- Linphone core [0x103030800] notified [call_log_updated] 2024-01-11 11:45:35:777 liblinphone-message- Unable to find audio video conference with conference ID ConferenceId(peer=sip:mk@151.1.84.567:32689;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip;pn-silent=1;pn-timeout=0;transport=udp, local=sip:mk@151.1.84.567:32689;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip;pn-silent=1;pn-timeout=0;transport=udp) in RAM. 2024-01-11 11:45:35:778 liblinphone-message- Linphone core [0x103030800] notified [call_state_changed] 2024-01-11 11:45:35:778 liblinphone-message- Negotiated media encryption is LinphoneMediaEncryptionNone 2024-01-11 11:45:35:778 liblinphone-message- [ToneManager] destroyRingStream 2024-01-11 11:45:35:778 liblinphone-message- StreamsGroup 0x2839e5a40 rendering stream#0 [audio] in state [Stopped] 2024-01-11 11:45:35:778 liblinphone-message- stream#0 [audio] in state [Stopped] is not part of any bundle 2024-01-11 11:45:35:778 liblinphone-message- Audio bandwidth for StreamsGroup [0x2839e5a40] is 80 2024-01-11 11:45:35:778 liblinphone-message- Call state LinphoneCallStreamsRunning, using voice stream 2024-01-11 11:45:35:778 liblinphone-message- Equalizer location: hp 2024-01-11 11:45:35:779 mediastreamer-message- cannot set noise gate mode to [0] because no volume send 2024-01-11 11:45:35:779 liblinphone-warning- Advanced adaptive rate control requested but avpf-tmmbr is not activated in this stream. Reverting to basic rate control instead 2024-01-11 11:45:35:779 liblinphone-message- stream#0 [audio] in state [Stopped] is not part of any bundle 2024-01-11 11:45:35:780 ortp-warning- Fail to set IPv4 packet info on RTP socket: Invalid argument. 2024-01-11 11:45:35:780 ortp-warning- Fail to set IPv4 packet info on RTCP socket: Invalid argument. 2024-01-11 11:45:35:780 liblinphone-message- ICE state is IceStateNotActivated for stream#0 [audio] in state [Stopped] 2024-01-11 11:45:35:781 ortp-message- RtpSession [0x1030f3800] sending to rtp [::ffff:15.160.122.98]:13106 rtcp [::ffff:15.160.122.98]:13106 2024-01-11 11:45:35:781 mediastreamer-message- Stun packet of length 20 sent on rtp for session [0x1030f3800] 2024-01-11 11:45:35:782 mediastreamer-message- Stun packet of length 20 sent on rtcp for session [0x1030f3800] 2024-01-11 11:45:35:782 mediastreamer-message- ms_au_read_new, sound card : 0x2822f8480 (iPhone Microfono) 2024-01-11 11:45:35:782 mediastreamer-message- au_update_ms_snd_card 2024-01-11 11:45:35:782 mediastreamer-message- ms_au_write_new, sound card : 0x2822f8480 (iPhone Microfono) 2024-01-11 11:45:35:782 mediastreamer-message- au_update_ms_snd_card 2024-01-11 11:45:35:782 mediastreamer-message- audio_stream_start_from_io: create encoder, decoder and resamplers. 2024-01-11 11:45:35:782 mediastreamer-message- speex_lib_ctl init with neon ? 1 2024-01-11 11:45:35:782 mediastreamer-message- speex_lib_ctl init with neon ? 1 2024-01-11 11:45:35:782 mediastreamer-message- Software echo cancellation disabled: use_ec=0, has_builtin_ec=1 2024-01-11 11:45:35:782 mediastreamer-warning- Destroying software echo canceller filter 2024-01-11 11:45:35:784 mediastreamer-message- Audio session already correctly configured. 2024-01-11 11:45:35:784 mediastreamer-message- target bitrate not set for stream [0x102f88780] using payload's bitrate is 80000 2024-01-11 11:45:35:784 mediastreamer-message- Setting audio encoder network bitrate to [80000] on stream [0x102f88780] 2024-01-11 11:45:35:784 ortp-message- RtpSession: target upload bandwidth set to 80000 2024-01-11 11:45:35:784 mediastreamer-message- MSUlawEnc: got maxptime=150 2024-01-11 11:45:35:784 mediastreamer-message- No information available for [//], 2024-01-11 11:45:35:784 mediastreamer-message- Sound device information for [//] is: builtin=[no], delay=[0] ms 2024-01-11 11:45:35:784 mediastreamer-message- MSVolume[0x2837e85a0]: set gain to [0.000000 db], [1.000000] linear 2024-01-11 11:45:35:784 mediastreamer-message- MSVolume[0x2837ec460]: set gain to [0.000000 db], [1.000000] linear 2024-01-11 11:45:35:785 mediastreamer-message- Audio session already correctly configured. 2024-01-11 11:45:35:785 mediastreamer-message- configuring MSAURead:0x2837f5c20-->MSUlawEnc:0x2837f5ea0 from rate [48000] to rate [8000] and from channel [1] to channel [1] 2024-01-11 11:45:35:786 mediastreamer-message- Audio session already correctly configured. 2024-01-11 11:45:35:786 mediastreamer-message- configuring MSUlawDec:0x2837f5f40-->MSAUWrite:0x2837f5d60 from rate [8000] to rate [48000] and from channel [1] to channel [1] 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSAURead:0x2837f5c20,0-->MSResample:0x2837f5fe0,0 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSResample:0x2837f5fe0,0-->MSEqualizer:0x2837f6120,0 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSEqualizer:0x2837f6120,0-->MSVolume:0x2837e85a0,0 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSVolume:0x2837e85a0,0-->MSAudioMixer:0x2837f6080,0 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSAudioMixer:0x2837f6080,0-->MSUlawEnc:0x2837f5ea0,0 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSUlawEnc:0x2837f5ea0,0-->MSRtpSend:0x2837e2800,0 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSRtpRecv:0x2837f5540,0-->MSUlawDec:0x2837f5f40,0 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSUlawDec:0x2837f5f40,0-->MSGenericPLC:0x2837f8820,0 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSGenericPLC:0x2837f8820,0-->MSAudioFlowControl:0x2837f83c0,0 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSAudioFlowControl:0x2837f83c0,0-->MSDtmfGen:0x2837f5cc0,0 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSDtmfGen:0x2837f5cc0,0-->MSVolume:0x2837ec460,0 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSVolume:0x2837ec460,0-->MSEqualizer:0x2837f61c0,0 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSEqualizer:0x2837f61c0,0-->MSAudioMixer:0x2837f8780,0 2024-01-11 11:45:35:787 mediastreamer-message- speex_lib_ctl init with neon ? 1 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSFilePlayer:0x2837f88c0,0-->MSResample:0x2837f8960,0 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSResample:0x2837f8960,0-->MSAudioMixer:0x2837f8780,1 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSAudioMixer:0x2837f8780,0-->MSResample:0x2837e8820,0 2024-01-11 11:45:35:787 mediastreamer-message- ms_filter_link: MSResample:0x2837e8820,0-->MSAUWrite:0x2837f5d60,0 2024-01-11 11:45:35:787 mediastreamer-message- au_read_preprocess 2024-01-11 11:45:35:787 mediastreamer-message- Priority used: 47 2024-01-11 11:45:35:787 mediastreamer-message- MSAudio MSTicker priority set to SCHED_RR and value (47) 2024-01-11 11:45:35:788 mediastreamer-message- Audio session already correctly configured. 2024-01-11 11:45:35:923 mediastreamer-warning- MSAudio MSTicker: late wakeup by 125 ms 2024-01-11 11:45:36:102 mediastreamer-warning- MSAudio MSTicker: late wakeup by 154 ms 2024-01-11 11:45:36:232 mediastreamer-warning- MSAudio MSTicker: late wakeup by 115 ms 2024-01-11 11:45:36:588 mediastreamer-warning- MSAudio MSTicker: late wakeup by 321 ms 2024-01-11 11:45:36:620 mediastreamer-message- AudioUnit created with type kAudioUnitSubType_VoiceProcessingIO. 2024-01-11 11:45:36:620 mediastreamer-message- ms_ticker_set_time_func: ticker's time method updated. 2024-01-11 11:45:36:620 mediastreamer-message- Initializing speex resampler in mode [voip] from 1 channels 2024-01-11 11:45:36:621 mediastreamer-message- Initializing speex resampler in mode [voip] from 1 channels 2024-01-11 11:45:36:621 mediastreamer-message- Initializing speex resampler in mode [voip] from 1 channels 2024-01-11 11:45:36:623 mediastreamer-message- Audio session already correctly configured. 2024-01-11 11:45:36:623 mediastreamer-message- MSVolume[0x2837e85a0]: set gain to [0.000000 db], [1.000000] linear 2024-01-11 11:45:36:623 mediastreamer-message- MSVolume[0x2837ec460]: set gain to [0.000000 db], [1.000000] linear 2024-01-11 11:45:36:623 liblinphone-message- AudioStream[0x102f88780]: mic is [enabled]. 2024-01-11 11:45:36:623 mediastreamer-message- MSVolume[0x2837e85a0]: set gain to [0.000000 db], [1.000000] linear 2024-01-11 11:45:36:623 mediastreamer-message- MSAudioFlowControl: configured with strategy=[1] and silent_threshold=[0.020000]. 2024-01-11 11:45:36:623 mediastreamer-message- MSVolume[0x2837ec460]: set gain to [0.000000 db], [1.000000] linear 2024-01-11 11:45:36:623 liblinphone-message- [LIME] Missing identity keys for mutual authentication, do not set auxiliary secret from identity keys 2024-01-11 11:45:36:623 liblinphone-message- CallSession[0x282dad898] : payload type 0 PCMU/8000 fmtp= added to frozen list 2024-01-11 11:45:36:623 liblinphone-message- CallSession[0x282dad898] : payload type 8 PCMA/8000 fmtp= added to frozen list 2024-01-11 11:45:36:623 liblinphone-message- CallSession[0x282dad898] : payload type 101 telephone-event/8000 fmtp= added to frozen list 2024-01-11 11:45:36:623 liblinphone-message- CallSession [0x282dad898] moving from state LinphoneCallConnected to LinphoneCallStreamsRunning 2024-01-11 11:45:36:624 liblinphone-message- Unable to find audio video conference with conference ID ConferenceId(peer=sip:mk@151.1.84.567:32689;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip;pn-silent=1;pn-timeout=0;transport=udp, local=sip:mk@151.1.84.567:32689;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip;pn-silent=1;pn-timeout=0;transport=udp) in RAM. 2024-01-11 11:45:36:625 liblinphone-message- Linphone core [0x103030800] notified [call_state_changed] 2024-01-11 11:45:36:625 liblinphone-message- MediaSession (local address sip:mk@151.1.84.567:32689;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip;pn-silent=1;pn-timeout=0;transport=udp remote address "mk2" sip:1036@15.160.122.98) has been accepted 2024-01-11 11:45:36:628 bctbx-message- Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2024-01-11 11:45:36:628 belle-sip-message- channel [0x10a854000]: starting recv background task with id=[1a]. 2024-01-11 11:45:36:628 belle-sip-message- channel [0x10a854000]: received [592] new bytes from [UDP://sip.test.com:5060]: ACK sip:151.1.84.567:32689;transport=udp SIP/2.0
Via: SIP/2.0/UDP 151.4.4.2:52032;branch=z9hG4bK77138237;rport
Max-Forwards: 70
From: "mk2" sip:1036@15.160.122.98;tag=as215b6007
Contact: sip:1036@151.4.4.2:52032
Call-ID: 07224b1a1a0e63082b9d95323790fdae@151.4.4.2:52032
CSeq: 102 ACK
User-Agent: Asterisk PBX 18.16.0
Content-Length: 0
2024-01-11 11:45:36:629 mediastreamer-message- Stun packet of length 20 sent on rtp for session [0x1030f3800] 2024-01-11 11:45:36:629 mediastreamer-message- Stun packet of length 20 sent on rtcp for session [0x1030f3800] 2024-01-11 11:45:36:630 mediastreamer-message- MSAudioMixer [0x2837f6080] is entering bypass mode. 2024-01-11 11:45:36:637 belle-sip-message- channel [0x10a854000] [592] bytes parsed 2024-01-11 11:45:36:637 belle-sip-message- Incoming INVITE has ACK, dialog is happy 2024-01-11 11:45:36:638 belle-sip-message- channel [0x10a854000]: ending recv background task with id=[1a]. 2024-01-11 11:45:36:638 belle-sip-message- channel [0x10a854000]: keep alive sent to [UDP://sip.test.com:5060] 2024-01-11 11:45:36:644 bctbx-message- Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2024-01-11 11:45:36:644 belle-sip-message- channel [0x10a854000]: starting recv background task with id=[1b]. 2024-01-11 11:45:36:644 belle-sip-message- channel [0x10a854000]: received [641] new bytes from [UDP://sip.test.com:5060]: BYE sip:151.1.84.567:32689;transport=udp SIP/2.0
Via: SIP/2.0/UDP 151.4.4.2:52032;branch=z9hG4bK00d4a15d;rport
Max-Forwards: 70
From: "mk2" sip:1036@15.160.122.98;tag=as215b6007
Call-ID: 07224b1a1a0e63082b9d95323790fdae@151.4.4.2:52032
CSeq: 103 BYE
User-Agent: Asterisk PBX 18.16.0
X-Asterisk-HangupCause: Bearer capability not available
X-Asterisk-HangupCauseCode: 58
Content-Length: 0
2024-01-11 11:45:36:647 belle-sip-message- channel [0x10a854000] [641] bytes parsed 2024-01-11 11:45:36:648 bctbx-message- Background task belle-sip transaction(0x2837e0780) started. Unknown remaining time since application is not fully in background. 2024-01-11 11:45:36:648 belle-sip-message- transaction [0x2837e0780]: starting transaction background task with id=[1c]. 2024-01-11 11:45:36:648 belle-sip-message- Changing [server] [BYE] transaction [0x2837e0780], from state [INIT] to [TRYING] 2024-01-11 11:45:36:648 belle-sip-message- Changing [server] [BYE] transaction [0x2837e0780], from state [TRYING] to [COMPLETED] 2024-01-11 11:45:36:648 belle-sip-message- channel [0x10a854000]: message sent to [UDP://sip.test.com:5060], size: [511] bytes SIP/2.0 200 Ok
Via: SIP/2.0/UDP 151.4.4.2:52032;branch=z9hG4bK00d4a15d;rport
From: "mk2" sip:1036@15.160.122.98;tag=as215b6007
Call-ID: 07224b1a1a0e63082b9d95323790fdae@151.4.4.2:52032
CSeq: 103 BYE
User-Agent: Unknown
Supported: replaces, outbound, gruu, path
2024-01-11 11:45:36:648 belle-sip-message- Dialog [0x283afcb00]: now updated by transaction [0x2837e0780].
2024-01-11 11:45:36:648 belle-sip-message- Dialog [0x283afcb00] deleted (is_expired=0)
2024-01-11 11:45:36:648 mediastreamer-message- ms_ticker_set_time_func: ticker's time method updated.
2024-01-11 11:45:36:648 mediastreamer-message- ms_ticker_synchronizer_destroy(): sound/wall clock skew was in average=0.000000 ms
2024-01-11 11:45:36:648 mediastreamer-message- Stopping AudioStream.
2024-01-11 11:45:36:648 mediastreamer-message- MediaStream[0x102f88780] (MSAudio) with RtpSession[0x1030f3800] summary:
2024-01-11 11:45:36:648 mediastreamer-message- send-ssrc = [dec:1727209020 hex:66f31e3c]
2024-01-11 11:45:36:648 mediastreamer-message- recv-ssrc = [dec:0 hex:0]
2024-01-11 11:45:36:648 ortp-message- ===========================================================
2024-01-11 11:45:36:648 ortp-message- RTP STATISTICS
2024-01-11 11:45:36:648 ortp-message- -----------------------------------------------------------
2024-01-11 11:45:36:648 ortp-message- sent 0 packets
2024-01-11 11:45:36:648 ortp-message- 0 duplicated packets
2024-01-11 11:45:36:648 ortp-message- 0 bytes
2024-01-11 11:45:36:648 ortp-message- received 0 packets
2024-01-11 11:45:36:649 ortp-message- 0 duplicated packets
2024-01-11 11:45:36:649 ortp-message- 0 bytes
2024-01-11 11:45:36:649 ortp-message- incoming delivered to the app 0 bytes
2024-01-11 11:45:36:649 ortp-message- incoming cumulative lost 0 packets
2024-01-11 11:45:36:649 ortp-message- incoming received too late 0 packets
2024-01-11 11:45:36:649 ortp-message- incoming bad formatted 0 packets
2024-01-11 11:45:36:649 ortp-message- incoming discarded (queue overflow) 0 packets
2024-01-11 11:45:36:649 ortp-message- sent rtcp 0 packets
2024-01-11 11:45:36:649 ortp-message- received rtcp 0 packets
2024-01-11 11:45:36:649 ortp-message- ===========================================================
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSAURead:0x2837f5c20,0-->MSResample:0x2837f5fe0,0
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSResample:0x2837f5fe0,0-->MSEqualizer:0x2837f6120,0
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSEqualizer:0x2837f6120,0-->MSVolume:0x2837e85a0,0
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSVolume:0x2837e85a0,0-->MSAudioMixer:0x2837f6080,0
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSAudioMixer:0x2837f6080,0-->MSUlawEnc:0x2837f5ea0,0
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSUlawEnc:0x2837f5ea0,0-->MSRtpSend:0x2837e2800,0
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSRtpRecv:0x2837f5540,0-->MSUlawDec:0x2837f5f40,0
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSUlawDec:0x2837f5f40,0-->MSGenericPLC:0x2837f8820,0
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSGenericPLC:0x2837f8820,0-->MSAudioFlowControl:0x2837f83c0,0
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSAudioFlowControl:0x2837f83c0,0-->MSDtmfGen:0x2837f5cc0,0
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSDtmfGen:0x2837f5cc0,0-->MSVolume:0x2837ec460,0
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSVolume:0x2837ec460,0-->MSEqualizer:0x2837f61c0,0
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSEqualizer:0x2837f61c0,0-->MSAudioMixer:0x2837f8780,0
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSFilePlayer:0x2837f88c0,0-->MSResample:0x2837f8960,0
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSResample:0x2837f8960,0-->MSAudioMixer:0x2837f8780,1
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSAudioMixer:0x2837f8780,0-->MSResample:0x2837e8820,0
2024-01-11 11:45:36:649 mediastreamer-message- ms_filter_unlink: MSResample:0x2837e8820,0-->MSAUWrite:0x2837f5d60,0
2024-01-11 11:45:36:649 mediastreamer-message- =================================================================================
2024-01-11 11:45:36:649 mediastreamer-message- FILTER USAGE STATISTICS
2024-01-11 11:45:36:649 mediastreamer-message- Name Count Time/tick (ms) CPU Usage
2024-01-11 11:45:36:649 mediastreamer-message- min mean max sd
2024-01-11 11:45:36:649 mediastreamer-message- ---------------------------------------------------------------------------------
2024-01-11 11:45:36:649 mediastreamer-message- MSResample 2 0.18 0.23 0.28 0.04 57.4
2024-01-11 11:45:36:649 mediastreamer-message- MSRtpSend 3 0.00 0.13 0.38 0.13 32.0
2024-01-11 11:45:36:649 mediastreamer-message- MSRtpRecv 3 0.02 0.03 0.03 0.00 6.6
2024-01-11 11:45:36:649 mediastreamer-message- MSAudioMixer 6 0.00 0.01 0.02 0.01 1.9
2024-01-11 11:45:36:649 mediastreamer-message- MSAURead 3 0.00 0.00 0.01 0.00 0.8
2024-01-11 11:45:36:649 mediastreamer-message- MSAUWrite 2 0.00 0.00 0.00 0.00 0.4
2024-01-11 11:45:36:649 mediastreamer-message- MSGenericPLC 3 0.00 0.00 0.00 0.00 0.3
2024-01-11 11:45:36:649 mediastreamer-message- MSFilePlayer 3 0.00 0.00 0.00 0.00 0.3
2024-01-11 11:45:36:649 mediastreamer-message- MSDtmfGen 3 0.00 0.00 0.00 0.00 0.3
2024-01-11 11:45:36:649 mediastreamer-message- MSAudioFlowControl 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:649 mediastreamer-message- MSEqualizer 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:649 mediastreamer-message- MSVolume 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:649 mediastreamer-message- MSUlawDec 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:649 mediastreamer-message- MSUlawEnc 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:649 mediastreamer-message- MSSpeexEC 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:649 mediastreamer-message- =================================================================================
2024-01-11 11:45:36:649 mediastreamer-message- au_read_uninit
2024-01-11 11:45:36:649 liblinphone-message- [ToneManager] notifyToneIndication reason No error
2024-01-11 11:45:36:649 liblinphone-message- [ToneManager] startNamedTone
2024-01-11 11:45:36:649 liblinphone-message- [ToneManager] playTone playing DTMF tone
2024-01-11 11:45:36:649 mediastreamer-message- ms_au_write_new, sound card : 0x2822f8480 (iPhone Microfono)
2024-01-11 11:45:36:649 mediastreamer-message- au_update_ms_snd_card
2024-01-11 11:45:36:649 mediastreamer-message- speex_lib_ctl init with neon ? 1
2024-01-11 11:45:36:650 mediastreamer-message- Audio session already correctly configured.
2024-01-11 11:45:36:650 mediastreamer-message- Audio session already correctly configured.
2024-01-11 11:45:36:650 mediastreamer-message- configuring resampler output to rate=[48000], nchannels=[1]
2024-01-11 11:45:36:650 mediastreamer-message- ms_filter_link: MSFilePlayer:0x2837e2a80,0-->MSDtmfGen:0x2837e2080,0
2024-01-11 11:45:36:650 mediastreamer-message- ms_filter_link: MSDtmfGen:0x2837e2080,0-->MSResample:0x2837e2d00,0
2024-01-11 11:45:36:650 mediastreamer-message- ms_filter_link: MSResample:0x2837e2d00,0-->MSAUWrite:0x2837e2bc0,0
2024-01-11 11:45:36:651 mediastreamer-message- Initializing speex resampler in mode [voip] from 1 channels
2024-01-11 11:45:36:651 mediastreamer-message- Audio session already correctly configured.
2024-01-11 11:45:36:651 mediastreamer-message- Playing tones of frequencies 480,0 Hz, duration=200, amplitude=0.500000 interval=200, repeat_count=2
2024-01-11 11:45:36:651 liblinphone-message- CallSession [0x282dad898] moving from state LinphoneCallStreamsRunning to LinphoneCallEnd
2024-01-11 11:45:36:651 liblinphone-message- StreamsGroup::finish() called.
2024-01-11 11:45:36:651 mediastreamer-message- =================================================================================
2024-01-11 11:45:36:651 mediastreamer-message- FILTER USAGE STATISTICS
2024-01-11 11:45:36:651 mediastreamer-message- Name Count Time/tick (ms) CPU Usage
2024-01-11 11:45:36:651 mediastreamer-message- min mean max sd
2024-01-11 11:45:36:651 mediastreamer-message- ---------------------------------------------------------------------------------
2024-01-11 11:45:36:651 mediastreamer-message- MSFilePlayer 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:651 mediastreamer-message- MSAudioFlowControl 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:651 mediastreamer-message- MSGenericPLC 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:652 mediastreamer-message- MSEqualizer 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:652 mediastreamer-message- MSAudioMixer 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:652 mediastreamer-message- MSVolume 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:652 mediastreamer-message- MSResample 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:652 mediastreamer-message- MSUlawDec 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:652 mediastreamer-message- MSUlawEnc 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:652 mediastreamer-message- MSAUWrite 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:652 mediastreamer-message- MSAURead 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:652 mediastreamer-message- MSDtmfGen 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:652 mediastreamer-message- MSRtpRecv 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:652 mediastreamer-message- MSSpeexEC 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:652 mediastreamer-message- MSRtpSend 0 0.00 0.00 0.00 0.00 0.0
2024-01-11 11:45:36:652 mediastreamer-message- =================================================================================
2024-01-11 11:45:36:652 mediastreamer-message- Stopping ZRTP context on session [0x0]
2024-01-11 11:45:36:652 mediastreamer-message- ZRTP context destroyed
2024-01-11 11:45:36:651 mediastreamer-message- Priority used: 47
2024-01-11 11:45:36:652 mediastreamer-message- Ring MSTicker priority set to SCHED_RR and value (47)
2024-01-11 11:45:36:660 mediastreamer-message- MSAudio MSTicker thread exiting
2024-01-11 11:45:36:661 liblinphone-message- MainDb::addEvent() of type ConferenceCallEnded (value 4)
2024-01-11 11:45:36:661 liblinphone-message- Update conference call in database: 07224b1a1a0e63082b9d95323790fdae@151.4.4.2:52032
2024-01-11 11:45:36:668 liblinphone-message- Linphone core [0x103030800] notified [call_log_updated]
2024-01-11 11:45:36:668 liblinphone-message- Resetting the current call
2024-01-11 11:45:36:668 liblinphone-message- Notifying sound card that is no longer needed.
2024-01-11 11:45:36:668 liblinphone-message- Removing the call (local address sip:mk@151.1.84.567:32689;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip;pn-silent=1;pn-timeout=0;transport=udp remote address "mk2" sip:1036@15.160.122.98) from the list attached to the core
2024-01-11 11:45:36:669 liblinphone-message- Linphone core [0x103030800] notified [last_call_ended]
2024-01-11 11:45:36:669 liblinphone-message- Unable to find audio video conference with conference ID ConferenceId(peer=sip:mk@151.1.84.567:32689;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip;pn-silent=1;pn-timeout=0;transport=udp, local=sip:mk@151.1.84.567:32689;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip;pn-silent=1;pn-timeout=0;transport=udp) in RAM.
2024-01-11 11:45:36:683 bctbx-message- Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background.
2024-01-11 11:45:36:683 belle-sip-message- channel [0x10a854000]: starting recv background task with id=[1d].
2024-01-11 11:45:36:684 belle-sip-message- channel [0x10a854000]: received [641] new bytes from [UDP://sip.test.com:5060]:
BYE sip:151.1.84.567:32689;transport=udp SIP/2.0
Via: SIP/2.0/UDP 151.4.4.2:52032;branch=z9hG4bK00d4a15d;rport
Max-Forwards: 70
From: "mk2" sip:1036@15.160.122.98;tag=as215b6007
Call-ID: 07224b1a1a0e63082b9d95323790fdae@151.4.4.2:52032
CSeq: 103 BYE
User-Agent: Asterisk PBX 18.16.0
X-Asterisk-HangupCause: Bearer capability not available
X-Asterisk-HangupCauseCode: 58
Content-Length: 0
2024-01-11 11:45:36:686 belle-sip-message- channel [0x10a854000] [641] bytes parsed 2024-01-11 11:45:36:686 belle-sip-message- Found transaction [0x2837e0780] matching request. 2024-01-11 11:45:36:686 belle-sip-message- channel [0x10a854000]: message sent to [UDP://sip.test.com:5060], size: [511] bytes SIP/2.0 200 Ok
Via: SIP/2.0/UDP 151.4.4.2:52032;branch=z9hG4bK00d4a15d;rport
From: "mk2" sip:1036@15.160.122.98;tag=as215b6007
Call-ID: 07224b1a1a0e63082b9d95323790fdae@151.4.4.2:52032
CSeq: 103 BYE
User-Agent: Unknown
Supported: replaces, outbound, gruu, path
2024-01-11 11:45:36:686 belle-sip-message- channel [0x10a854000]: ending recv background task with id=[1d]. 2024-01-11 11:45:36:686 liblinphone-message- Dialog [0x283afcb00] terminated for op [0x103018800] 2024-01-11 11:45:36:704 bctbx-message- Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2024-01-11 11:45:36:704 belle-sip-message- channel [0x10a854000]: starting recv background task with id=[1e]. 2024-01-11 11:45:36:704 belle-sip-message- channel [0x10a854000]: received [641] new bytes from [UDP://sip.test.com:5060]: BYE sip:151.1.84.567:32689;transport=udp SIP/2.0
Via: SIP/2.0/UDP 151.4.4.2:52032;branch=z9hG4bK00d4a15d;rport
Max-Forwards: 70
From: "mk2" sip:1036@15.160.122.98;tag=as215b6007
Call-ID: 07224b1a1a0e63082b9d95323790fdae@151.4.4.2:52032
CSeq: 103 BYE
User-Agent: Asterisk PBX 18.16.0
X-Asterisk-HangupCause: Bearer capability not available
X-Asterisk-HangupCauseCode: 58
Content-Length: 0
2024-01-11 11:45:36:706 belle-sip-message- channel [0x10a854000] [641] bytes parsed 2024-01-11 11:45:36:707 belle-sip-message- Found transaction [0x2837e0780] matching request. 2024-01-11 11:45:36:707 belle-sip-message- channel [0x10a854000]: message sent to [UDP://sip.test.com:5060], size: [511] bytes SIP/2.0 200 Ok
Via: SIP/2.0/UDP 151.4.4.2:52032;branch=z9hG4bK00d4a15d;rport
From: "mk2" sip:1036@15.160.122.98;tag=as215b6007
Call-ID: 07224b1a1a0e63082b9d95323790fdae@151.4.4.2:52032
CSeq: 103 BYE
User-Agent: Unknown
Supported: replaces, outbound, gruu, path
2024-01-11 11:45:36:707 belle-sip-message- channel [0x10a854000]: ending recv background task with id=[1e]. 2024-01-11 11:45:36:707 liblinphone-message- CallSession [0x282dad898] moving from state LinphoneCallEnd to LinphoneCallReleased 2024-01-11 11:45:36:707 liblinphone-message- Unable to find audio video conference with conference ID ConferenceId(peer=sip:mk@151.1.84.567:32689;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip;pn-silent=1;pn-timeout=0;transport=udp, local=sip:mk@151.1.84.567:32689;pn-prid=9B43AA40D0BYTIRNEROR83MV4AA09036B0B2985735E6B144E36228558:voip;pn-provider=apns.dev;pn-param=ABCD1234.com.test.app.voip;pn-silent=1;pn-timeout=0;transport=udp) in RAM.
2024-01-11 11:45:36:708 liblinphone-message- Linphone core [0x103030800] notified [call_state_changed] 2024-01-11 11:45:36:708 liblinphone-message- op [0x103018800] : setOrUpdateDialog() current=[0x283afcb00] new=[0x0] 2024-01-11 11:45:36:743 mediastreamer-message- [IOS Audio Route Change] msiounit audio route change callback 2024-01-11 11:45:36:744 mediastreamer-message- [IOS Audio Route Change] Previous audio route: input=No input, output=No output, New audio route: input=iPhone Microfono, output=Ricevitore 2024-01-11 11:45:36:744 mediastreamer-message- [IOS Audio Route Change] Audio unit not initialized, ignore route change 2024-01-11 11:45:36:783 mediastreamer-message- AVAudioSession activated: 1 2024-01-11 11:45:36:786 mediastreamer-message- configure_audio_unit() now called. 2024-01-11 11:45:36:786 mediastreamer-message- apply_sound_card_to_audio_session() 2024-01-11 11:45:36:790 mediastreamer-message- apply_sound_card_to_audio_session(): change AVAudioSession preferred input to iPhone Microfono. 2024-01-11 11:45:36:796 mediastreamer-message- configure_audio_unit() took 10 ms. 2024-01-11 11:45:36:796 mediastreamer-message- start_audio_unit(): about to start audio unit. 2024-01-11 11:45:36:913 mediastreamer-message- I/O unit latency [0.000000], quality [64] 2024-01-11 11:45:36:923 mediastreamer-message- [IOS Audio Route Change] msiounit audio route change callback 2024-01-11 11:45:36:924 mediastreamer-message- [IOS Audio Route Change] Previous audio route: input=iPhone Microfono, output=Ricevitore, New audio route: input=iPhone Microfono, output=Ricevitore 2024-01-11 11:45:36:924 mediastreamer-message- [IOS Audio Route Change] Audio unit not initialized, ignore route change 2024-01-11 11:45:37.021404+0100 XCally[11449:2225308] [aurioc] AURemoteIO.h:311 Unable to join I/O thread to workgroup ((null)): 2 2024-01-11 11:45:37.029099+0100 XCally[11449:2224956] [aurioc] AURemoteIO.cpp:1691 AUIOClient_StartIO failed (1701737535) 2024-01-11 11:45:37:029 mediastreamer-error- Cannot start audioUnit because [ent?] 2024-01-11 11:45:37:029 mediastreamer-error- AudioUnit error for (auresult = AudioOutputUnitStart(_audio_unit)): ret=unknown error (1701737535) (../../../../../mediastreamer2/src/audiofilters/msiounit.mm:509) 2024-01-11 11:45:37:029 mediastreamer-message- AudioUnit could not be started, current hw output latency [0.001500] input [0.000625] iobuf[0.021333] hw sample rate [48000.000000] 2024-01-11 11:45:37:029 mediastreamer-message- start_audio_unit() took 233 ms. 2024-01-11 11:45:37:039 mediastreamer-message- [IOS Audio Route Change] msiounit audio route change callback 2024-01-11 11:45:37:040 mediastreamer-message- [IOS Audio Route Change] Previous audio route: input=iPhone Microfono, output=Ricevitore, New audio route: input=No input, output=Altoparlante 2024-01-11 11:45:37:040 mediastreamer-message- [IOS Audio Route Change] Audio unit not initialized, ignore route change 2024-01-11 11:45:37:057 mediastreamer-message- [IOS Audio Route Change] msiounit audio route change callback 2024-01-11 11:45:37:058 mediastreamer-message- [IOS Audio Route Change] Previous audio route: input=iPhone Microfono, output=Ricevitore, New audio route: input=No input, output=Altoparlante 2024-01-11 11:45:37:058 mediastreamer-message- [IOS Audio Route Change] Audio unit not initialized, ignore route change 2024-01-11 11:45:37:086 mediastreamer-message- AVAudioSession activated: 0 2024-01-11 11:45:37:086 mediastreamer-message- AudioSession is deactivated while AudioUnit was created. Mark AudioUnit as zombified. 2024-01-11 11:45:37:664 liblinphone-message- RingStream no longer needed. 2024-01-11 11:45:37:664 liblinphone-message- [ToneManager] destroyRingStream 2024-01-11 11:45:37:664 mediastreamer-message- ms_filter_unlink: MSFilePlayer:0x2837e2a80,0-->MSDtmfGen:0x2837e2080,0 2024-01-11 11:45:37:664 mediastreamer-message- ms_filter_unlink: MSDtmfGen:0x2837e2080,0-->MSResample:0x2837e2d00,0 2024-01-11 11:45:37:665 mediastreamer-message- ms_filter_unlink: MSResample:0x2837e2d00,0-->MSAUWrite:0x2837e2bc0,0 2024-01-11 11:45:37:673 mediastreamer-message- Ring MSTicker thread exiting 2024-01-11 11:45:37:676 mediastreamer-message- AudioUnit stopped 2024-01-11 11:45:37:677 mediastreamer-message- au_destroy_audio_unit set holder card to NULL 2024-01-11 11:45:37:679 mediastreamer-message- AudioUnit destroyed
Viish
commented
5 months ago Either the latest logs don't match your issue (the call is correctly accepted with the 200 OK being sent), or it's your proxy server that sends the 488 to your other device.
Alessandro-v
commented
5 months ago @Viish thanks for the answer, I will ask the server provider to check for it. Just one more question, If it's a server problem why is this happening with the latest version of the SDK and not with the 5.2.x version?
Context
I use the linphone-sdk in my iOS project. Upgrading the SDK from the 5.2.x to the version 5.3.7 makes accepting a call always failing while, with the previous version, the system was perfectly working.
General information
Expected behaviour
Accepting a call with the app open needs to work like the previous version of the sdk
To Reproduce
Additional context
Add any other context about the problem here.
SDK logs URL
No response