2023-02-15 11:41:40:181 [/qt] MESSAGE QT: "Update nat policy." 2023-02-15 11:41:40:181 [/liblinphone] MESSAGE Sal nat helper [enabled] 2023-02-15 11:41:40:181 [/liblinphone] MESSAGE Sal use rports [enabled] 2023-02-15 11:41:40:193 [/qt] MESSAGE QT: "Notify event count: 0." 2023-02-15 11:41:40:197 [/qt] MESSAGE QT: "Notify event count: 0." 2023-02-15 11:41:42:430 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C184326180], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:41:42:430 [/belle-sip] MESSAGE Client INVITE transaction [000001C184326180] terminated 2023-02-15 11:41:42:430 [/liblinphone] MESSAGE Destroying op [000001C1FA42D0D0] of type [SalOpCall] 2023-02-15 11:41:42:434 [/belle-sip] MESSAGE Transaction [000001C1FDAE0220] deleted 2023-02-15 11:41:42:434 [/belle-sip] MESSAGE Transaction [000001C18475CA00] deleted 2023-02-15 11:41:42:434 [/belle-sip] MESSAGE Transaction [000001C184326180] deleted 2023-02-15 11:41:43:156 [/qt] MESSAGE QT: "Refresh registers." 2023-02-15 11:41:43:156 [/liblinphone] MESSAGE AuthRequested pushed 2023-02-15 11:41:43:156 [/belle-sip] MESSAGE No auth info found for call id [9rc6jfkcLk] 2023-02-15 11:41:43:156 [/belle-sip] MESSAGE Transaction [000001C1F2371110] deleted 2023-02-15 11:41:43:156 [/belle-sip] MESSAGE Changing [client] [REGISTER] transaction [000001C183C12C80], from state [INIT] to [TRYING] 2023-02-15 11:41:43:156 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [618] bytes REGISTER sip:10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.WsxQS2SX9;rport From: ;tag=sRJ3shbOh To: sip:60006@10.1.61.7 CSeq: 23 REGISTER Call-ID: 9rc6jfkcLk Max-Forwards: 70 Supported: replaces, outbound, gruu, path, record-aware Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;message-expires=2419200;+sip.instance="" Expires: 3600 User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 2023-02-15 11:41:43:156 [/liblinphone] MESSAGE Account [000001C1F9C5D4D0] for identity [sip:60006@10.1.61.7] moving from state [LinphoneRegistrationOk] to [LinphoneRegistrationProgress] on core [000001C1F1E70EB0] 2023-02-15 11:41:43:156 [/liblinphone] MESSAGE updateDependentAccount(): 000001C1F9C5D4D0 is registered, checking for [000001C1F9C5D4D8] ->dependency=0000000000000000 2023-02-15 11:41:43:157 [/qt] MESSAGE QT: "Notify event count: 0." 2023-02-15 11:41:43:158 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [account_registration_state_changed] 2023-02-15 11:41:43:158 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [registration_state_changed] 2023-02-15 11:41:43:177 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [420] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.WsxQS2SX9;rport=5060;received=10.25.20.62 From: ;tag=sRJ3shbOh To: sip:60006@10.1.61.7;tag=97adf4c8edef53a25de65a08cdb15f62.1f772813 CSeq: 23 REGISTER Call-ID: 9rc6jfkcLk WWW-Authenticate: Digest realm="10.1.61.7", nonce="Y+zGJmPsxPrxkYrqtu33dPJ/7tRPg66g" Server: kamailio (5.6.3 (x86_64/linux)) Content-Length: 0 2023-02-15 11:41:43:178 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [420] bytes parsed 2023-02-15 11:41:43:178 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:41:43:178 [/belle-sip] MESSAGE Changing [client] [REGISTER] transaction [000001C183C12C80], from state [TRYING] to [COMPLETED] 2023-02-15 11:41:43:178 [/liblinphone] MESSAGE AuthRequested pushed 2023-02-15 11:41:43:178 [/belle-sip] MESSAGE No auth info found for call id [9rc6jfkcLk] 2023-02-15 11:41:43:178 [/belle-sip] MESSAGE Auth info not found for this refresh operation on [000001C1F8B13ED0] 2023-02-15 11:41:43:178 [/liblinphone] MESSAGE Register refresher [401] reason [Unauthorized] for proxy [] 2023-02-15 11:41:43:178 [/liblinphone] MESSAGE Op 000001C1F74A2D30 added as pending authentication 2023-02-15 11:41:43:178 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.3 2023-02-15 11:41:43:178 [/liblinphone] MESSAGE 10.1.61.7/60006/10.1.61.7/HttpDigest authentication fails. 2023-02-15 11:41:43:178 [/liblinphone] MESSAGE AuthRequested pushed 2023-02-15 11:41:43:191 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [authentication_requested] 2023-02-15 11:41:43:202 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [authentication_requested] 2023-02-15 11:41:43:202 [/liblinphone] MESSAGE Account [000001C1F9C5D4D0] for identity [sip:60006@10.1.61.7] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationFailed] on core [000001C1F1E70EB0] 2023-02-15 11:41:43:202 [/liblinphone] MESSAGE updateDependentAccount(): 000001C1F9C5D4D0 is registered, checking for [000001C1F9C5D4D8] ->dependency=0000000000000000 2023-02-15 11:41:43:203 [/qt] MESSAGE QT: "Notify event count: 0." 2023-02-15 11:41:43:204 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [account_registration_state_changed] 2023-02-15 11:41:43:204 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [registration_state_changed] 2023-02-15 11:41:43:492 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C1FDAE0A40], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:41:43:492 [/belle-sip] MESSAGE Client INVITE transaction [000001C1FDAE0A40] terminated 2023-02-15 11:41:43:492 [/belle-sip] MESSAGE Transaction [000001C1FDAE0A40] deleted 2023-02-15 11:41:43:558 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C1FDB09FA0], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:41:43:558 [/belle-sip] MESSAGE Client INVITE transaction [000001C1FDB09FA0] terminated 2023-02-15 11:41:48:180 [/belle-sip] MESSAGE Changing [client] [REGISTER] transaction [000001C183C12C80], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:41:48:180 [/belle-sip] MESSAGE Client internal REGISTER transaction [000001C183C12C80] terminated 2023-02-15 11:41:48:304 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185ED4B90], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:41:48:304 [/belle-sip] MESSAGE Client INVITE transaction [000001C185ED4B90] terminated 2023-02-15 11:41:48:304 [/liblinphone] MESSAGE Destroying op [000001C1F3663960] of type [SalOpCall] 2023-02-15 11:41:48:305 [/belle-sip] MESSAGE Transaction [000001C1FDB09FA0] deleted 2023-02-15 11:41:48:305 [/belle-sip] MESSAGE Transaction [000001C1857F2140] deleted 2023-02-15 11:41:48:305 [/belle-sip] MESSAGE Transaction [000001C185ED4B90] deleted 2023-02-15 11:41:49:371 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.3 2023-02-15 11:41:49:371 [/liblinphone] MESSAGE linphone_core_add_auth_info(): restarted [0] operation(s) after updating auth info for username: [60006] realm [10.1.61.3] domain [10.1.61.7] 2023-02-15 11:41:49:463 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185EE7010], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:41:49:463 [/belle-sip] MESSAGE Client INVITE transaction [000001C185EE7010] terminated 2023-02-15 11:41:49:961 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: keep alive sent to [UDP://10.1.61.7:5060] 2023-02-15 11:41:53:525 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.3 2023-02-15 11:41:53:525 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.7 2023-02-15 11:41:53:525 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.7 2023-02-15 11:41:53:525 [/liblinphone] MESSAGE AuthStack::authFound() for Username[60006];Userid[];Realm[10.1.61.4];Domain[10.1.61.7];Algorithm[];AvailableAlgorithms[]; 2023-02-15 11:41:53:525 [/belle-sip] MESSAGE Auth info found for [60006] realm [10.1.61.4] 2023-02-15 11:41:53:525 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C184316740], from state [INIT] to [CALLING] 2023-02-15 11:41:53:525 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [1241] bytes INVITE sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.hsFYJviI4;rport From: ;tag=iFLb~Fz20 To: sip:60003@10.1.61.7 CSeq: 22 INVITE Call-ID: rFOblbwGuC Max-Forwards: 70 Supported: replaces, outbound, gruu, path, record-aware Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, PRACK, UPDATE Content-Type: application/sdp Content-Length: 369 Contact: ;expires=3599 User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 Authorization: Digest realm="10.1.61.4", nonce="1676461287/44bdd9e4223ae2b739593ac0905826fc", algorithm=MD5, opaque="627c8f256ba4b35c", username="60006", uri="sip:60003@10.1.61.7", response="21358372d158c899607a7420baf34c2b", cnonce="O23hryANNxVC7XQk", nc=00000001, qop=auth v=0 o=60006 3618 921 IN IP4 10.25.20.62 s=Talk c=IN IP4 10.25.20.62 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics a=group:BUNDLE as a=record:off m=audio 36600 RTP/AVP 8 0 96 a=rtpmap:96 telephone-event/8000 a=rtcp-mux a=mid:as a=extmap:1 urn:ietf:params:rtp-hdrext:sdes:mid a=rtcp-fb:* trr-int 1000 a=rtcp-fb:* ccm tmmbr 2023-02-15 11:41:53:526 [/liblinphone] MESSAGE Op 000001C185B5FA80 removed as pending authentication 2023-02-15 11:41:53:526 [/liblinphone] MESSAGE linphone_core_add_auth_info(): restarted [1] operation(s) after updating auth info for username: [60006] realm [10.1.61.7] domain [10.1.61.7] 2023-02-15 11:41:53:553 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [312] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.hsFYJviI4;rport=5060;received=10.25.20.62 From: ;tag=iFLb~Fz20 To: sip:60003@10.1.61.7 CSeq: 22 INVITE Call-ID: rFOblbwGuC Server: kamailio (5.6.3 (x86_64/linux)) Content-Length: 0 2023-02-15 11:41:53:553 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [312] bytes parsed 2023-02-15 11:41:53:553 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:41:53:553 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C184316740], from state [CALLING] to [PROCEEDING] 2023-02-15 11:41:53:554 [/liblinphone] MESSAGE op [000001C185B5FA80] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:41:53:554 [/liblinphone] MESSAGE Op [000001C185B5FA80] receiving call response [100], dialog is [0000000000000000] in state [BELLE_SIP_DIALOG_NULL] 2023-02-15 11:41:53:554 [/liblinphone] MESSAGE Cancelling INVITE request from [sip:60006@10.1.61.7] to [sip:60003@10.1.61.7] 2023-02-15 11:41:53:554 [/belle-sip] MESSAGE Found transaction [000001C184316740] matching request. 2023-02-15 11:41:53:554 [/belle-sip] MESSAGE Changing [client] [CANCEL] transaction [000001C1846C2E60], from state [INIT] to [TRYING] 2023-02-15 11:41:53:554 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [321] bytes CANCEL sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.hsFYJviI4;rport Call-ID: rFOblbwGuC From: ;tag=iFLb~Fz20 To: sip:60003@10.1.61.7 Max-Forwards: 70 CSeq: 22 CANCEL User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 2023-02-15 11:41:53:586 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [552] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.25.20.62:5060;rport=5060;received=10.25.20.62;branch=z9hG4bK.hsFYJviI4 Record-Route: Call-ID: rFOblbwGuC From: ;tag=iFLb~Fz20 To: ;tag=63a3997d-6d4e-435b-a63f-bba778c3f529 CSeq: 22 INVITE Server: pbx Contact: Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER P-Asserted-Identity: "60003" Content-Length: 0 2023-02-15 11:41:53:587 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [552] bytes parsed 2023-02-15 11:41:53:587 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:41:53:587 [/belle-sip] MESSAGE New client dialog [000001C1859161A0] , local tag [iFLb~Fz20], remote tag [63a3997d-6d4e-435b-a63f-bba778c3f529] 2023-02-15 11:41:53:587 [/belle-sip] MESSAGE Dialog [000001C1859161A0]: now updated by transaction [000001C184316740]. 2023-02-15 11:41:53:587 [/liblinphone] MESSAGE op [000001C185B5FA80] : setOrUpdateDialog() current=[0000000000000000] new=[000001C1859161A0] 2023-02-15 11:41:53:587 [/liblinphone] MESSAGE Op [000001C185B5FA80] receiving call response [180], dialog is [000001C1859161A0] in state [BELLE_SIP_DIALOG_EARLY] 2023-02-15 11:41:53:617 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [329] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 200 canceling Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.hsFYJviI4;rport=5060;received=10.25.20.62 Call-ID: rFOblbwGuC From: ;tag=iFLb~Fz20 To: sip:60003@10.1.61.7;tag=5c46d2e780a7932142ddfe457a3280bb-04bbf071 CSeq: 22 CANCEL Server: kamailio (5.6.3 (x86_64/linux)) Content-Length: 0 2023-02-15 11:41:53:618 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [329] bytes parsed 2023-02-15 11:41:53:618 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:41:53:618 [/belle-sip] MESSAGE Changing [client] [CANCEL] transaction [000001C1846C2E60], from state [TRYING] to [COMPLETED] 2023-02-15 11:41:53:618 [/liblinphone] MESSAGE op [000001C185B5FA80] : setOrUpdateDialog() current=[000001C1859161A0] new=[0000000000000000] 2023-02-15 11:41:53:618 [/liblinphone] MESSAGE Op [000001C185B5FA80] receiving call response [200], dialog is [0000000000000000] in state [BELLE_SIP_DIALOG_NULL] 2023-02-15 11:41:53:618 [/liblinphone] WARNING Cancel done reported on already terminated CallSession 2023-02-15 11:41:53:654 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [532] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 10.25.20.62:5060;rport=5060;received=10.25.20.62;branch=z9hG4bK.hsFYJviI4 Record-Route: Call-ID: rFOblbwGuC From: ;tag=iFLb~Fz20 To: ;tag=63a3997d-6d4e-435b-a63f-bba778c3f529 CSeq: 22 INVITE Server: pbx Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER P-Asserted-Identity: "60003" Content-Length: 0 2023-02-15 11:41:53:657 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [532] bytes parsed 2023-02-15 11:41:53:657 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:41:53:657 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C184316740], from state [PROCEEDING] to [COMPLETED] 2023-02-15 11:41:53:658 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [331] bytes ACK sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.hsFYJviI4;rport Call-ID: rFOblbwGuC From: ;tag=iFLb~Fz20 To: ;tag=63a3997d-6d4e-435b-a63f-bba778c3f529 Contact: ;expires=3599 Max-Forwards: 70 CSeq: 22 ACK 2023-02-15 11:41:53:658 [/belle-sip] MESSAGE Dialog [000001C1859161A0]: now updated by transaction [000001C184316740]. 2023-02-15 11:41:53:658 [/belle-sip] MESSAGE Dialog [000001C1859161A0] deleted (is_expired=0) 2023-02-15 11:41:53:658 [/liblinphone] MESSAGE op [000001C185B5FA80] : setOrUpdateDialog() current=[0000000000000000] new=[000001C1859161A0] 2023-02-15 11:41:53:658 [/liblinphone] MESSAGE Op [000001C185B5FA80] receiving call response [487], dialog is [000001C1859161A0] in state [BELLE_SIP_DIALOG_TERMINATED] 2023-02-15 11:41:53:658 [/liblinphone] WARNING Failure reported on already terminated CallSession 2023-02-15 11:41:53:687 [/liblinphone] MESSAGE Dialog [000001C1859161A0] terminated for op [000001C185B5FA80] 2023-02-15 11:41:53:721 [/liblinphone] MESSAGE op [000001C185B5FA80] : setOrUpdateDialog() current=[000001C1859161A0] new=[0000000000000000] 2023-02-15 11:41:54:741 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185ED56F0], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:41:54:741 [/belle-sip] MESSAGE Client INVITE transaction [000001C185ED56F0] terminated 2023-02-15 11:41:54:741 [/liblinphone] MESSAGE Destroying op [000001C185B5F6B0] of type [SalOpCall] 2023-02-15 11:41:54:741 [/belle-sip] MESSAGE Transaction [000001C185EE7010] deleted 2023-02-15 11:41:54:741 [/belle-sip] MESSAGE Transaction [000001C186007D60] deleted 2023-02-15 11:41:54:742 [/belle-sip] MESSAGE Transaction [000001C185ED56F0] deleted 2023-02-15 11:41:55:615 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185F0A0B0], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:41:55:615 [/belle-sip] MESSAGE Client INVITE transaction [000001C185F0A0B0] terminated 2023-02-15 11:41:55:615 [/belle-sip] MESSAGE Transaction [000001C185F0A0B0] deleted 2023-02-15 11:41:55:682 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C184316810], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:41:55:682 [/belle-sip] MESSAGE Client INVITE transaction [000001C184316810] terminated 2023-02-15 11:41:56:540 [/liblinphone] MESSAGE New MediaSession [000001C1858E5710] initialized (liblinphone version: 5.2.15) 2023-02-15 11:41:56:540 [/liblinphone] MESSAGE Media local-ip for streams advertised in SDP: 10.25.20.62 2023-02-15 11:41:56:540 [/liblinphone] MESSAGE Rtp bundle is enabled. 2023-02-15 11:41:56:540 [/liblinphone] MESSAGE Found media local-ip from signaling: 10.25.20.62 2023-02-15 11:41:56:541 [/liblinphone] MESSAGE [LIME] C:\GitLab-Runner\builds\hexP7jis\0\BC\public\linphone-desktop\linphone-sdk\lime\src\lime_localStorage.cpp:255 Cannot find Lime User sip:60006@10.25.20.62 in DB while setting up identity key for ZRTP auxiliary secret 2023-02-15 11:41:56:541 [/liblinphone] MESSAGE stream#0 [audio] in state [Stopped]: multicast role is [inactive] 2023-02-15 11:41:56:541 [/ortp] MESSAGE RtpSession bound to [::0] ports [36600] [36601] 2023-02-15 11:41:56:541 [/liblinphone] MESSAGE Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80 2023-02-15 11:41:56:542 [/liblinphone] MESSAGE Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32 2023-02-15 11:41:56:542 [/liblinphone] MESSAGE Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80 2023-02-15 11:41:56:542 [/liblinphone] MESSAGE Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32 2023-02-15 11:41:56:542 [/liblinphone] MESSAGE Configured srtp crypto suite: AEAD_AES_128_GCM 2023-02-15 11:41:56:542 [/liblinphone] MESSAGE Configured srtp crypto suite: AEAD_AES_256_GCM 2023-02-15 11:41:56:542 [/liblinphone] MESSAGE Configured zrtp key agreement: 'MS_ZRTP_KEY_AGREEMENT_K255_KYB512' 2023-02-15 11:41:56:542 [/mediastreamer] MESSAGE Creating ZRTP engine on rtp session [000001C1FBEE28F0] ssrc 0xe7f38f18 2023-02-15 11:41:56:542 [/ortp] MESSAGE rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2023-02-15 11:41:56:542 [/mediastreamer] MESSAGE Setting DSCP to 46 for MSAudio stream. 2023-02-15 11:41:56:542 [/ortp] MESSAGE check OS support for qwave.lib 2023-02-15 11:41:56:542 [/ortp] ERROR QOSAddSocketToFlow failed to add a flow with error 87 2023-02-15 11:41:56:542 [/liblinphone] MESSAGE Created stream of type audio at index 0: stream#0 [audio] in state [Stopped] 2023-02-15 11:41:56:543 [/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. 2023-02-15 11:41:56:543 [/liblinphone] MESSAGE [MS2AudioStream] setting type of soundcard 000001C1F3182B80 to voice 2023-02-15 11:41:56:543 [/liblinphone] MESSAGE [MS2AudioStream] setting type of soundcard 000001C1F3182400 to voice 2023-02-15 11:41:56:543 [/liblinphone] MESSAGE [ToneManager] stopDtmfStream 2023-02-15 11:41:56:543 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_created] 2023-02-15 11:41:56:543 [/liblinphone] MESSAGE CallSession [000001C1858E5710] moving from state LinphoneCallIdle to LinphoneCallOutgoingInit 2023-02-15 11:41:56:543 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [first_call_started] 2023-02-15 11:41:56:543 [/qt] MESSAGE QT: "Add call:" "sip:60006@10.1.61.7" "sip:60003@10.1.61.7" 2023-02-15 11:41:56:553 [/liblinphone] WARNING Couldn't find supported video definition for 0x0 2023-02-15 11:41:56:590 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_state_changed] 2023-02-15 11:41:56:591 [/liblinphone] MESSAGE [ToneManager] stopDtmfStream 2023-02-15 11:41:56:591 [/liblinphone] MESSAGE ICE state is IceStateNotActivated for stream#0 [audio] in state [Stopped] 2023-02-15 11:41:56:591 [/liblinphone] MESSAGE Contact has been fixed using proxy 2023-02-15 11:41:56:592 [/liblinphone] MESSAGE Setting contact address for session 000001C1847F38F0 to ;expires=3599 2023-02-15 11:41:56:592 [/liblinphone] MESSAGE [sip:60006@10.1.61.7] calling [sip:60003@10.1.61.7] on op [000001C185B5E770] 2023-02-15 11:41:56:592 [/liblinphone] MESSAGE Skipping top route of initial route-set because same as request-uri 2023-02-15 11:41:56:592 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185EEB1E0], from state [INIT] to [CALLING] 2023-02-15 11:41:56:592 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [964] bytes INVITE sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.Z6QTdv3ec;rport From: ;tag=UzN0yUYRr To: sip:60003@10.1.61.7 CSeq: 20 INVITE Call-ID: 0pCKDVgtDG Max-Forwards: 70 Supported: replaces, outbound, gruu, path, record-aware Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, PRACK, UPDATE Content-Type: application/sdp Content-Length: 370 Contact: ;expires=3599 User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 v=0 o=60006 1927 1060 IN IP4 10.25.20.62 s=Talk c=IN IP4 10.25.20.62 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics a=group:BUNDLE as a=record:off m=audio 36600 RTP/AVP 8 0 96 a=rtpmap:96 telephone-event/8000 a=rtcp-mux a=mid:as a=extmap:1 urn:ietf:params:rtp-hdrext:sdes:mid a=rtcp-fb:* trr-int 1000 a=rtcp-fb:* ccm tmmbr 2023-02-15 11:41:56:592 [/liblinphone] MESSAGE CallSession [000001C1858E5710] moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress 2023-02-15 11:41:56:593 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_state_changed] 2023-02-15 11:41:56:593 [/liblinphone] MESSAGE MainDb::addEvent() of type ConferenceCallStarted (value 3) 2023-02-15 11:41:56:593 [/liblinphone] MESSAGE Insert new conference call in database: 0pCKDVgtDG 2023-02-15 11:41:56:602 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_log_updated] 2023-02-15 11:41:56:637 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [312] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.Z6QTdv3ec;rport=5060;received=10.25.20.62 From: ;tag=UzN0yUYRr To: sip:60003@10.1.61.7 CSeq: 20 INVITE Call-ID: 0pCKDVgtDG Server: kamailio (5.6.3 (x86_64/linux)) Content-Length: 0 2023-02-15 11:41:56:638 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [312] bytes parsed 2023-02-15 11:41:56:638 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:41:56:638 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185EEB1E0], from state [CALLING] to [PROCEEDING] 2023-02-15 11:41:56:638 [/liblinphone] MESSAGE op [000001C185B5E770] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:41:56:638 [/liblinphone] MESSAGE Op [000001C185B5E770] receiving call response [100], dialog is [0000000000000000] in state [BELLE_SIP_DIALOG_NULL] 2023-02-15 11:41:56:656 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [519] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.25.20.62:5060;rport=5060;received=10.25.20.62;branch=z9hG4bK.Z6QTdv3ec Record-Route: Call-ID: 0pCKDVgtDG From: ;tag=UzN0yUYRr To: ;tag=z9hG4bK15ff.65050fa0e98be652868d4ac780ed8bc3.0 CSeq: 20 INVITE WWW-Authenticate: Digest realm="10.1.61.3",nonce="1676461306/560f242673cbef01c979a1a72e7a77e8",opaque="61cf572b2b5d683f",algorithm=MD5,qop="auth" Server: pbx Content-Length: 0 2023-02-15 11:41:56:657 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [519] bytes parsed 2023-02-15 11:41:56:657 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:41:56:657 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185EEB1E0], from state [PROCEEDING] to [COMPLETED] 2023-02-15 11:41:56:657 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [341] bytes ACK sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.Z6QTdv3ec;rport Call-ID: 0pCKDVgtDG From: ;tag=UzN0yUYRr To: ;tag=z9hG4bK15ff.65050fa0e98be652868d4ac780ed8bc3.0 Contact: ;expires=3599 Max-Forwards: 70 CSeq: 20 ACK 2023-02-15 11:41:56:657 [/liblinphone] MESSAGE AuthRequested pushed 2023-02-15 11:41:56:657 [/belle-sip] MESSAGE No auth info found for call id [0pCKDVgtDG] 2023-02-15 11:41:56:657 [/liblinphone] MESSAGE No auth info found for [sip:60006@10.1.61.7] 2023-02-15 11:41:56:657 [/liblinphone] MESSAGE Op 000001C185B5E770 added as pending authentication 2023-02-15 11:41:56:657 [/liblinphone] MESSAGE [Magic Search] Found 0 results in friends 2023-02-15 11:41:56:734 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [authentication_requested] 2023-02-15 11:41:57:708 [/liblinphone] MESSAGE Terminate CallSession [000001C1858E5710] which is currently in state [LinphoneCallOutgoingProgress] 2023-02-15 11:41:57:708 [/belle-sip] MESSAGE ICT retransmissions stopped 2023-02-15 11:41:57:708 [/liblinphone] MESSAGE [ToneManager] startErrorTone 2023-02-15 11:41:57:708 [/liblinphone] MESSAGE [ToneManager] playTone 2023-02-15 11:41:57:745 [/bctbx] MESSAGE MSWASAPI playback output initialized for [Наушники гарнитуры (Plantronics C720-M)--{0.0.0.00000000}.{f6bd33e5-fc39-4f07-95f6-ad1ae778399a}] at 48000 Hz, 2 channels, with buffer size 9600 (200 ms), device period is 10, 16-bit frames are on 32 bits 2023-02-15 11:41:57:745 [/mediastreamer] MESSAGE speex_lib_ctl init with neon ? 0 2023-02-15 11:41:57:745 [/mediastreamer] MESSAGE configuring resampler output to rate=[48000], nchannels=[2] 2023-02-15 11:41:57:745 [/mediastreamer] MESSAGE ms_filter_link: MSFilePlayer:000001C18459AB40,0-->MSDtmfGen:000001C18459B7F0,0 2023-02-15 11:41:57:745 [/mediastreamer] MESSAGE ms_filter_link: MSDtmfGen:000001C18459B7F0,0-->MSResample:000001C18459B320,0 2023-02-15 11:41:57:745 [/mediastreamer] MESSAGE ms_filter_link: MSResample:000001C18459B320,0-->MSWASAPIWrite:000001C18459B240,0 2023-02-15 11:41:57:745 [/mediastreamer] MESSAGE Initializing speex resampler in mode [voip] 2023-02-15 11:41:57:745 [/bctbx] WARNING bctbx_set_self_thread_name(): not implemented. 2023-02-15 11:41:57:745 [/mediastreamer] MESSAGE win32 timer resolution set to 2 ms 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE Playing tones of frequencies 480,0 Hz, duration=200, amplitude=0.500000 interval=200, repeat_count=2 2023-02-15 11:41:57:746 [/liblinphone] MESSAGE CallSession [000001C1858E5710] moving from state LinphoneCallOutgoingProgress to LinphoneCallEnd 2023-02-15 11:41:57:746 [/liblinphone] MESSAGE StreamsGroup::finish() called. 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE ================================================================================= 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE FILTER USAGE STATISTICS 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE Name Count Time/tick (ms) CPU Usage 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE min mean max sd 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE --------------------------------------------------------------------------------- 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE MSOpusDec 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE MSMKVPlayer 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE MSResample 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE MSWASAPIWrite 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE MSDtmfGen 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE MSFilePlayer 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE MSWebRTCAEC 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE MSRtpSend 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE ================================================================================= 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE ================================================================================= 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE FILTER USAGE STATISTICS 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE Name Count Time/tick (ms) CPU Usage 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE min mean max sd 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE --------------------------------------------------------------------------------- 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE MSOpusDec 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE MSMKVPlayer 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE MSResample 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE MSWASAPIWrite 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE MSDtmfGen 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE MSFilePlayer 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE MSWebRTCAEC 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE MSRtpSend 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE ================================================================================= 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE Stopping ZRTP context on session [0000000000000000] 2023-02-15 11:41:57:746 [/mediastreamer] MESSAGE ZRTP context destroyed 2023-02-15 11:41:57:747 [/liblinphone] MESSAGE MainDb::addEvent() of type ConferenceCallEnded (value 4) 2023-02-15 11:41:57:747 [/liblinphone] MESSAGE Update conference call in database: 0pCKDVgtDG 2023-02-15 11:41:57:757 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_log_updated] 2023-02-15 11:41:57:757 [/liblinphone] MESSAGE Resetting the current call 2023-02-15 11:41:57:757 [/liblinphone] MESSAGE Removing the call (local address sip:60006@10.1.61.7 remote address sip:60003@10.1.61.7) from the list attached to the core 2023-02-15 11:41:57:757 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [last_call_ended] 2023-02-15 11:41:57:782 [/qt] MESSAGE QT: "Removing " CallModel " : " CallModel(0x1c185666540) 2023-02-15 11:41:57:783 [/qt] MESSAGE QT: "Update (`sip:60003@10.1.61.7`, `sip:60006@10.1.61.7`) from chat call." 2023-02-15 11:41:57:783 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_state_changed] 2023-02-15 11:41:57:783 [/liblinphone] MESSAGE CallSession [000001C1858E5710] moving from state LinphoneCallEnd to LinphoneCallReleased 2023-02-15 11:41:57:784 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_state_changed] 2023-02-15 11:41:57:813 [/qt] MESSAGE QT: "Notify event count: 0." 2023-02-15 11:41:57:816 [/qt] MESSAGE QT: "Notify event count: 0." 2023-02-15 11:41:58:616 [/belle-sip] MESSAGE Changing [client] [CANCEL] transaction [000001C1846C2E60], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:41:58:616 [/belle-sip] MESSAGE Client CANCEL transaction [000001C1846C2E60] terminated 2023-02-15 11:41:58:741 [/liblinphone] MESSAGE RingStream no longer needed. 2023-02-15 11:41:58:741 [/liblinphone] MESSAGE [ToneManager] destroyRingStream 2023-02-15 11:41:58:742 [/mediastreamer] MESSAGE ms_filter_unlink: MSFilePlayer:000001C18459AB40,0-->MSDtmfGen:000001C18459B7F0,0 2023-02-15 11:41:58:742 [/mediastreamer] MESSAGE ms_filter_unlink: MSDtmfGen:000001C18459B7F0,0-->MSResample:000001C18459B320,0 2023-02-15 11:41:58:742 [/mediastreamer] MESSAGE ms_filter_unlink: MSResample:000001C18459B320,0-->MSWASAPIWrite:000001C18459B240,0 2023-02-15 11:41:58:746 [/mediastreamer] MESSAGE Ring MSTicker thread exiting 2023-02-15 11:42:01:828 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.4 2023-02-15 11:42:01:828 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.3 2023-02-15 11:42:01:828 [/liblinphone] MESSAGE Account [000001C1F9C5D4D0] for identity [sip:60006@10.1.61.7] moving from state [LinphoneRegistrationFailed] to [LinphoneRegistrationProgress] on core [000001C1F1E70EB0] 2023-02-15 11:42:01:828 [/liblinphone] MESSAGE updateDependentAccount(): 000001C1F9C5D4D0 is registered, checking for [000001C1F9C5D4D8] ->dependency=0000000000000000 2023-02-15 11:42:01:828 [/qt] MESSAGE QT: "Notify event count: 0." 2023-02-15 11:42:01:830 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [account_registration_state_changed] 2023-02-15 11:42:01:830 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [registration_state_changed] 2023-02-15 11:42:01:830 [/liblinphone] MESSAGE Op 000001C1F74A2D30 removed as pending authentication 2023-02-15 11:42:01:830 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.3 2023-02-15 11:42:01:830 [/liblinphone] MESSAGE AuthStack::authFound() for Username[60006];Userid[];Realm[10.1.61.7];Domain[10.1.61.7];Algorithm[];AvailableAlgorithms[]; 2023-02-15 11:42:01:830 [/belle-sip] MESSAGE Auth info found for [60006] realm [10.1.61.7] 2023-02-15 11:42:01:830 [/belle-sip] MESSAGE Transaction [000001C183C12C80] deleted 2023-02-15 11:42:01:830 [/belle-sip] MESSAGE Changing [client] [REGISTER] transaction [000001C185810800], from state [INIT] to [TRYING] 2023-02-15 11:42:01:830 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [787] bytes REGISTER sip:10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.YCuJBwHoe;rport From: ;tag=sRJ3shbOh To: sip:60006@10.1.61.7 CSeq: 24 REGISTER Call-ID: 9rc6jfkcLk Max-Forwards: 70 Supported: replaces, outbound, gruu, path, record-aware Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;message-expires=2419200;+sip.instance="" Expires: 3600 User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 Authorization: Digest realm="10.1.61.7", nonce="Y+zGJmPsxPrxkYrqtu33dPJ/7tRPg66g", username="60006", uri="sip:10.1.61.7", response="07655e95501a87d2e1e9668b2ec77817" 2023-02-15 11:42:01:830 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.3 2023-02-15 11:42:01:830 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.3 2023-02-15 11:42:01:830 [/liblinphone] MESSAGE AuthStack::authFound() for Username[60006];Userid[];Realm[10.1.61.3];Domain[10.1.61.7];Algorithm[];AvailableAlgorithms[]; 2023-02-15 11:42:01:830 [/belle-sip] MESSAGE Auth info found for [60006] realm [10.1.61.3] 2023-02-15 11:42:01:830 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C184320DD0], from state [INIT] to [CALLING] 2023-02-15 11:42:01:830 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [1242] bytes INVITE sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.O9kPHB30~;rport From: ;tag=UzN0yUYRr To: sip:60003@10.1.61.7 CSeq: 21 INVITE Call-ID: 0pCKDVgtDG Max-Forwards: 70 Supported: replaces, outbound, gruu, path, record-aware Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, PRACK, UPDATE Content-Type: application/sdp Content-Length: 370 Contact: ;expires=3599 User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 Authorization: Digest realm="10.1.61.3", nonce="1676461306/560f242673cbef01c979a1a72e7a77e8", algorithm=MD5, opaque="61cf572b2b5d683f", username="60006", uri="sip:60003@10.1.61.7", response="d4ef090a969659c5679d5cd00353bf9d", cnonce="5xgqrmGCThzwCuJ3", nc=00000001, qop=auth v=0 o=60006 1927 1060 IN IP4 10.25.20.62 s=Talk c=IN IP4 10.25.20.62 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics a=group:BUNDLE as a=record:off m=audio 36600 RTP/AVP 8 0 96 a=rtpmap:96 telephone-event/8000 a=rtcp-mux a=mid:as a=extmap:1 urn:ietf:params:rtp-hdrext:sdes:mid a=rtcp-fb:* trr-int 1000 a=rtcp-fb:* ccm tmmbr 2023-02-15 11:42:01:830 [/liblinphone] MESSAGE Op 000001C185B5E770 removed as pending authentication 2023-02-15 11:42:01:830 [/liblinphone] MESSAGE linphone_core_add_auth_info(): restarted [2] operation(s) after updating auth info for username: [60006] realm [10.1.61.3] domain [10.1.61.7] 2023-02-15 11:42:01:834 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [312] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.O9kPHB30~;rport=5060;received=10.25.20.62 From: ;tag=UzN0yUYRr To: sip:60003@10.1.61.7 CSeq: 21 INVITE Call-ID: 0pCKDVgtDG Server: kamailio (5.6.3 (x86_64/linux)) Content-Length: 0 2023-02-15 11:42:01:835 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [312] bytes parsed 2023-02-15 11:42:01:835 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:01:835 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C184320DD0], from state [CALLING] to [PROCEEDING] 2023-02-15 11:42:01:835 [/liblinphone] MESSAGE op [000001C185B5E770] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:42:01:835 [/liblinphone] MESSAGE Op [000001C185B5E770] receiving call response [100], dialog is [0000000000000000] in state [BELLE_SIP_DIALOG_NULL] 2023-02-15 11:42:01:835 [/liblinphone] MESSAGE Cancelling INVITE request from [sip:60006@10.1.61.7] to [sip:60003@10.1.61.7] 2023-02-15 11:42:01:835 [/belle-sip] MESSAGE Found transaction [000001C184320DD0] matching request. 2023-02-15 11:42:01:835 [/belle-sip] MESSAGE Changing [client] [CANCEL] transaction [000001C1857D8100], from state [INIT] to [TRYING] 2023-02-15 11:42:01:835 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [321] bytes CANCEL sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.O9kPHB30~;rport Call-ID: 0pCKDVgtDG From: ;tag=UzN0yUYRr To: sip:60003@10.1.61.7 Max-Forwards: 70 CSeq: 21 CANCEL User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 2023-02-15 11:42:01:875 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [329] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 200 canceling Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.O9kPHB30~;rport=5060;received=10.25.20.62 Call-ID: 0pCKDVgtDG From: ;tag=UzN0yUYRr To: sip:60003@10.1.61.7;tag=5c46d2e780a7932142ddfe457a3280bb-b8c9c95b CSeq: 21 CANCEL Server: kamailio (5.6.3 (x86_64/linux)) Content-Length: 0 2023-02-15 11:42:01:876 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [329] bytes parsed 2023-02-15 11:42:01:876 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:01:876 [/belle-sip] MESSAGE Changing [client] [CANCEL] transaction [000001C1857D8100], from state [TRYING] to [COMPLETED] 2023-02-15 11:42:01:876 [/liblinphone] MESSAGE op [000001C185B5E770] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:42:01:876 [/liblinphone] MESSAGE Op [000001C185B5E770] receiving call response [200], dialog is [0000000000000000] in state [BELLE_SIP_DIALOG_NULL] 2023-02-15 11:42:01:876 [/liblinphone] WARNING Cancel done reported on already terminated CallSession 2023-02-15 11:42:01:898 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [481] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.25.20.62:5060;rport=5060;received=10.25.20.62;branch=z9hG4bK.YCuJBwHoe Call-ID: 9rc6jfkcLk From: ;tag=sRJ3shbOh To: ;tag=z9hG4bK52e2.ff2821419fd467be92911a28534346b9.2 CSeq: 24 REGISTER Date: Wed, 15 Feb 2023 11:42:05 GMT Contact: ;expires=3599 Expires: 3600 Supported: path Path: Server: pbx Content-Length: 0 2023-02-15 11:42:01:898 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [481] bytes parsed 2023-02-15 11:42:01:899 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:01:899 [/belle-sip] MESSAGE Changing [client] [REGISTER] transaction [000001C185810800], from state [TRYING] to [COMPLETED] 2023-02-15 11:42:01:899 [/belle-sip] MESSAGE Refresher [000001C1F8B13ED0]: has no contact for request [000001C185BF18F0]. 2023-02-15 11:42:01:899 [/belle-sip] MESSAGE Refresher[000001C1F8B13ED0]: scheduling next timer in 3239100 ms for purpose [normal refresh] 2023-02-15 11:42:01:899 [/liblinphone] MESSAGE Register refresher [200] reason [OK] for proxy [] 2023-02-15 11:42:01:899 [/liblinphone] MESSAGE Account [000001C1F9C5D4D0] for identity [sip:60006@10.1.61.7] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] on core [000001C1F1E70EB0] 2023-02-15 11:42:01:899 [/liblinphone] MESSAGE Updating friends for identity [sip:60006@10.1.61.7] on core [000001C1F1E70EB0] 2023-02-15 11:42:01:899 [/liblinphone] MESSAGE updateDependentAccount(): 000001C1F9C5D4D0 is registered, checking for [000001C1F9C5D4D8] ->dependency=0000000000000000 2023-02-15 11:42:01:899 [/qt] MESSAGE QT: "Notify event count: 0." 2023-02-15 11:42:01:900 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [account_registration_state_changed] 2023-02-15 11:42:01:900 [/liblinphone] WARNING [LIME] No LIME server URL in account params, trying to fallback on Core's default LIME server URL [] 2023-02-15 11:42:01:900 [/liblinphone] WARNING [LIME] Server URL unavailable for encryption engine: can't create user 2023-02-15 11:42:01:900 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [registration_state_changed] 2023-02-15 11:42:01:930 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [745] new bytes from [UDP://10.1.61.7:5060]: NOTIFY sip:60006@10.25.20.62:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 10.1.61.7;branch=z9hG4bK0b23.1b9d36ea51145c0c54e5eb0de37432b7.0 Via: SIP/2.0/UDP 10.1.61.4:5060;received=10.1.61.4;rport=5060;branch=z9hG4bKPj0f2a8b83-6961-4a8f-b87c-4cc83c7f0c2f From: ;tag=2712e088-093b-4b27-9670-150344d1fdf1 To: Contact: Call-ID: 230fbffa-3e6f-4b15-8c84-926c22843174 CSeq: 48172 NOTIFY Subscription-State: terminated Event: message-summary Allow-Events: message-summary, presence, dialog, refer Supported: path Max-Forwards: 69 User-Agent: pbx Content-Type: application/simple-message-summary Content-Length: 48 Messages-Waiting: no Voice-Message: 0/0 (0/0) 2023-02-15 11:42:01:931 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [697] bytes parsed 2023-02-15 11:42:01:931 [/belle-sip] MESSAGE channel [000001C1FC389ED0] read [48] bytes of body from [10.1.61.7:5060] 2023-02-15 11:42:01:931 [/belle-sip] MESSAGE Changing [server] [NOTIFY] transaction [000001C1855453D0], from state [INIT] to [TRYING] 2023-02-15 11:42:01:931 [/liblinphone] MESSAGE Outgoing subscription terminated by remote [sip:60006@10.25.20.62] 2023-02-15 11:42:01:932 [/liblinphone] MESSAGE LinphoneEvent [000001C1857D7440] moving to subscription state LinphoneSubscriptionTerminated 2023-02-15 11:42:01:932 [/belle-sip] MESSAGE Changing [server] [NOTIFY] transaction [000001C1855453D0], from state [TRYING] to [COMPLETED] 2023-02-15 11:42:01:932 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [544] bytes SIP/2.0 200 Ok Via: SIP/2.0/UDP 10.1.61.7;branch=z9hG4bK0b23.1b9d36ea51145c0c54e5eb0de37432b7.0 Via: SIP/2.0/UDP 10.1.61.4:5060;received=10.1.61.4;rport=5060;branch=z9hG4bKPj0f2a8b83-6961-4a8f-b87c-4cc83c7f0c2f From: ;tag=2712e088-093b-4b27-9670-150344d1fdf1 To: ;tag=vbxbpov Call-ID: 230fbffa-3e6f-4b15-8c84-926c22843174 CSeq: 48172 NOTIFY User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 Supported: replaces, outbound, gruu, path, record-aware 2023-02-15 11:42:01:932 [/liblinphone] MESSAGE Destroying op [000001C1FEDF5AB0] of type [SalOpUnknown] 2023-02-15 11:42:01:961 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [368] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 10.25.20.62:5060;rport=5060;received=10.25.20.62;branch=z9hG4bK.O9kPHB30~ Record-Route: Call-ID: 0pCKDVgtDG From: ;tag=UzN0yUYRr To: ;tag=4d94096c-bdbd-4bee-bb87-bfbf55dc3171 CSeq: 21 INVITE Server: pbx Content-Length: 0 2023-02-15 11:42:01:961 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [368] bytes parsed 2023-02-15 11:42:01:961 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:01:961 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C184320DD0], from state [PROCEEDING] to [COMPLETED] 2023-02-15 11:42:01:961 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [331] bytes ACK sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.O9kPHB30~;rport Call-ID: 0pCKDVgtDG From: ;tag=UzN0yUYRr To: ;tag=4d94096c-bdbd-4bee-bb87-bfbf55dc3171 Contact: ;expires=3599 Max-Forwards: 70 CSeq: 21 ACK 2023-02-15 11:42:01:962 [/liblinphone] MESSAGE op [000001C185B5E770] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:42:01:962 [/liblinphone] MESSAGE Op [000001C185B5E770] receiving call response [487], dialog is [0000000000000000] in state [BELLE_SIP_DIALOG_NULL] 2023-02-15 11:42:01:962 [/liblinphone] MESSAGE op [000001C185B5E770] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:42:01:992 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [745] new bytes from [UDP://10.1.61.7:5060]: NOTIFY sip:60006@10.25.20.62:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 10.1.61.7;branch=z9hG4bK8585.1c72187210b6861418cc2b80be4f3a9e.0 Via: SIP/2.0/UDP 10.1.61.3:5060;received=10.1.61.3;rport=5060;branch=z9hG4bKPjecd2660c-5df9-4d47-9c7a-3e7592766a3c From: ;tag=58a48591-54ea-4fe6-942d-332376dfeb5b To: Contact: Call-ID: bba74355-4784-4701-bfbe-24febd5e0721 CSeq: 52202 NOTIFY Subscription-State: terminated Event: message-summary Allow-Events: message-summary, presence, dialog, refer Supported: path Max-Forwards: 69 User-Agent: pbx Content-Type: application/simple-message-summary Content-Length: 48 Messages-Waiting: no Voice-Message: 0/0 (0/0) 2023-02-15 11:42:01:994 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [697] bytes parsed 2023-02-15 11:42:01:994 [/belle-sip] MESSAGE channel [000001C1FC389ED0] read [48] bytes of body from [10.1.61.7:5060] 2023-02-15 11:42:01:994 [/belle-sip] MESSAGE Changing [server] [NOTIFY] transaction [000001C185543B70], from state [INIT] to [TRYING] 2023-02-15 11:42:01:994 [/liblinphone] MESSAGE Outgoing subscription terminated by remote [sip:60006@10.25.20.62] 2023-02-15 11:42:01:994 [/liblinphone] MESSAGE LinphoneEvent [000001C1857D75C0] moving to subscription state LinphoneSubscriptionTerminated 2023-02-15 11:42:01:994 [/belle-sip] MESSAGE Changing [server] [NOTIFY] transaction [000001C185543B70], from state [TRYING] to [COMPLETED] 2023-02-15 11:42:01:994 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [544] bytes SIP/2.0 200 Ok Via: SIP/2.0/UDP 10.1.61.7;branch=z9hG4bK8585.1c72187210b6861418cc2b80be4f3a9e.0 Via: SIP/2.0/UDP 10.1.61.3:5060;received=10.1.61.3;rport=5060;branch=z9hG4bKPjecd2660c-5df9-4d47-9c7a-3e7592766a3c From: ;tag=58a48591-54ea-4fe6-942d-332376dfeb5b To: ;tag=zfuCWdd Call-ID: bba74355-4784-4701-bfbe-24febd5e0721 CSeq: 52202 NOTIFY User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 Supported: replaces, outbound, gruu, path, record-aware 2023-02-15 11:42:01:994 [/liblinphone] MESSAGE Destroying op [000001C1FEDF37A0] of type [SalOpUnknown] 2023-02-15 11:42:03:036 [/liblinphone] MESSAGE New MediaSession [000001C1858DDA30] initialized (liblinphone version: 5.2.15) 2023-02-15 11:42:03:036 [/liblinphone] MESSAGE Media local-ip for streams advertised in SDP: 10.25.20.62 2023-02-15 11:42:03:036 [/liblinphone] MESSAGE Rtp bundle is enabled. 2023-02-15 11:42:03:036 [/liblinphone] MESSAGE Found media local-ip from signaling: 10.25.20.62 2023-02-15 11:42:03:037 [/liblinphone] MESSAGE [LIME] C:\GitLab-Runner\builds\hexP7jis\0\BC\public\linphone-desktop\linphone-sdk\lime\src\lime_localStorage.cpp:255 Cannot find Lime User sip:60006@10.25.20.62 in DB while setting up identity key for ZRTP auxiliary secret 2023-02-15 11:42:03:037 [/liblinphone] MESSAGE stream#0 [audio] in state [Stopped]: multicast role is [inactive] 2023-02-15 11:42:03:037 [/ortp] MESSAGE RtpSession bound to [::0] ports [36600] [36601] 2023-02-15 11:42:03:037 [/liblinphone] MESSAGE Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80 2023-02-15 11:42:03:037 [/liblinphone] MESSAGE Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32 2023-02-15 11:42:03:037 [/liblinphone] MESSAGE Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80 2023-02-15 11:42:03:038 [/liblinphone] MESSAGE Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32 2023-02-15 11:42:03:038 [/liblinphone] MESSAGE Configured srtp crypto suite: AEAD_AES_128_GCM 2023-02-15 11:42:03:038 [/liblinphone] MESSAGE Configured srtp crypto suite: AEAD_AES_256_GCM 2023-02-15 11:42:03:038 [/liblinphone] MESSAGE Configured zrtp key agreement: 'MS_ZRTP_KEY_AGREEMENT_K255_KYB512' 2023-02-15 11:42:03:038 [/mediastreamer] MESSAGE Creating ZRTP engine on rtp session [000001C1FBEE28F0] ssrc 0x72b4fbbb 2023-02-15 11:42:03:038 [/ortp] MESSAGE rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2023-02-15 11:42:03:038 [/mediastreamer] MESSAGE Setting DSCP to 46 for MSAudio stream. 2023-02-15 11:42:03:038 [/ortp] MESSAGE check OS support for qwave.lib 2023-02-15 11:42:03:038 [/ortp] ERROR QOSAddSocketToFlow failed to add a flow with error 87 2023-02-15 11:42:03:038 [/liblinphone] MESSAGE Created stream of type audio at index 0: stream#0 [audio] in state [Stopped] 2023-02-15 11:42:03:038 [/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. 2023-02-15 11:42:03:039 [/liblinphone] MESSAGE [MS2AudioStream] setting type of soundcard 000001C1F3182B80 to voice 2023-02-15 11:42:03:039 [/liblinphone] MESSAGE [MS2AudioStream] setting type of soundcard 000001C1F3182400 to voice 2023-02-15 11:42:03:039 [/liblinphone] MESSAGE [ToneManager] stopDtmfStream 2023-02-15 11:42:03:039 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_created] 2023-02-15 11:42:03:039 [/liblinphone] MESSAGE CallSession [000001C1858DDA30] moving from state LinphoneCallIdle to LinphoneCallOutgoingInit 2023-02-15 11:42:03:039 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [first_call_started] 2023-02-15 11:42:03:039 [/qt] MESSAGE QT: "Add call:" "sip:60006@10.1.61.7" "sip:60003@10.1.61.7" 2023-02-15 11:42:03:047 [/liblinphone] WARNING Couldn't find supported video definition for 0x0 2023-02-15 11:42:03:081 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_state_changed] 2023-02-15 11:42:03:081 [/liblinphone] MESSAGE [ToneManager] stopDtmfStream 2023-02-15 11:42:03:081 [/liblinphone] MESSAGE ICE state is IceStateNotActivated for stream#0 [audio] in state [Stopped] 2023-02-15 11:42:03:081 [/liblinphone] MESSAGE Contact has been fixed using proxy 2023-02-15 11:42:03:082 [/liblinphone] MESSAGE Setting contact address for session 000001C1847F38F0 to ;expires=3599 2023-02-15 11:42:03:082 [/liblinphone] MESSAGE [sip:60006@10.1.61.7] calling [sip:60003@10.1.61.7] on op [000001C185B5D090] 2023-02-15 11:42:03:082 [/liblinphone] MESSAGE Skipping top route of initial route-set because same as request-uri 2023-02-15 11:42:03:082 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185EFA740], from state [INIT] to [CALLING] 2023-02-15 11:42:03:082 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [964] bytes INVITE sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.NvUMDrUsU;rport From: ;tag=fiIrqvWTt To: sip:60003@10.1.61.7 CSeq: 20 INVITE Call-ID: kFsjotAXCe Max-Forwards: 70 Supported: replaces, outbound, gruu, path, record-aware Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, PRACK, UPDATE Content-Type: application/sdp Content-Length: 370 Contact: ;expires=3599 User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 v=0 o=60006 3567 3200 IN IP4 10.25.20.62 s=Talk c=IN IP4 10.25.20.62 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics a=group:BUNDLE as a=record:off m=audio 36600 RTP/AVP 8 0 96 a=rtpmap:96 telephone-event/8000 a=rtcp-mux a=mid:as a=extmap:1 urn:ietf:params:rtp-hdrext:sdes:mid a=rtcp-fb:* trr-int 1000 a=rtcp-fb:* ccm tmmbr 2023-02-15 11:42:03:082 [/liblinphone] MESSAGE CallSession [000001C1858DDA30] moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress 2023-02-15 11:42:03:082 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_state_changed] 2023-02-15 11:42:03:082 [/liblinphone] MESSAGE MainDb::addEvent() of type ConferenceCallStarted (value 3) 2023-02-15 11:42:03:083 [/liblinphone] MESSAGE Insert new conference call in database: kFsjotAXCe 2023-02-15 11:42:03:094 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_log_updated] 2023-02-15 11:42:03:128 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [312] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.NvUMDrUsU;rport=5060;received=10.25.20.62 From: ;tag=fiIrqvWTt To: sip:60003@10.1.61.7 CSeq: 20 INVITE Call-ID: kFsjotAXCe Server: kamailio (5.6.3 (x86_64/linux)) Content-Length: 0 2023-02-15 11:42:03:129 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [312] bytes parsed 2023-02-15 11:42:03:129 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:03:129 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185EFA740], from state [CALLING] to [PROCEEDING] 2023-02-15 11:42:03:129 [/liblinphone] MESSAGE op [000001C185B5D090] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:42:03:129 [/liblinphone] MESSAGE Op [000001C185B5D090] receiving call response [100], dialog is [0000000000000000] in state [BELLE_SIP_DIALOG_NULL] 2023-02-15 11:42:03:146 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [521] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.25.20.62:5060;rport=5060;received=10.25.20.62;branch=z9hG4bK.NvUMDrUsU Record-Route: Call-ID: kFsjotAXCe From: ;tag=fiIrqvWTt To: ;tag=z9hG4bKa176.2345f4595a7504fd57f3990115d5f99c.0 CSeq: 20 INVITE WWW-Authenticate: Digest realm="10.1.61.4",nonce="1676461326/273096f266c835b072d60f623dd8e121",opaque="4a79833a22c00a5a",algorithm=MD5,qop="auth" Server: pbx Content-Length: 0 2023-02-15 11:42:03:147 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [521] bytes parsed 2023-02-15 11:42:03:147 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:03:147 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185EFA740], from state [PROCEEDING] to [COMPLETED] 2023-02-15 11:42:03:147 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [341] bytes ACK sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.NvUMDrUsU;rport Call-ID: kFsjotAXCe From: ;tag=fiIrqvWTt To: ;tag=z9hG4bKa176.2345f4595a7504fd57f3990115d5f99c.0 Contact: ;expires=3599 Max-Forwards: 70 CSeq: 20 ACK 2023-02-15 11:42:03:147 [/liblinphone] MESSAGE AuthRequested pushed 2023-02-15 11:42:03:147 [/belle-sip] MESSAGE No auth info found for call id [kFsjotAXCe] 2023-02-15 11:42:03:147 [/liblinphone] MESSAGE No auth info found for [sip:60006@10.1.61.7] 2023-02-15 11:42:03:147 [/liblinphone] MESSAGE Op 000001C185B5D090 added as pending authentication 2023-02-15 11:42:03:147 [/liblinphone] MESSAGE [Magic Search] Found 0 results in friends 2023-02-15 11:42:03:190 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [authentication_requested] 2023-02-15 11:42:04:076 [/liblinphone] MESSAGE Terminate CallSession [000001C1858DDA30] which is currently in state [LinphoneCallOutgoingProgress] 2023-02-15 11:42:04:076 [/belle-sip] MESSAGE ICT retransmissions stopped 2023-02-15 11:42:04:076 [/liblinphone] MESSAGE [ToneManager] startErrorTone 2023-02-15 11:42:04:076 [/liblinphone] MESSAGE [ToneManager] playTone 2023-02-15 11:42:04:113 [/bctbx] MESSAGE MSWASAPI playback output initialized for [Наушники гарнитуры (Plantronics C720-M)--{0.0.0.00000000}.{f6bd33e5-fc39-4f07-95f6-ad1ae778399a}] at 48000 Hz, 2 channels, with buffer size 9600 (200 ms), device period is 10, 16-bit frames are on 32 bits 2023-02-15 11:42:04:113 [/mediastreamer] MESSAGE speex_lib_ctl init with neon ? 0 2023-02-15 11:42:04:113 [/mediastreamer] MESSAGE configuring resampler output to rate=[48000], nchannels=[2] 2023-02-15 11:42:04:114 [/mediastreamer] MESSAGE ms_filter_link: MSFilePlayer:000001C185651FD0,0-->MSDtmfGen:000001C185651BE0,0 2023-02-15 11:42:04:114 [/mediastreamer] MESSAGE ms_filter_link: MSDtmfGen:000001C185651BE0,0-->MSResample:000001C1856524A0,0 2023-02-15 11:42:04:114 [/mediastreamer] MESSAGE ms_filter_link: MSResample:000001C1856524A0,0-->MSWASAPIWrite:000001C185652040,0 2023-02-15 11:42:04:114 [/mediastreamer] MESSAGE Initializing speex resampler in mode [voip] 2023-02-15 11:42:04:114 [/bctbx] WARNING bctbx_set_self_thread_name(): not implemented. 2023-02-15 11:42:04:114 [/mediastreamer] MESSAGE win32 timer resolution set to 2 ms 2023-02-15 11:42:04:114 [/mediastreamer] MESSAGE Playing tones of frequencies 480,0 Hz, duration=200, amplitude=0.500000 interval=200, repeat_count=2 2023-02-15 11:42:04:114 [/liblinphone] MESSAGE CallSession [000001C1858DDA30] moving from state LinphoneCallOutgoingProgress to LinphoneCallEnd 2023-02-15 11:42:04:114 [/liblinphone] MESSAGE StreamsGroup::finish() called. 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE ================================================================================= 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE FILTER USAGE STATISTICS 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE Name Count Time/tick (ms) CPU Usage 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE min mean max sd 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE --------------------------------------------------------------------------------- 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE MSOpusDec 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE MSMKVPlayer 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE MSResample 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE MSWASAPIWrite 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE MSDtmfGen 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE MSFilePlayer 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE MSWebRTCAEC 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE MSRtpSend 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE ================================================================================= 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE ================================================================================= 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE FILTER USAGE STATISTICS 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE Name Count Time/tick (ms) CPU Usage 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE min mean max sd 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE --------------------------------------------------------------------------------- 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE MSOpusDec 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE MSMKVPlayer 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE MSResample 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE MSWASAPIWrite 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE MSDtmfGen 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE MSFilePlayer 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE MSWebRTCAEC 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE MSRtpSend 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE ================================================================================= 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE Stopping ZRTP context on session [0000000000000000] 2023-02-15 11:42:04:115 [/mediastreamer] MESSAGE ZRTP context destroyed 2023-02-15 11:42:04:116 [/liblinphone] MESSAGE MainDb::addEvent() of type ConferenceCallEnded (value 4) 2023-02-15 11:42:04:116 [/liblinphone] MESSAGE Update conference call in database: kFsjotAXCe 2023-02-15 11:42:04:122 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_log_updated] 2023-02-15 11:42:04:122 [/liblinphone] MESSAGE Resetting the current call 2023-02-15 11:42:04:122 [/liblinphone] MESSAGE Removing the call (local address sip:60006@10.1.61.7 remote address sip:60003@10.1.61.7) from the list attached to the core 2023-02-15 11:42:04:122 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [last_call_ended] 2023-02-15 11:42:04:149 [/qt] MESSAGE QT: "Removing " CallModel " : " CallModel(0x1c18566a230) 2023-02-15 11:42:04:150 [/qt] MESSAGE QT: "Update (`sip:60003@10.1.61.7`, `sip:60006@10.1.61.7`) from chat call." 2023-02-15 11:42:04:150 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_state_changed] 2023-02-15 11:42:04:150 [/liblinphone] MESSAGE CallSession [000001C1858DDA30] moving from state LinphoneCallEnd to LinphoneCallReleased 2023-02-15 11:42:04:150 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_state_changed] 2023-02-15 11:42:04:188 [/qt] MESSAGE QT: "Notify event count: 0." 2023-02-15 11:42:04:191 [/qt] MESSAGE QT: "Notify event count: 0." 2023-02-15 11:42:05:116 [/liblinphone] MESSAGE RingStream no longer needed. 2023-02-15 11:42:05:116 [/liblinphone] MESSAGE [ToneManager] destroyRingStream 2023-02-15 11:42:05:117 [/mediastreamer] MESSAGE ms_filter_unlink: MSFilePlayer:000001C185651FD0,0-->MSDtmfGen:000001C185651BE0,0 2023-02-15 11:42:05:117 [/mediastreamer] MESSAGE ms_filter_unlink: MSDtmfGen:000001C185651BE0,0-->MSResample:000001C1856524A0,0 2023-02-15 11:42:05:117 [/mediastreamer] MESSAGE ms_filter_unlink: MSResample:000001C1856524A0,0-->MSWASAPIWrite:000001C185652040,0 2023-02-15 11:42:05:125 [/mediastreamer] MESSAGE Ring MSTicker thread exiting 2023-02-15 11:42:06:871 [/belle-sip] MESSAGE Changing [client] [CANCEL] transaction [000001C1857D8100], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:42:06:871 [/belle-sip] MESSAGE Client CANCEL transaction [000001C1857D8100] terminated 2023-02-15 11:42:06:900 [/belle-sip] MESSAGE Changing [client] [REGISTER] transaction [000001C185810800], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:42:06:900 [/belle-sip] MESSAGE Client internal REGISTER transaction [000001C185810800] terminated 2023-02-15 11:42:07:877 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.3 2023-02-15 11:42:07:877 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.4 2023-02-15 11:42:07:877 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.4 2023-02-15 11:42:07:877 [/liblinphone] MESSAGE AuthStack::authFound() for Username[60006];Userid[];Realm[10.1.61.4];Domain[10.1.61.7];Algorithm[];AvailableAlgorithms[]; 2023-02-15 11:42:07:877 [/belle-sip] MESSAGE Auth info found for [60006] realm [10.1.61.4] 2023-02-15 11:42:07:877 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185EE8050], from state [INIT] to [CALLING] 2023-02-15 11:42:07:877 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [1242] bytes INVITE sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.Yf5HcLj7k;rport From: ;tag=fiIrqvWTt To: sip:60003@10.1.61.7 CSeq: 21 INVITE Call-ID: kFsjotAXCe Max-Forwards: 70 Supported: replaces, outbound, gruu, path, record-aware Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, PRACK, UPDATE Content-Type: application/sdp Content-Length: 370 Contact: ;expires=3599 User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 Authorization: Digest realm="10.1.61.4", nonce="1676461326/273096f266c835b072d60f623dd8e121", algorithm=MD5, opaque="4a79833a22c00a5a", username="60006", uri="sip:60003@10.1.61.7", response="67e6d070f519032c2b8722f0d264ee5d", cnonce="4PBA~NxfRXTGqrRC", nc=00000001, qop=auth v=0 o=60006 3567 3200 IN IP4 10.25.20.62 s=Talk c=IN IP4 10.25.20.62 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics a=group:BUNDLE as a=record:off m=audio 36600 RTP/AVP 8 0 96 a=rtpmap:96 telephone-event/8000 a=rtcp-mux a=mid:as a=extmap:1 urn:ietf:params:rtp-hdrext:sdes:mid a=rtcp-fb:* trr-int 1000 a=rtcp-fb:* ccm tmmbr 2023-02-15 11:42:07:877 [/liblinphone] MESSAGE Op 000001C185B5D090 removed as pending authentication 2023-02-15 11:42:07:877 [/liblinphone] MESSAGE linphone_core_add_auth_info(): restarted [1] operation(s) after updating auth info for username: [60006] realm [10.1.61.4] domain [10.1.61.7] 2023-02-15 11:42:07:899 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [312] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.Yf5HcLj7k;rport=5060;received=10.25.20.62 From: ;tag=fiIrqvWTt To: sip:60003@10.1.61.7 CSeq: 21 INVITE Call-ID: kFsjotAXCe Server: kamailio (5.6.3 (x86_64/linux)) Content-Length: 0 2023-02-15 11:42:07:899 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [312] bytes parsed 2023-02-15 11:42:07:899 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:07:900 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185EE8050], from state [CALLING] to [PROCEEDING] 2023-02-15 11:42:07:900 [/liblinphone] MESSAGE op [000001C185B5D090] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:42:07:900 [/liblinphone] MESSAGE Op [000001C185B5D090] receiving call response [100], dialog is [0000000000000000] in state [BELLE_SIP_DIALOG_NULL] 2023-02-15 11:42:07:900 [/liblinphone] MESSAGE Cancelling INVITE request from [sip:60006@10.1.61.7] to [sip:60003@10.1.61.7] 2023-02-15 11:42:07:900 [/belle-sip] MESSAGE Found transaction [000001C185EE8050] matching request. 2023-02-15 11:42:07:900 [/belle-sip] MESSAGE Changing [client] [CANCEL] transaction [000001C1F88E7E60], from state [INIT] to [TRYING] 2023-02-15 11:42:07:900 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [321] bytes CANCEL sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.Yf5HcLj7k;rport Call-ID: kFsjotAXCe From: ;tag=fiIrqvWTt To: sip:60003@10.1.61.7 Max-Forwards: 70 CSeq: 21 CANCEL User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 2023-02-15 11:42:07:931 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [521] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.25.20.62:5060;rport=5060;received=10.25.20.62;branch=z9hG4bK.Yf5HcLj7k Record-Route: Call-ID: kFsjotAXCe From: ;tag=fiIrqvWTt To: ;tag=z9hG4bKb176.032b7c9cd86117ff8bfbf544b0f670a1.0 CSeq: 21 INVITE WWW-Authenticate: Digest realm="10.1.61.3",nonce="1676461317/7fdef0fa7b1900003cf89da37b32a9cd",opaque="0f109d3b080cb725",algorithm=MD5,qop="auth" Server: pbx Content-Length: 0 2023-02-15 11:42:07:931 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [521] bytes parsed 2023-02-15 11:42:07:931 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:07:931 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185EE8050], from state [PROCEEDING] to [COMPLETED] 2023-02-15 11:42:07:932 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [341] bytes ACK sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.Yf5HcLj7k;rport Call-ID: kFsjotAXCe From: ;tag=fiIrqvWTt To: ;tag=z9hG4bKb176.032b7c9cd86117ff8bfbf544b0f670a1.0 Contact: ;expires=3599 Max-Forwards: 70 CSeq: 21 ACK 2023-02-15 11:42:07:932 [/liblinphone] MESSAGE Op is in state terminating, nothing else to do 2023-02-15 11:42:07:932 [/liblinphone] MESSAGE op [000001C185B5D090] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:42:07:932 [/liblinphone] MESSAGE Op [000001C185B5D090] receiving call response [401], dialog is [0000000000000000] in state [BELLE_SIP_DIALOG_NULL] 2023-02-15 11:42:07:932 [/liblinphone] MESSAGE op [000001C185B5D090] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:42:07:961 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [350] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 200 ok -- no more pending branches Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.Yf5HcLj7k;rport=5060;received=10.25.20.62 Call-ID: kFsjotAXCe From: ;tag=fiIrqvWTt To: sip:60003@10.1.61.7;tag=5c46d2e780a7932142ddfe457a3280bb-bd34c856 CSeq: 21 CANCEL Server: kamailio (5.6.3 (x86_64/linux)) Content-Length: 0 2023-02-15 11:42:07:961 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [350] bytes parsed 2023-02-15 11:42:07:961 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:07:961 [/belle-sip] MESSAGE Changing [client] [CANCEL] transaction [000001C1F88E7E60], from state [TRYING] to [COMPLETED] 2023-02-15 11:42:07:961 [/liblinphone] MESSAGE Op [000001C185B5D090] is terminated, nothing to do with this [200] 2023-02-15 11:42:09:060 [/liblinphone] MESSAGE New MediaSession [000001C185DE6370] initialized (liblinphone version: 5.2.15) 2023-02-15 11:42:09:060 [/liblinphone] MESSAGE Media local-ip for streams advertised in SDP: 10.25.20.62 2023-02-15 11:42:09:060 [/liblinphone] MESSAGE Rtp bundle is enabled. 2023-02-15 11:42:09:060 [/liblinphone] MESSAGE Found media local-ip from signaling: 10.25.20.62 2023-02-15 11:42:09:061 [/liblinphone] MESSAGE [LIME] C:\GitLab-Runner\builds\hexP7jis\0\BC\public\linphone-desktop\linphone-sdk\lime\src\lime_localStorage.cpp:255 Cannot find Lime User sip:60006@10.25.20.62 in DB while setting up identity key for ZRTP auxiliary secret 2023-02-15 11:42:09:061 [/liblinphone] MESSAGE stream#0 [audio] in state [Stopped]: multicast role is [inactive] 2023-02-15 11:42:09:061 [/ortp] MESSAGE RtpSession bound to [::0] ports [36600] [36601] 2023-02-15 11:42:09:061 [/liblinphone] MESSAGE Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80 2023-02-15 11:42:09:061 [/liblinphone] MESSAGE Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32 2023-02-15 11:42:09:061 [/liblinphone] MESSAGE Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80 2023-02-15 11:42:09:061 [/liblinphone] MESSAGE Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32 2023-02-15 11:42:09:061 [/liblinphone] MESSAGE Configured srtp crypto suite: AEAD_AES_128_GCM 2023-02-15 11:42:09:061 [/liblinphone] MESSAGE Configured srtp crypto suite: AEAD_AES_256_GCM 2023-02-15 11:42:09:061 [/liblinphone] MESSAGE Configured zrtp key agreement: 'MS_ZRTP_KEY_AGREEMENT_K255_KYB512' 2023-02-15 11:42:09:061 [/mediastreamer] MESSAGE Creating ZRTP engine on rtp session [000001C1FBEE28F0] ssrc 0xcd19175d 2023-02-15 11:42:09:062 [/ortp] MESSAGE rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2023-02-15 11:42:09:062 [/mediastreamer] MESSAGE Setting DSCP to 46 for MSAudio stream. 2023-02-15 11:42:09:062 [/ortp] MESSAGE check OS support for qwave.lib 2023-02-15 11:42:09:062 [/ortp] ERROR QOSAddSocketToFlow failed to add a flow with error 87 2023-02-15 11:42:09:062 [/liblinphone] MESSAGE Created stream of type audio at index 0: stream#0 [audio] in state [Stopped] 2023-02-15 11:42:09:062 [/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. 2023-02-15 11:42:09:062 [/liblinphone] MESSAGE [MS2AudioStream] setting type of soundcard 000001C1F3182B80 to voice 2023-02-15 11:42:09:062 [/liblinphone] MESSAGE [MS2AudioStream] setting type of soundcard 000001C1F3182400 to voice 2023-02-15 11:42:09:062 [/liblinphone] MESSAGE [ToneManager] stopDtmfStream 2023-02-15 11:42:09:062 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_created] 2023-02-15 11:42:09:062 [/liblinphone] MESSAGE CallSession [000001C185DE6370] moving from state LinphoneCallIdle to LinphoneCallOutgoingInit 2023-02-15 11:42:09:062 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [first_call_started] 2023-02-15 11:42:09:063 [/qt] MESSAGE QT: "Add call:" "sip:60006@10.1.61.7" "sip:60003@10.1.61.7" 2023-02-15 11:42:09:071 [/liblinphone] WARNING Couldn't find supported video definition for 0x0 2023-02-15 11:42:09:105 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_state_changed] 2023-02-15 11:42:09:106 [/liblinphone] MESSAGE [ToneManager] stopDtmfStream 2023-02-15 11:42:09:106 [/liblinphone] MESSAGE ICE state is IceStateNotActivated for stream#0 [audio] in state [Stopped] 2023-02-15 11:42:09:106 [/liblinphone] MESSAGE Contact has been fixed using proxy 2023-02-15 11:42:09:106 [/liblinphone] MESSAGE Setting contact address for session 000001C1847F38F0 to ;expires=3599 2023-02-15 11:42:09:106 [/liblinphone] MESSAGE [sip:60006@10.1.61.7] calling [sip:60003@10.1.61.7] on op [000001C185B5EB40] 2023-02-15 11:42:09:106 [/liblinphone] MESSAGE Skipping top route of initial route-set because same as request-uri 2023-02-15 11:42:09:106 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185F0B6A0], from state [INIT] to [CALLING] 2023-02-15 11:42:09:107 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [963] bytes INVITE sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.QOrLfUfOi;rport From: ;tag=LADrsozib To: sip:60003@10.1.61.7 CSeq: 20 INVITE Call-ID: x8mLe72GJE Max-Forwards: 70 Supported: replaces, outbound, gruu, path, record-aware Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, PRACK, UPDATE Content-Type: application/sdp Content-Length: 369 Contact: ;expires=3599 User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 v=0 o=60006 1208 442 IN IP4 10.25.20.62 s=Talk c=IN IP4 10.25.20.62 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics a=group:BUNDLE as a=record:off m=audio 36600 RTP/AVP 8 0 96 a=rtpmap:96 telephone-event/8000 a=rtcp-mux a=mid:as a=extmap:1 urn:ietf:params:rtp-hdrext:sdes:mid a=rtcp-fb:* trr-int 1000 a=rtcp-fb:* ccm tmmbr 2023-02-15 11:42:09:107 [/liblinphone] MESSAGE CallSession [000001C185DE6370] moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress 2023-02-15 11:42:09:107 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_state_changed] 2023-02-15 11:42:09:107 [/liblinphone] MESSAGE MainDb::addEvent() of type ConferenceCallStarted (value 3) 2023-02-15 11:42:09:107 [/liblinphone] MESSAGE Insert new conference call in database: x8mLe72GJE 2023-02-15 11:42:09:123 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_log_updated] 2023-02-15 11:42:09:156 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [312] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.QOrLfUfOi;rport=5060;received=10.25.20.62 From: ;tag=LADrsozib To: sip:60003@10.1.61.7 CSeq: 20 INVITE Call-ID: x8mLe72GJE Server: kamailio (5.6.3 (x86_64/linux)) Content-Length: 0 2023-02-15 11:42:09:157 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [312] bytes parsed 2023-02-15 11:42:09:157 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:09:157 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185F0B6A0], from state [CALLING] to [PROCEEDING] 2023-02-15 11:42:09:157 [/liblinphone] MESSAGE op [000001C185B5EB40] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:42:09:157 [/liblinphone] MESSAGE Op [000001C185B5EB40] receiving call response [100], dialog is [0000000000000000] in state [BELLE_SIP_DIALOG_NULL] 2023-02-15 11:42:09:184 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [521] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.25.20.62:5060;rport=5060;received=10.25.20.62;branch=z9hG4bK.QOrLfUfOi Record-Route: Call-ID: x8mLe72GJE From: ;tag=LADrsozib To: ;tag=z9hG4bKafec.3bce25af195436f36e344d7dda838672.0 CSeq: 20 INVITE WWW-Authenticate: Digest realm="10.1.61.4",nonce="1676461332/51c720608b72d4e8b9b7e27892ccd3a4",opaque="1343481c2a4134ce",algorithm=MD5,qop="auth" Server: pbx Content-Length: 0 2023-02-15 11:42:09:185 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [521] bytes parsed 2023-02-15 11:42:09:185 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:09:185 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185F0B6A0], from state [PROCEEDING] to [COMPLETED] 2023-02-15 11:42:09:185 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [341] bytes ACK sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.QOrLfUfOi;rport Call-ID: x8mLe72GJE From: ;tag=LADrsozib To: ;tag=z9hG4bKafec.3bce25af195436f36e344d7dda838672.0 Contact: ;expires=3599 Max-Forwards: 70 CSeq: 20 ACK 2023-02-15 11:42:09:185 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.4 2023-02-15 11:42:09:185 [/liblinphone] MESSAGE AuthStack::authFound() for Username[60006];Userid[];Realm[10.1.61.4];Domain[10.1.61.7];Algorithm[];AvailableAlgorithms[]; 2023-02-15 11:42:09:185 [/belle-sip] MESSAGE Auth info found for [60006] realm [10.1.61.4] 2023-02-15 11:42:09:185 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C184327AB0], from state [INIT] to [CALLING] 2023-02-15 11:42:09:186 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [1241] bytes INVITE sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.WLLk2qFAQ;rport From: ;tag=LADrsozib To: sip:60003@10.1.61.7 CSeq: 21 INVITE Call-ID: x8mLe72GJE Max-Forwards: 70 Supported: replaces, outbound, gruu, path, record-aware Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, PRACK, UPDATE Content-Type: application/sdp Content-Length: 369 Contact: ;expires=3599 User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 Authorization: Digest realm="10.1.61.4", nonce="1676461332/51c720608b72d4e8b9b7e27892ccd3a4", algorithm=MD5, opaque="1343481c2a4134ce", username="60006", uri="sip:60003@10.1.61.7", response="109083b946983dc52fd3dbac8b7ca79c", cnonce="6Q~bmCfOKHMotnK5", nc=00000001, qop=auth v=0 o=60006 1208 442 IN IP4 10.25.20.62 s=Talk c=IN IP4 10.25.20.62 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics a=group:BUNDLE as a=record:off m=audio 36600 RTP/AVP 8 0 96 a=rtpmap:96 telephone-event/8000 a=rtcp-mux a=mid:as a=extmap:1 urn:ietf:params:rtp-hdrext:sdes:mid a=rtcp-fb:* trr-int 1000 a=rtcp-fb:* ccm tmmbr 2023-02-15 11:42:09:186 [/liblinphone] MESSAGE [Magic Search] Found 0 results in friends 2023-02-15 11:42:09:211 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [312] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.WLLk2qFAQ;rport=5060;received=10.25.20.62 From: ;tag=LADrsozib To: sip:60003@10.1.61.7 CSeq: 21 INVITE Call-ID: x8mLe72GJE Server: kamailio (5.6.3 (x86_64/linux)) Content-Length: 0 2023-02-15 11:42:09:212 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [312] bytes parsed 2023-02-15 11:42:09:212 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:09:212 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C184327AB0], from state [CALLING] to [PROCEEDING] 2023-02-15 11:42:09:212 [/liblinphone] MESSAGE op [000001C185B5EB40] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:42:09:212 [/liblinphone] MESSAGE Op [000001C185B5EB40] receiving call response [100], dialog is [0000000000000000] in state [BELLE_SIP_DIALOG_NULL] 2023-02-15 11:42:09:241 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [521] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.25.20.62:5060;rport=5060;received=10.25.20.62;branch=z9hG4bK.WLLk2qFAQ Record-Route: Call-ID: x8mLe72GJE From: ;tag=LADrsozib To: ;tag=z9hG4bKbfec.ab81f1cd21f1909795a2eeafd3adfce3.0 CSeq: 21 INVITE WWW-Authenticate: Digest realm="10.1.61.3",nonce="1676461318/59fb2266cc261e4231c9524fd0701169",opaque="2bf0f5af6f90fbfd",algorithm=MD5,qop="auth" Server: pbx Content-Length: 0 2023-02-15 11:42:09:241 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [521] bytes parsed 2023-02-15 11:42:09:241 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:09:241 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C184327AB0], from state [PROCEEDING] to [COMPLETED] 2023-02-15 11:42:09:243 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [341] bytes ACK sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.WLLk2qFAQ;rport Call-ID: x8mLe72GJE From: ;tag=LADrsozib To: ;tag=z9hG4bKbfec.ab81f1cd21f1909795a2eeafd3adfce3.0 Contact: ;expires=3599 Max-Forwards: 70 CSeq: 21 ACK 2023-02-15 11:42:09:243 [/liblinphone] MESSAGE AuthRequested pushed 2023-02-15 11:42:09:243 [/belle-sip] MESSAGE No auth info found for call id [x8mLe72GJE] 2023-02-15 11:42:09:243 [/liblinphone] MESSAGE No auth info found for [sip:60006@10.1.61.7] 2023-02-15 11:42:09:243 [/liblinphone] MESSAGE Op 000001C185B5EB40 added as pending authentication 2023-02-15 11:42:09:284 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [authentication_requested] 2023-02-15 11:42:09:988 [/liblinphone] MESSAGE Terminate CallSession [000001C185DE6370] which is currently in state [LinphoneCallOutgoingProgress] 2023-02-15 11:42:09:988 [/belle-sip] MESSAGE ICT retransmissions stopped 2023-02-15 11:42:09:988 [/liblinphone] MESSAGE [ToneManager] startErrorTone 2023-02-15 11:42:09:988 [/liblinphone] MESSAGE [ToneManager] playTone 2023-02-15 11:42:10:025 [/bctbx] MESSAGE MSWASAPI playback output initialized for [Наушники гарнитуры (Plantronics C720-M)--{0.0.0.00000000}.{f6bd33e5-fc39-4f07-95f6-ad1ae778399a}] at 48000 Hz, 2 channels, with buffer size 9600 (200 ms), device period is 10, 16-bit frames are on 32 bits 2023-02-15 11:42:10:025 [/mediastreamer] MESSAGE speex_lib_ctl init with neon ? 0 2023-02-15 11:42:10:025 [/mediastreamer] MESSAGE configuring resampler output to rate=[48000], nchannels=[2] 2023-02-15 11:42:10:025 [/mediastreamer] MESSAGE ms_filter_link: MSFilePlayer:000001C1846D5E50,0-->MSDtmfGen:000001C1846D5EC0,0 2023-02-15 11:42:10:025 [/mediastreamer] MESSAGE ms_filter_link: MSDtmfGen:000001C1846D5EC0,0-->MSResample:000001C1846D4B10,0 2023-02-15 11:42:10:025 [/mediastreamer] MESSAGE ms_filter_link: MSResample:000001C1846D4B10,0-->MSWASAPIWrite:000001C1846D6010,0 2023-02-15 11:42:10:025 [/mediastreamer] MESSAGE Initializing speex resampler in mode [voip] 2023-02-15 11:42:10:026 [/bctbx] WARNING bctbx_set_self_thread_name(): not implemented. 2023-02-15 11:42:10:026 [/mediastreamer] MESSAGE win32 timer resolution set to 2 ms 2023-02-15 11:42:10:026 [/mediastreamer] MESSAGE Playing tones of frequencies 480,0 Hz, duration=200, amplitude=0.500000 interval=200, repeat_count=2 2023-02-15 11:42:10:026 [/liblinphone] MESSAGE CallSession [000001C185DE6370] moving from state LinphoneCallOutgoingProgress to LinphoneCallEnd 2023-02-15 11:42:10:026 [/liblinphone] MESSAGE StreamsGroup::finish() called. 2023-02-15 11:42:10:026 [/mediastreamer] MESSAGE ================================================================================= 2023-02-15 11:42:10:026 [/mediastreamer] MESSAGE FILTER USAGE STATISTICS 2023-02-15 11:42:10:026 [/mediastreamer] MESSAGE Name Count Time/tick (ms) CPU Usage 2023-02-15 11:42:10:026 [/mediastreamer] MESSAGE min mean max sd 2023-02-15 11:42:10:026 [/mediastreamer] MESSAGE --------------------------------------------------------------------------------- 2023-02-15 11:42:10:026 [/mediastreamer] MESSAGE MSOpusDec 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:10:026 [/mediastreamer] MESSAGE MSMKVPlayer 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:10:026 [/mediastreamer] MESSAGE MSResample 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:10:026 [/mediastreamer] MESSAGE MSWASAPIWrite 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:10:026 [/mediastreamer] MESSAGE MSDtmfGen 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:10:026 [/mediastreamer] MESSAGE MSFilePlayer 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:10:026 [/mediastreamer] MESSAGE MSWebRTCAEC 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:10:026 [/mediastreamer] MESSAGE MSRtpSend 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:10:026 [/mediastreamer] MESSAGE ================================================================================= 2023-02-15 11:42:10:027 [/mediastreamer] MESSAGE ================================================================================= 2023-02-15 11:42:10:027 [/mediastreamer] MESSAGE FILTER USAGE STATISTICS 2023-02-15 11:42:10:027 [/mediastreamer] MESSAGE Name Count Time/tick (ms) CPU Usage 2023-02-15 11:42:10:027 [/mediastreamer] MESSAGE min mean max sd 2023-02-15 11:42:10:027 [/mediastreamer] MESSAGE --------------------------------------------------------------------------------- 2023-02-15 11:42:10:027 [/mediastreamer] MESSAGE MSOpusDec 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:10:027 [/mediastreamer] MESSAGE MSMKVPlayer 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:10:027 [/mediastreamer] MESSAGE MSResample 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:10:027 [/mediastreamer] MESSAGE MSWASAPIWrite 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:10:027 [/mediastreamer] MESSAGE MSDtmfGen 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:10:027 [/mediastreamer] MESSAGE MSFilePlayer 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:10:027 [/mediastreamer] MESSAGE MSWebRTCAEC 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:10:027 [/mediastreamer] MESSAGE MSRtpSend 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:10:027 [/mediastreamer] MESSAGE ================================================================================= 2023-02-15 11:42:10:027 [/mediastreamer] MESSAGE Stopping ZRTP context on session [0000000000000000] 2023-02-15 11:42:10:027 [/mediastreamer] MESSAGE ZRTP context destroyed 2023-02-15 11:42:10:027 [/liblinphone] MESSAGE MainDb::addEvent() of type ConferenceCallEnded (value 4) 2023-02-15 11:42:10:027 [/liblinphone] MESSAGE Update conference call in database: x8mLe72GJE 2023-02-15 11:42:10:035 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_log_updated] 2023-02-15 11:42:10:035 [/liblinphone] MESSAGE Resetting the current call 2023-02-15 11:42:10:035 [/liblinphone] MESSAGE Removing the call (local address sip:60006@10.1.61.7 remote address sip:60003@10.1.61.7) from the list attached to the core 2023-02-15 11:42:10:035 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [last_call_ended] 2023-02-15 11:42:10:061 [/qt] MESSAGE QT: "Removing " CallModel " : " CallModel(0x1c18566f4b0) 2023-02-15 11:42:10:063 [/qt] MESSAGE QT: "Update (`sip:60003@10.1.61.7`, `sip:60006@10.1.61.7`) from chat call." 2023-02-15 11:42:10:063 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_state_changed] 2023-02-15 11:42:10:063 [/liblinphone] MESSAGE CallSession [000001C185DE6370] moving from state LinphoneCallEnd to LinphoneCallReleased 2023-02-15 11:42:10:063 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_state_changed] 2023-02-15 11:42:10:093 [/qt] MESSAGE QT: "Notify event count: 0." 2023-02-15 11:42:10:096 [/qt] MESSAGE QT: "Notify event count: 0." 2023-02-15 11:42:11:021 [/liblinphone] MESSAGE RingStream no longer needed. 2023-02-15 11:42:11:021 [/liblinphone] MESSAGE [ToneManager] destroyRingStream 2023-02-15 11:42:11:022 [/mediastreamer] MESSAGE ms_filter_unlink: MSFilePlayer:000001C1846D5E50,0-->MSDtmfGen:000001C1846D5EC0,0 2023-02-15 11:42:11:022 [/mediastreamer] MESSAGE ms_filter_unlink: MSDtmfGen:000001C1846D5EC0,0-->MSResample:000001C1846D4B10,0 2023-02-15 11:42:11:022 [/mediastreamer] MESSAGE ms_filter_unlink: MSResample:000001C1846D4B10,0-->MSWASAPIWrite:000001C1846D6010,0 2023-02-15 11:42:11:027 [/mediastreamer] MESSAGE Ring MSTicker thread exiting 2023-02-15 11:42:11:866 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [534] new bytes from [UDP://10.1.61.7:5060]: OPTIONS sip:60006@10.25.20.62:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 10.1.61.7;branch=z9hG4bK120b.52a54c78538520704bb6293a56d8707d.0 Via: SIP/2.0/UDP 10.1.61.4:5060;received=10.1.61.4;rport=5060;branch=z9hG4bKPj5a9cbfa0-8f8b-4313-858f-d234397660c0 From: ;tag=be472169-88c4-417a-b036-970ca2341f88 To: Contact: Call-ID: 395ed175-d63c-42da-91b5-96c7980fa666 CSeq: 56793 OPTIONS Supported: path Max-Forwards: 69 User-Agent: pbx Content-Length: 0 2023-02-15 11:42:11:868 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [534] bytes parsed 2023-02-15 11:42:11:868 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [393] bytes SIP/2.0 200 Ok Via: SIP/2.0/UDP 10.1.61.7;branch=z9hG4bK120b.52a54c78538520704bb6293a56d8707d.0 Via: SIP/2.0/UDP 10.1.61.4:5060;received=10.1.61.4;rport=5060;branch=z9hG4bKPj5a9cbfa0-8f8b-4313-858f-d234397660c0 From: ;tag=be472169-88c4-417a-b036-970ca2341f88 To: ;tag=~w-kN Call-ID: 395ed175-d63c-42da-91b5-96c7980fa666 CSeq: 56793 OPTIONS 2023-02-15 11:42:11:900 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [533] new bytes from [UDP://10.1.61.7:5060]: OPTIONS sip:60006@10.25.20.62:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 10.1.61.7;branch=z9hG4bK1d9f.3c49930f5300631a8afc29f716a8bf40.0 Via: SIP/2.0/UDP 10.1.61.3:5060;received=10.1.61.3;rport=5060;branch=z9hG4bKPj35446516-9a4d-4c73-9c03-541f8d1a503b From: ;tag=c89135f5-0f07-4b29-b9ef-abb1bb4d8f9f To: Contact: Call-ID: ac6c75de-abef-45dc-8822-795c8ade8793 CSeq: 7276 OPTIONS Supported: path Max-Forwards: 69 User-Agent: pbx Content-Length: 0 2023-02-15 11:42:11:903 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [533] bytes parsed 2023-02-15 11:42:11:903 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [392] bytes SIP/2.0 200 Ok Via: SIP/2.0/UDP 10.1.61.7;branch=z9hG4bK1d9f.3c49930f5300631a8afc29f716a8bf40.0 Via: SIP/2.0/UDP 10.1.61.3:5060;received=10.1.61.3;rport=5060;branch=z9hG4bKPj35446516-9a4d-4c73-9c03-541f8d1a503b From: ;tag=c89135f5-0f07-4b29-b9ef-abb1bb4d8f9f To: ;tag=suWbn Call-ID: ac6c75de-abef-45dc-8822-795c8ade8793 CSeq: 7276 OPTIONS 2023-02-15 11:42:12:963 [/belle-sip] MESSAGE Changing [client] [CANCEL] transaction [000001C1F88E7E60], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:42:12:963 [/belle-sip] MESSAGE Client CANCEL transaction [000001C1F88E7E60] terminated 2023-02-15 11:42:13:572 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.4 2023-02-15 11:42:13:572 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.3 2023-02-15 11:42:13:572 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=60006, realm=10.1.61.3 2023-02-15 11:42:13:572 [/liblinphone] MESSAGE AuthStack::authFound() for Username[60006];Userid[];Realm[10.1.61.3];Domain[10.1.61.7];Algorithm[];AvailableAlgorithms[]; 2023-02-15 11:42:13:572 [/belle-sip] MESSAGE Auth info found for [60006] realm [10.1.61.3] 2023-02-15 11:42:13:572 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C1843276A0], from state [INIT] to [CALLING] 2023-02-15 11:42:13:572 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [1241] bytes INVITE sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.22jt6Iorr;rport From: ;tag=LADrsozib To: sip:60003@10.1.61.7 CSeq: 22 INVITE Call-ID: x8mLe72GJE Max-Forwards: 70 Supported: replaces, outbound, gruu, path, record-aware Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, PRACK, UPDATE Content-Type: application/sdp Content-Length: 369 Contact: ;expires=3599 User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 Authorization: Digest realm="10.1.61.3", nonce="1676461318/59fb2266cc261e4231c9524fd0701169", algorithm=MD5, opaque="2bf0f5af6f90fbfd", username="60006", uri="sip:60003@10.1.61.7", response="9e7a119228eeb3097bacfbf794251112", cnonce="WpbD4f4QK0kt4vm7", nc=00000001, qop=auth v=0 o=60006 1208 442 IN IP4 10.25.20.62 s=Talk c=IN IP4 10.25.20.62 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics a=group:BUNDLE as a=record:off m=audio 36600 RTP/AVP 8 0 96 a=rtpmap:96 telephone-event/8000 a=rtcp-mux a=mid:as a=extmap:1 urn:ietf:params:rtp-hdrext:sdes:mid a=rtcp-fb:* trr-int 1000 a=rtcp-fb:* ccm tmmbr 2023-02-15 11:42:13:572 [/liblinphone] MESSAGE Op 000001C185B5EB40 removed as pending authentication 2023-02-15 11:42:13:572 [/liblinphone] MESSAGE linphone_core_add_auth_info(): restarted [1] operation(s) after updating auth info for username: [60006] realm [10.1.61.3] domain [10.1.61.7] 2023-02-15 11:42:13:585 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [312] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.22jt6Iorr;rport=5060;received=10.25.20.62 From: ;tag=LADrsozib To: sip:60003@10.1.61.7 CSeq: 22 INVITE Call-ID: x8mLe72GJE Server: kamailio (5.6.3 (x86_64/linux)) Content-Length: 0 2023-02-15 11:42:13:586 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [312] bytes parsed 2023-02-15 11:42:13:586 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:13:586 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C1843276A0], from state [CALLING] to [PROCEEDING] 2023-02-15 11:42:13:586 [/liblinphone] MESSAGE op [000001C185B5EB40] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:42:13:586 [/liblinphone] MESSAGE Op [000001C185B5EB40] receiving call response [100], dialog is [0000000000000000] in state [BELLE_SIP_DIALOG_NULL] 2023-02-15 11:42:13:586 [/liblinphone] MESSAGE Cancelling INVITE request from [sip:60006@10.1.61.7] to [sip:60003@10.1.61.7] 2023-02-15 11:42:13:586 [/belle-sip] MESSAGE Found transaction [000001C1843276A0] matching request. 2023-02-15 11:42:13:586 [/belle-sip] MESSAGE Changing [client] [CANCEL] transaction [000001C185415610], from state [INIT] to [TRYING] 2023-02-15 11:42:13:587 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [321] bytes CANCEL sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.22jt6Iorr;rport Call-ID: x8mLe72GJE From: ;tag=LADrsozib To: sip:60003@10.1.61.7 Max-Forwards: 70 CSeq: 22 CANCEL User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 2023-02-15 11:42:13:617 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [329] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 200 canceling Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.22jt6Iorr;rport=5060;received=10.25.20.62 Call-ID: x8mLe72GJE From: ;tag=LADrsozib To: sip:60003@10.1.61.7;tag=5c46d2e780a7932142ddfe457a3280bb-64ff7b51 CSeq: 22 CANCEL Server: kamailio (5.6.3 (x86_64/linux)) Content-Length: 0 2023-02-15 11:42:13:617 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [329] bytes parsed 2023-02-15 11:42:13:617 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:13:617 [/belle-sip] MESSAGE Changing [client] [CANCEL] transaction [000001C185415610], from state [TRYING] to [COMPLETED] 2023-02-15 11:42:13:617 [/liblinphone] MESSAGE op [000001C185B5EB40] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:42:13:617 [/liblinphone] MESSAGE Op [000001C185B5EB40] receiving call response [200], dialog is [0000000000000000] in state [BELLE_SIP_DIALOG_NULL] 2023-02-15 11:42:13:617 [/liblinphone] WARNING Cancel done reported on already terminated CallSession 2023-02-15 11:42:13:647 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [370] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 10.25.20.62:5060;rport=5060;received=10.25.20.62;branch=z9hG4bK.22jt6Iorr Record-Route: Call-ID: x8mLe72GJE From: ;tag=LADrsozib To: ;tag=961a0297-718b-4d04-bd39-22323aaf7de3 CSeq: 22 INVITE Server: pbx Content-Length: 0 2023-02-15 11:42:13:648 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [370] bytes parsed 2023-02-15 11:42:13:648 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:13:648 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C1843276A0], from state [PROCEEDING] to [COMPLETED] 2023-02-15 11:42:13:648 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [331] bytes ACK sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.22jt6Iorr;rport Call-ID: x8mLe72GJE From: ;tag=LADrsozib To: ;tag=961a0297-718b-4d04-bd39-22323aaf7de3 Contact: ;expires=3599 Max-Forwards: 70 CSeq: 22 ACK 2023-02-15 11:42:13:648 [/liblinphone] MESSAGE op [000001C185B5EB40] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:42:13:648 [/liblinphone] MESSAGE Op [000001C185B5EB40] receiving call response [487], dialog is [0000000000000000] in state [BELLE_SIP_DIALOG_NULL] 2023-02-15 11:42:13:648 [/liblinphone] MESSAGE op [000001C185B5EB40] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:42:14:558 [/liblinphone] MESSAGE New MediaSession [000001C1FE3A3B20] initialized (liblinphone version: 5.2.15) 2023-02-15 11:42:14:558 [/liblinphone] MESSAGE Media local-ip for streams advertised in SDP: 10.25.20.62 2023-02-15 11:42:14:558 [/liblinphone] MESSAGE Rtp bundle is enabled. 2023-02-15 11:42:14:558 [/liblinphone] MESSAGE Found media local-ip from signaling: 10.25.20.62 2023-02-15 11:42:14:559 [/liblinphone] MESSAGE [LIME] C:\GitLab-Runner\builds\hexP7jis\0\BC\public\linphone-desktop\linphone-sdk\lime\src\lime_localStorage.cpp:255 Cannot find Lime User sip:60006@10.25.20.62 in DB while setting up identity key for ZRTP auxiliary secret 2023-02-15 11:42:14:559 [/liblinphone] MESSAGE stream#0 [audio] in state [Stopped]: multicast role is [inactive] 2023-02-15 11:42:14:559 [/ortp] MESSAGE RtpSession bound to [::0] ports [36600] [36601] 2023-02-15 11:42:14:559 [/liblinphone] MESSAGE Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80 2023-02-15 11:42:14:559 [/liblinphone] MESSAGE Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32 2023-02-15 11:42:14:559 [/liblinphone] MESSAGE Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80 2023-02-15 11:42:14:559 [/liblinphone] MESSAGE Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32 2023-02-15 11:42:14:559 [/liblinphone] MESSAGE Configured srtp crypto suite: AEAD_AES_128_GCM 2023-02-15 11:42:14:559 [/liblinphone] MESSAGE Configured srtp crypto suite: AEAD_AES_256_GCM 2023-02-15 11:42:14:559 [/liblinphone] MESSAGE Configured zrtp key agreement: 'MS_ZRTP_KEY_AGREEMENT_K255_KYB512' 2023-02-15 11:42:14:559 [/mediastreamer] MESSAGE Creating ZRTP engine on rtp session [000001C1FBEE28F0] ssrc 0xe27ead00 2023-02-15 11:42:14:560 [/ortp] MESSAGE rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2023-02-15 11:42:14:560 [/mediastreamer] MESSAGE Setting DSCP to 46 for MSAudio stream. 2023-02-15 11:42:14:560 [/ortp] MESSAGE check OS support for qwave.lib 2023-02-15 11:42:14:560 [/ortp] ERROR QOSAddSocketToFlow failed to add a flow with error 87 2023-02-15 11:42:14:560 [/liblinphone] MESSAGE Created stream of type audio at index 0: stream#0 [audio] in state [Stopped] 2023-02-15 11:42:14:560 [/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. 2023-02-15 11:42:14:560 [/liblinphone] MESSAGE [MS2AudioStream] setting type of soundcard 000001C1F3182B80 to voice 2023-02-15 11:42:14:560 [/liblinphone] MESSAGE [MS2AudioStream] setting type of soundcard 000001C1F3182400 to voice 2023-02-15 11:42:14:560 [/liblinphone] MESSAGE [ToneManager] stopDtmfStream 2023-02-15 11:42:14:560 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_created] 2023-02-15 11:42:14:560 [/liblinphone] MESSAGE CallSession [000001C1FE3A3B20] moving from state LinphoneCallIdle to LinphoneCallOutgoingInit 2023-02-15 11:42:14:560 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [first_call_started] 2023-02-15 11:42:14:561 [/qt] MESSAGE QT: "Add call:" "sip:60006@10.1.61.7" "sip:60003@10.1.61.7" 2023-02-15 11:42:14:569 [/liblinphone] WARNING Couldn't find supported video definition for 0x0 2023-02-15 11:42:14:605 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_state_changed] 2023-02-15 11:42:14:605 [/liblinphone] MESSAGE [ToneManager] stopDtmfStream 2023-02-15 11:42:14:605 [/liblinphone] MESSAGE ICE state is IceStateNotActivated for stream#0 [audio] in state [Stopped] 2023-02-15 11:42:14:606 [/liblinphone] MESSAGE Contact has been fixed using proxy 2023-02-15 11:42:14:606 [/liblinphone] MESSAGE Setting contact address for session 000001C1847F38F0 to ;expires=3599 2023-02-15 11:42:14:606 [/liblinphone] MESSAGE [sip:60006@10.1.61.7] calling [sip:60003@10.1.61.7] on op [000001C185B605F0] 2023-02-15 11:42:14:606 [/liblinphone] MESSAGE Skipping top route of initial route-set because same as request-uri 2023-02-15 11:42:14:606 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185ED5BD0], from state [INIT] to [CALLING] 2023-02-15 11:42:14:606 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [964] bytes INVITE sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.zHEEXFMro;rport From: ;tag=LurK1O-Zv To: sip:60003@10.1.61.7 CSeq: 20 INVITE Call-ID: G4xU7bdX-U Max-Forwards: 70 Supported: replaces, outbound, gruu, path, record-aware Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, PRACK, UPDATE Content-Type: application/sdp Content-Length: 370 Contact: ;expires=3599 User-Agent: Linphone-Desktop/5.0.8 (PC000000031081) windows/10 Qt/5.15.2 LinphoneSDK/5.2.15 v=0 o=60006 2805 2110 IN IP4 10.25.20.62 s=Talk c=IN IP4 10.25.20.62 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics a=group:BUNDLE as a=record:off m=audio 36600 RTP/AVP 8 0 96 a=rtpmap:96 telephone-event/8000 a=rtcp-mux a=mid:as a=extmap:1 urn:ietf:params:rtp-hdrext:sdes:mid a=rtcp-fb:* trr-int 1000 a=rtcp-fb:* ccm tmmbr 2023-02-15 11:42:14:606 [/liblinphone] MESSAGE CallSession [000001C1FE3A3B20] moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress 2023-02-15 11:42:14:607 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_state_changed] 2023-02-15 11:42:14:607 [/liblinphone] MESSAGE MainDb::addEvent() of type ConferenceCallStarted (value 3) 2023-02-15 11:42:14:607 [/liblinphone] MESSAGE Insert new conference call in database: G4xU7bdX-U 2023-02-15 11:42:14:614 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_log_updated] 2023-02-15 11:42:14:650 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [312] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.zHEEXFMro;rport=5060;received=10.25.20.62 From: ;tag=LurK1O-Zv To: sip:60003@10.1.61.7 CSeq: 20 INVITE Call-ID: G4xU7bdX-U Server: kamailio (5.6.3 (x86_64/linux)) Content-Length: 0 2023-02-15 11:42:14:651 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [312] bytes parsed 2023-02-15 11:42:14:651 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:14:651 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185ED5BD0], from state [CALLING] to [PROCEEDING] 2023-02-15 11:42:14:651 [/liblinphone] MESSAGE op [000001C185B605F0] : setOrUpdateDialog() current=[0000000000000000] new=[0000000000000000] 2023-02-15 11:42:14:651 [/liblinphone] MESSAGE Op [000001C185B605F0] receiving call response [100], dialog is [0000000000000000] in state [BELLE_SIP_DIALOG_NULL] 2023-02-15 11:42:14:681 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [521] new bytes from [UDP://10.1.61.7:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.25.20.62:5060;rport=5060;received=10.25.20.62;branch=z9hG4bK.zHEEXFMro Record-Route: Call-ID: G4xU7bdX-U From: ;tag=LurK1O-Zv To: ;tag=z9hG4bK3e5b.b4e69b580453d89ac6ef105f99bf6add.0 CSeq: 20 INVITE WWW-Authenticate: Digest realm="10.1.61.4",nonce="1676461338/7522a82acfd275ba75b4f2636234cc33",opaque="0c3020254771be9c",algorithm=MD5,qop="auth" Server: pbx Content-Length: 0 2023-02-15 11:42:14:684 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [521] bytes parsed 2023-02-15 11:42:14:684 [/belle-sip] MESSAGE Found transaction matching response. 2023-02-15 11:42:14:684 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185ED5BD0], from state [PROCEEDING] to [COMPLETED] 2023-02-15 11:42:14:685 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [341] bytes ACK sip:60003@10.1.61.7 SIP/2.0 Via: SIP/2.0/UDP 10.25.20.62:5060;branch=z9hG4bK.zHEEXFMro;rport Call-ID: G4xU7bdX-U From: ;tag=LurK1O-Zv To: ;tag=z9hG4bK3e5b.b4e69b580453d89ac6ef105f99bf6add.0 Contact: ;expires=3599 Max-Forwards: 70 CSeq: 20 ACK 2023-02-15 11:42:14:685 [/liblinphone] MESSAGE AuthRequested pushed 2023-02-15 11:42:14:685 [/belle-sip] MESSAGE No auth info found for call id [G4xU7bdX-U] 2023-02-15 11:42:14:685 [/liblinphone] MESSAGE No auth info found for [sip:60006@10.1.61.7] 2023-02-15 11:42:14:685 [/liblinphone] MESSAGE Op 000001C185B605F0 added as pending authentication 2023-02-15 11:42:14:685 [/liblinphone] MESSAGE [Magic Search] Found 0 results in friends 2023-02-15 11:42:14:727 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [authentication_requested] 2023-02-15 11:42:15:526 [/liblinphone] MESSAGE Terminate CallSession [000001C1FE3A3B20] which is currently in state [LinphoneCallOutgoingProgress] 2023-02-15 11:42:15:527 [/belle-sip] MESSAGE ICT retransmissions stopped 2023-02-15 11:42:15:527 [/liblinphone] MESSAGE [ToneManager] startErrorTone 2023-02-15 11:42:15:527 [/liblinphone] MESSAGE [ToneManager] playTone 2023-02-15 11:42:15:563 [/bctbx] MESSAGE MSWASAPI playback output initialized for [Наушники гарнитуры (Plantronics C720-M)--{0.0.0.00000000}.{f6bd33e5-fc39-4f07-95f6-ad1ae778399a}] at 48000 Hz, 2 channels, with buffer size 9600 (200 ms), device period is 10, 16-bit frames are on 32 bits 2023-02-15 11:42:15:563 [/mediastreamer] MESSAGE speex_lib_ctl init with neon ? 0 2023-02-15 11:42:15:563 [/mediastreamer] MESSAGE configuring resampler output to rate=[48000], nchannels=[2] 2023-02-15 11:42:15:563 [/mediastreamer] MESSAGE ms_filter_link: MSFilePlayer:000001C1849473E0,0-->MSDtmfGen:000001C1849478B0,0 2023-02-15 11:42:15:563 [/mediastreamer] MESSAGE ms_filter_link: MSDtmfGen:000001C1849478B0,0-->MSResample:000001C184947450,0 2023-02-15 11:42:15:563 [/mediastreamer] MESSAGE ms_filter_link: MSResample:000001C184947450,0-->MSWASAPIWrite:000001C184947D10,0 2023-02-15 11:42:15:563 [/mediastreamer] MESSAGE Initializing speex resampler in mode [voip] 2023-02-15 11:42:15:563 [/bctbx] WARNING bctbx_set_self_thread_name(): not implemented. 2023-02-15 11:42:15:563 [/mediastreamer] MESSAGE win32 timer resolution set to 2 ms 2023-02-15 11:42:15:564 [/mediastreamer] MESSAGE Playing tones of frequencies 480,0 Hz, duration=200, amplitude=0.500000 interval=200, repeat_count=2 2023-02-15 11:42:15:564 [/liblinphone] MESSAGE CallSession [000001C1FE3A3B20] moving from state LinphoneCallOutgoingProgress to LinphoneCallEnd 2023-02-15 11:42:15:564 [/liblinphone] MESSAGE StreamsGroup::finish() called. 2023-02-15 11:42:15:564 [/mediastreamer] MESSAGE ================================================================================= 2023-02-15 11:42:15:564 [/mediastreamer] MESSAGE FILTER USAGE STATISTICS 2023-02-15 11:42:15:564 [/mediastreamer] MESSAGE Name Count Time/tick (ms) CPU Usage 2023-02-15 11:42:15:564 [/mediastreamer] MESSAGE min mean max sd 2023-02-15 11:42:15:564 [/mediastreamer] MESSAGE --------------------------------------------------------------------------------- 2023-02-15 11:42:15:564 [/mediastreamer] MESSAGE MSOpusDec 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:15:564 [/mediastreamer] MESSAGE MSMKVPlayer 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:15:564 [/mediastreamer] MESSAGE MSResample 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:15:564 [/mediastreamer] MESSAGE MSWASAPIWrite 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:15:564 [/mediastreamer] MESSAGE MSDtmfGen 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:15:564 [/mediastreamer] MESSAGE MSFilePlayer 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:15:564 [/mediastreamer] MESSAGE MSWebRTCAEC 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:15:564 [/mediastreamer] MESSAGE MSRtpSend 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE ================================================================================= 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE ================================================================================= 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE FILTER USAGE STATISTICS 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE Name Count Time/tick (ms) CPU Usage 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE min mean max sd 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE --------------------------------------------------------------------------------- 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE MSOpusDec 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE MSMKVPlayer 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE MSResample 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE MSWASAPIWrite 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE MSDtmfGen 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE MSFilePlayer 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE MSWebRTCAEC 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE MSRtpSend 0 0.00 0.00 0.00 0.00 0.0 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE ================================================================================= 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE Stopping ZRTP context on session [0000000000000000] 2023-02-15 11:42:15:565 [/mediastreamer] MESSAGE ZRTP context destroyed 2023-02-15 11:42:15:565 [/liblinphone] MESSAGE MainDb::addEvent() of type ConferenceCallEnded (value 4) 2023-02-15 11:42:15:565 [/liblinphone] MESSAGE Update conference call in database: G4xU7bdX-U 2023-02-15 11:42:15:575 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_log_updated] 2023-02-15 11:42:15:575 [/liblinphone] MESSAGE Resetting the current call 2023-02-15 11:42:15:575 [/liblinphone] MESSAGE Removing the call (local address sip:60006@10.1.61.7 remote address sip:60003@10.1.61.7) from the list attached to the core 2023-02-15 11:42:15:575 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [last_call_ended] 2023-02-15 11:42:15:600 [/qt] MESSAGE QT: "Removing " CallModel " : " CallModel(0x1c185673740) 2023-02-15 11:42:15:602 [/qt] MESSAGE QT: "Update (`sip:60003@10.1.61.7`, `sip:60006@10.1.61.7`) from chat call." 2023-02-15 11:42:15:602 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_state_changed] 2023-02-15 11:42:15:602 [/liblinphone] MESSAGE CallSession [000001C1FE3A3B20] moving from state LinphoneCallEnd to LinphoneCallReleased 2023-02-15 11:42:15:602 [/liblinphone] MESSAGE Linphone core [000001C1F1E70EB0] notified [call_state_changed] 2023-02-15 11:42:15:633 [/qt] MESSAGE QT: "Notify event count: 0." 2023-02-15 11:42:15:637 [/qt] MESSAGE QT: "Notify event count: 0." 2023-02-15 11:42:16:554 [/liblinphone] MESSAGE RingStream no longer needed. 2023-02-15 11:42:16:554 [/liblinphone] MESSAGE [ToneManager] destroyRingStream 2023-02-15 11:42:16:555 [/mediastreamer] MESSAGE ms_filter_unlink: MSFilePlayer:000001C1849473E0,0-->MSDtmfGen:000001C1849478B0,0 2023-02-15 11:42:16:555 [/mediastreamer] MESSAGE ms_filter_unlink: MSDtmfGen:000001C1849478B0,0-->MSResample:000001C184947450,0 2023-02-15 11:42:16:555 [/mediastreamer] MESSAGE ms_filter_unlink: MSResample:000001C184947450,0-->MSWASAPIWrite:000001C184947D10,0 2023-02-15 11:42:16:563 [/mediastreamer] MESSAGE Ring MSTicker thread exiting 2023-02-15 11:42:18:618 [/belle-sip] MESSAGE Changing [client] [CANCEL] transaction [000001C185415610], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:42:18:618 [/belle-sip] MESSAGE Client CANCEL transaction [000001C185415610] terminated 2023-02-15 11:42:19:962 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: keep alive sent to [UDP://10.1.61.7:5060] 2023-02-15 11:42:25:648 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C184316740], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:42:25:649 [/belle-sip] MESSAGE Client INVITE transaction [000001C184316740] terminated 2023-02-15 11:42:25:649 [/liblinphone] MESSAGE Destroying op [000001C185B5FA80] of type [SalOpCall] 2023-02-15 11:42:25:649 [/belle-sip] MESSAGE Transaction [000001C184316810] deleted 2023-02-15 11:42:25:649 [/belle-sip] MESSAGE Transaction [000001C1846C2E60] deleted 2023-02-15 11:42:25:649 [/belle-sip] MESSAGE Transaction [000001C184316740] deleted 2023-02-15 11:42:28:648 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185EEB1E0], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:42:28:648 [/belle-sip] MESSAGE Client INVITE transaction [000001C185EEB1E0] terminated 2023-02-15 11:42:33:946 [/belle-sip] MESSAGE Changing [server] [NOTIFY] transaction [000001C1855453D0], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:42:33:946 [/belle-sip] MESSAGE Server NOTIFY transaction [000001C1855453D0] terminated 2023-02-15 11:42:33:946 [/liblinphone] MESSAGE Unhandled transaction terminated [000001C1855453D0] 2023-02-15 11:42:33:946 [/belle-sip] MESSAGE Transaction [000001C1855453D0] deleted 2023-02-15 11:42:33:991 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C184320DD0], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:42:33:991 [/belle-sip] MESSAGE Client INVITE transaction [000001C184320DD0] terminated 2023-02-15 11:42:33:991 [/liblinphone] MESSAGE Destroying op [000001C185B5E770] of type [SalOpCall] 2023-02-15 11:42:33:991 [/belle-sip] MESSAGE Transaction [000001C185EEB1E0] deleted 2023-02-15 11:42:33:991 [/belle-sip] MESSAGE Transaction [000001C1857D8100] deleted 2023-02-15 11:42:33:991 [/belle-sip] MESSAGE Transaction [000001C184320DD0] deleted 2023-02-15 11:42:33:991 [/belle-sip] MESSAGE Changing [server] [NOTIFY] transaction [000001C185543B70], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:42:33:991 [/belle-sip] MESSAGE Server NOTIFY transaction [000001C185543B70] terminated 2023-02-15 11:42:33:992 [/liblinphone] MESSAGE Unhandled transaction terminated [000001C185543B70] 2023-02-15 11:42:33:992 [/belle-sip] MESSAGE Transaction [000001C185543B70] deleted 2023-02-15 11:42:35:163 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185EFA740], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:42:35:163 [/belle-sip] MESSAGE Client INVITE transaction [000001C185EFA740] terminated 2023-02-15 11:42:39:945 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185EE8050], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:42:39:945 [/belle-sip] MESSAGE Client INVITE transaction [000001C185EE8050] terminated 2023-02-15 11:42:39:946 [/liblinphone] MESSAGE Destroying op [000001C185B5D090] of type [SalOpCall] 2023-02-15 11:42:39:946 [/belle-sip] MESSAGE Transaction [000001C185EFA740] deleted 2023-02-15 11:42:39:946 [/belle-sip] MESSAGE Transaction [000001C1F88E7E60] deleted 2023-02-15 11:42:39:946 [/belle-sip] MESSAGE Transaction [000001C185EE8050] deleted 2023-02-15 11:42:41:209 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185F0B6A0], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:42:41:210 [/belle-sip] MESSAGE Client INVITE transaction [000001C185F0B6A0] terminated 2023-02-15 11:42:41:210 [/belle-sip] MESSAGE Transaction [000001C185F0B6A0] deleted 2023-02-15 11:42:41:262 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C184327AB0], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:42:41:262 [/belle-sip] MESSAGE Client INVITE transaction [000001C184327AB0] terminated 2023-02-15 11:42:45:664 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C1843276A0], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:42:45:665 [/belle-sip] MESSAGE Client INVITE transaction [000001C1843276A0] terminated 2023-02-15 11:42:45:665 [/liblinphone] MESSAGE Destroying op [000001C185B5EB40] of type [SalOpCall] 2023-02-15 11:42:45:665 [/belle-sip] MESSAGE Transaction [000001C184327AB0] deleted 2023-02-15 11:42:45:665 [/belle-sip] MESSAGE Transaction [000001C185415610] deleted 2023-02-15 11:42:45:665 [/belle-sip] MESSAGE Transaction [000001C1843276A0] deleted 2023-02-15 11:42:46:694 [/belle-sip] MESSAGE Changing [client] [INVITE] transaction [000001C185ED5BD0], from state [COMPLETED] to [TERMINATED] 2023-02-15 11:42:46:695 [/belle-sip] MESSAGE Client INVITE transaction [000001C185ED5BD0] terminated 2023-02-15 11:42:46:695 [/liblinphone] MESSAGE Destroying op [000001C185B605F0] of type [SalOpCall] 2023-02-15 11:42:46:695 [/liblinphone] MESSAGE Op 000001C185B605F0 removed as pending authentication 2023-02-15 11:42:46:695 [/belle-sip] MESSAGE Transaction [000001C185ED5BD0] deleted 2023-02-15 11:42:49:976 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: keep alive sent to [UDP://10.1.61.7:5060] 2023-02-15 11:43:11:865 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [534] new bytes from [UDP://10.1.61.7:5060]: OPTIONS sip:60006@10.25.20.62:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 10.1.61.7;branch=z9hG4bKd8e6.25217e4653f532dbf0a7295725df6f30.0 Via: SIP/2.0/UDP 10.1.61.4:5060;received=10.1.61.4;rport=5060;branch=z9hG4bKPj8431eb54-df68-46d1-8001-22606d1513c4 From: ;tag=9d4e2df1-6670-481d-aada-113c54a660ee To: Contact: Call-ID: 0559bd5f-f419-473d-bb57-44b0e8d800f1 CSeq: 28879 OPTIONS Supported: path Max-Forwards: 69 User-Agent: pbx Content-Length: 0 2023-02-15 11:43:11:867 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [534] bytes parsed 2023-02-15 11:43:11:867 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [393] bytes SIP/2.0 200 Ok Via: SIP/2.0/UDP 10.1.61.7;branch=z9hG4bKd8e6.25217e4653f532dbf0a7295725df6f30.0 Via: SIP/2.0/UDP 10.1.61.4:5060;received=10.1.61.4;rport=5060;branch=z9hG4bKPj8431eb54-df68-46d1-8001-22606d1513c4 From: ;tag=9d4e2df1-6670-481d-aada-113c54a660ee To: ;tag=9NdqX Call-ID: 0559bd5f-f419-473d-bb57-44b0e8d800f1 CSeq: 28879 OPTIONS 2023-02-15 11:43:11:911 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: received [534] new bytes from [UDP://10.1.61.7:5060]: OPTIONS sip:60006@10.25.20.62:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 10.1.61.7;branch=z9hG4bKcf1b.35721b254fe652e252f94da8abc38024.0 Via: SIP/2.0/UDP 10.1.61.3:5060;received=10.1.61.3;rport=5060;branch=z9hG4bKPja15afb3e-18aa-47a9-a46b-cbfae5719688 From: ;tag=5cf0c2be-0a7c-4b9a-83b5-5949175f0aff To: Contact: Call-ID: 63e4fd39-0655-472f-9dc7-99271a4c4e06 CSeq: 22358 OPTIONS Supported: path Max-Forwards: 69 User-Agent: pbx Content-Length: 0 2023-02-15 11:43:11:913 [/belle-sip] MESSAGE channel [000001C1FC389ED0] [534] bytes parsed 2023-02-15 11:43:11:913 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: message sent to [UDP://10.1.61.7:5060], size: [393] bytes SIP/2.0 200 Ok Via: SIP/2.0/UDP 10.1.61.7;branch=z9hG4bKcf1b.35721b254fe652e252f94da8abc38024.0 Via: SIP/2.0/UDP 10.1.61.3:5060;received=10.1.61.3;rport=5060;branch=z9hG4bKPja15afb3e-18aa-47a9-a46b-cbfae5719688 From: ;tag=5cf0c2be-0a7c-4b9a-83b5-5949175f0aff To: ;tag=RzBs3 Call-ID: 63e4fd39-0655-472f-9dc7-99271a4c4e06 CSeq: 22358 OPTIONS 2023-02-15 11:43:19:975 [/belle-sip] MESSAGE channel [000001C1FC389ED0]: keep alive sent to [UDP://10.1.61.7:5060]