Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

ip to ip.NullPointerException: null upcall object in pj::Call::onCallState , java.lang.NullPointerException: null upcall object in pj::Call::onCallTsxState and resend old msg 180 rinding #3950

Open
zhuangguangkang0013 opened this issue May 10, 2024 · 2 comments

Comments

@zhuangguangkang0013
Copy link

Describe the bug

I'm running Android 8.1, using IP to IP for LAN communication. This problem seems to occur when multiple CALLs are disconnected from the network after calling/ringing. Not entirely sure how to reproduce. Appeared 2 times. What the log shows seems to be that the expired CALL timer is not destroyed, resulting in repeated attempts to retransmit. This causes "null upcall object in pj::Call::onCallTsxState" and "null upcall object in pj::Call::onCallState". There is currently no more detailed log

java.lang.NullPointerException: null upcall object in pj::Call::onCallState
java.lang.NullPointerException: null upcall object in pj::Call::onCallTsxState

05-09 16:07:55.060 3883 3972 D pjsip-pjsua_0: 16:07:55.058 tsx0xc0cfc864 Retransmit timer event
05-09 16:07:55.062 3883 3972 D pjsip-pjsua_0: 16:07:55.061 tsx0xc0cfc864 .Retransmiting Response msg 180/INVITE/cseq=7220 (tdta0xc41c6064), count=80, restart?=1
05-09 16:07:55.064 3883 3972 D pjsip-pjsua_0: 16:07:55.063 pjsua_core.c .TX 545 bytes Response msg 180/INVITE/cseq=7220 (tdta0xc41c6064) to UDP 192.168.1.105:5060:
05-09 16:07:55.064 3883 3972 D pjsip-pjsua_0: SIP/2.0 180 Ringing

05-09 16:07:55.064 3883 3972 D pjsip-pjsua_0: Via: SIP/2.0/UDP 192.168.1.105:5060;rport=5060;received=192.168.1.105;branch=z9hG4bKPjCsMdhdUVEEZzcZWtPD8Q7qLo4cgnwk3r

05-09 16:07:55.064 3883 3972 D pjsip-pjsua_0: Call-ID: S.yf6J.qiGUy3xpIPG-mQMDeNUtbrxxf

05-09 16:07:55.064 3883 3972 D pjsip-pjsua_0: From: sip:405579132320223232@192.168.1.105;tag=TQjK-HyH2C67MSu5mmkx61vnFbgEMLS2

05-09 16:07:55.064 3883 3972 D pjsip-pjsua_0: To: sip:457007454442622976@192.168.1.101;tag=07a8aa0c-1689-4121-beca-5b4509559138

05-09 16:07:55.064 3883 3972 D pjsip-pjsua_0: CSeq: 7220 INVITE

05-09 16:07:55.064 3883 3972 D pjsip-pjsua_0: Contact: sip:457007454442622976@192.168.1.101:5060

05-09 16:07:55.064 3883 3972 D pjsip-pjsua_0: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS

05-09 16:07:55.064 3883 3972 D pjsip-pjsua_0: Content-Length: 0

05-09 16:07:55.064 3883 3972 D pjsip-pjsua_0:

05-09 16:07:55.064 3883 3972 D pjsip-pjsua_0:
05-09 16:07:55.064 3883 3972 D pjsip-pjsua_0: --end msg--
05-09 16:07:55.079 3883 3972 D pjsip-pjsua_0: 16:07:55.076 tlsc0xc1035414 Sending 4 byte(s) keep-alive to open-sips.gvs-icloud.com:5061
05-09 16:07:55.089 3883 3972 D pjsip-pjsua_0: 16:07:55.087 tsx0xc1da7864 Retransmit timer event
05-09 16:07:55.091 3883 3972 D pjsip-pjsua_0: 16:07:55.090 tsx0xc1da7864 .Retransmiting Response msg 180/INVITE/cseq=15161 (tdta0xc1dab064), count=82, restart?=1
05-09 16:07:55.093 3883 3972 D pjsip-pjsua_0: 16:07:55.092 pjsua_core.c .TX 546 bytes Response msg 180/INVITE/cseq=15161 (tdta0xc1dab064) to UDP 192.168.1.105:5060:
05-09 16:07:55.093 3883 3972 D pjsip-pjsua_0: SIP/2.0 180 Ringing

05-09 16:07:55.093 3883 3972 D pjsip-pjsua_0: Via: SIP/2.0/UDP 192.168.1.105:5060;rport=5060;received=192.168.1.105;branch=z9hG4bKPjE9NuBy1TwUJVAtftQ06TRV3IjYIFPE84

05-09 16:07:55.093 3883 3972 D pjsip-pjsua_0: Call-ID: K1X4ZMmHAI.ra3EjP2kR-uhcsU6vJ-oT

05-09 16:07:55.093 3883 3972 D pjsip-pjsua_0: From: sip:405579132320223232@192.168.1.105;tag=Qd.we67wAtzidA6h01BCCJdiJV50mwdh

05-09 16:07:55.093 3883 3972 D pjsip-pjsua_0: To: sip:457007454442622976@192.168.1.101;tag=6b3241a5-5875-4830-9342-f0e73756ab43

05-09 16:07:55.093 3883 3972 D pjsip-pjsua_0: CSeq: 15161 INVITE

05-09 16:07:55.093 3883 3972 D pjsip-pjsua_0: Contact: sip:457007454442622976@192.168.1.101:5060

05-09 16:07:55.093 3883 3972 D pjsip-pjsua_0: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS

05-09 16:07:55.093 3883 3972 D pjsip-pjsua_0: Content-Length: 0

05-09 16:07:55.093 3883 3972 D pjsip-pjsua_0:

05-09 16:07:55.093 3883 3972 D pjsip-pjsua_0:
05-09 16:07:55.093 3883 3972 D pjsip-pjsua_0: --end msg--
05-09 16:07:55.106 3883 3972 D pjsip-pjsua_0: 16:07:55.104 tsx0xc10a0864 Retransmit timer event
05-09 16:07:55.108 3883 3972 D pjsip-pjsua_0: 16:07:55.107 tsx0xc10a0864 .Retransmiting Response msg 180/INVITE/cseq=679 (tdta0xc10b1064), count=77, restart?=1
05-09 16:07:55.110 3883 3972 D pjsip-pjsua_0: 16:07:55.108 pjsua_core.c .TX 544 bytes Response msg 180/INVITE/cseq=679 (tdta0xc10b1064) to UDP 192.168.1.105:5060:
05-09 16:07:55.110 3883 3972 D pjsip-pjsua_0: SIP/2.0 180 Ringing

05-09 16:07:55.110 3883 3972 D pjsip-pjsua_0: Via: SIP/2.0/UDP 192.168.1.105:5060;rport=5060;received=192.168.1.105;branch=z9hG4bKPjWqOObqaXPE-r0BAa3fcjUlNSp2AE3jzh

05-09 16:07:55.110 3883 3972 D pjsip-pjsua_0: Call-ID: 82SGKjymwlqJY8Sj0a0NUm5RkQ6ibBNj

05-09 16:07:55.110 3883 3972 D pjsip-pjsua_0: From: sip:405579132320223232@192.168.1.105;tag=bQmB1e.pkib4aWc1lHtKwVQp2Vp7M8XL

05-09 16:07:55.110 3883 3972 D pjsip-pjsua_0: To: sip:457007454442622976@192.168.1.101;tag=00a628fc-065f-4785-983b-c3a88bb38036

05-09 16:07:55.110 3883 3972 D pjsip-pjsua_0: CSeq: 679 INVITE

05-09 16:07:55.110 3883 3972 D pjsip-pjsua_0: Contact: sip:457007454442622976@192.168.1.101:5060

05-09 16:07:55.110 3883 3972 D pjsip-pjsua_0: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS

05-09 16:07:55.110 3883 3972 D pjsip-pjsua_0: Content-Length: 0

05-09 16:07:55.110 3883 3972 D pjsip-pjsua_0:

05-09 16:07:55.110 3883 3972 D pjsip-pjsua_0:
05-09 16:07:55.110 3883 3972 D pjsip-pjsua_0: --end msg--

Steps to reproduce

I'm running Android 8.1, using IP to IP for LAN communication. This problem seems to occur when multiple CALLs are disconnected from the network after calling/ringing. Not entirely sure how to reproduce. Appeared 2 times. What the log shows seems to be that the expired CALL timer is not destroyed, resulting in repeated attempts to retransmit. This causes "null upcall object in pj::Call::onCallTsxState" and "null upcall object in pj::Call::onCallState". There is currently no more detailed log

PJSIP version

2.12

Context

Android OS 8.1

Log, call stack, etc

05-09 15:20:13.010  3883  3972 E AndroidRuntime: java.lang.NullPointerException: null upcall object in pj::Call::onCallTsxState 
05-09 15:20:13.013  3883  3972 E AndroidRuntime: java.lang.NullPointerException: null upcall object in pj::Call::onCallState 

05-09 16:07:55.060  3883  3972 D pjsip-pjsua_0: 16:07:55.058  tsx0xc0cfc864  Retransmit timer event
05-09 16:07:55.062  3883  3972 D pjsip-pjsua_0: 16:07:55.061  tsx0xc0cfc864  .Retransmiting Response msg 180/INVITE/cseq=7220 (tdta0xc41c6064), count=80, restart?=1
05-09 16:07:55.064  3883  3972 D pjsip-pjsua_0: 16:07:55.063   pjsua_core.c  .TX 545 bytes Response msg 180/INVITE/cseq=7220 (tdta0xc41c6064) to UDP 192.168.1.105:5060:
05-09 16:07:55.064  3883  3972 D pjsip-pjsua_0: SIP/2.0 180 Ringing

05-09 16:07:55.064  3883  3972 D pjsip-pjsua_0: Via: SIP/2.0/UDP 192.168.1.105:5060;rport=5060;received=192.168.1.105;branch=z9hG4bKPjCsMdhdUVEEZzcZWtPD8Q7qLo4cgnwk3r

05-09 16:07:55.064  3883  3972 D pjsip-pjsua_0: Call-ID: S.yf6J.qiGUy3xpIPG-mQMDeNUtbrxxf

05-09 16:07:55.064  3883  3972 D pjsip-pjsua_0: From: <sip:405579132320223232@192.168.1.105>;tag=TQjK-HyH2C67MSu5mmkx61vnFbgEMLS2

05-09 16:07:55.064  3883  3972 D pjsip-pjsua_0: To: <sip:457007454442622976@192.168.1.101>;tag=07a8aa0c-1689-4121-beca-5b4509559138

05-09 16:07:55.064  3883  3972 D pjsip-pjsua_0: CSeq: 7220 INVITE

05-09 16:07:55.064  3883  3972 D pjsip-pjsua_0: Contact: <sip:457007454442622976@192.168.1.101:5060>

05-09 16:07:55.064  3883  3972 D pjsip-pjsua_0: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS

05-09 16:07:55.064  3883  3972 D pjsip-pjsua_0: Content-Length:  0

05-09 16:07:55.064  3883  3972 D pjsip-pjsua_0: 

05-09 16:07:55.064  3883  3972 D pjsip-pjsua_0: 
05-09 16:07:55.064  3883  3972 D pjsip-pjsua_0: --end msg--
05-09 16:07:55.079  3883  3972 D pjsip-pjsua_0: 16:07:55.076 tlsc0xc1035414  Sending 4 byte(s) keep-alive to open-sips.gvs-icloud.com:5061
05-09 16:07:55.089  3883  3972 D pjsip-pjsua_0: 16:07:55.087  tsx0xc1da7864  Retransmit timer event
05-09 16:07:55.091  3883  3972 D pjsip-pjsua_0: 16:07:55.090  tsx0xc1da7864  .Retransmiting Response msg 180/INVITE/cseq=15161 (tdta0xc1dab064), count=82, restart?=1
05-09 16:07:55.093  3883  3972 D pjsip-pjsua_0: 16:07:55.092   pjsua_core.c  .TX 546 bytes Response msg 180/INVITE/cseq=15161 (tdta0xc1dab064) to UDP 192.168.1.105:5060:
05-09 16:07:55.093  3883  3972 D pjsip-pjsua_0: SIP/2.0 180 Ringing

05-09 16:07:55.093  3883  3972 D pjsip-pjsua_0: Via: SIP/2.0/UDP 192.168.1.105:5060;rport=5060;received=192.168.1.105;branch=z9hG4bKPjE9NuBy1TwUJVAtftQ06TRV3IjYIFPE84

05-09 16:07:55.093  3883  3972 D pjsip-pjsua_0: Call-ID: K1X4ZMmHAI.ra3EjP2kR-uhcsU6vJ-oT

05-09 16:07:55.093  3883  3972 D pjsip-pjsua_0: From: <sip:405579132320223232@192.168.1.105>;tag=Qd.we67wAtzidA6h01BCCJdiJV50mwdh

05-09 16:07:55.093  3883  3972 D pjsip-pjsua_0: To: <sip:457007454442622976@192.168.1.101>;tag=6b3241a5-5875-4830-9342-f0e73756ab43

05-09 16:07:55.093  3883  3972 D pjsip-pjsua_0: CSeq: 15161 INVITE

05-09 16:07:55.093  3883  3972 D pjsip-pjsua_0: Contact: <sip:457007454442622976@192.168.1.101:5060>

05-09 16:07:55.093  3883  3972 D pjsip-pjsua_0: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS

05-09 16:07:55.093  3883  3972 D pjsip-pjsua_0: Content-Length:  0

05-09 16:07:55.093  3883  3972 D pjsip-pjsua_0: 

05-09 16:07:55.093  3883  3972 D pjsip-pjsua_0: 
05-09 16:07:55.093  3883  3972 D pjsip-pjsua_0: --end msg--
05-09 16:07:55.106  3883  3972 D pjsip-pjsua_0: 16:07:55.104  tsx0xc10a0864  Retransmit timer event
05-09 16:07:55.108  3883  3972 D pjsip-pjsua_0: 16:07:55.107  tsx0xc10a0864  .Retransmiting Response msg 180/INVITE/cseq=679 (tdta0xc10b1064), count=77, restart?=1
05-09 16:07:55.110  3883  3972 D pjsip-pjsua_0: 16:07:55.108   pjsua_core.c  .TX 544 bytes Response msg 180/INVITE/cseq=679 (tdta0xc10b1064) to UDP 192.168.1.105:5060:
05-09 16:07:55.110  3883  3972 D pjsip-pjsua_0: SIP/2.0 180 Ringing

05-09 16:07:55.110  3883  3972 D pjsip-pjsua_0: Via: SIP/2.0/UDP 192.168.1.105:5060;rport=5060;received=192.168.1.105;branch=z9hG4bKPjWqOObqaXPE-r0BAa3fcjUlNSp2AE3jzh

05-09 16:07:55.110  3883  3972 D pjsip-pjsua_0: Call-ID: 82SGKjymwlqJY8Sj0a0NUm5RkQ6ibBNj

05-09 16:07:55.110  3883  3972 D pjsip-pjsua_0: From: <sip:405579132320223232@192.168.1.105>;tag=bQmB1e.pkib4aWc1lHtKwVQp2Vp7M8XL

05-09 16:07:55.110  3883  3972 D pjsip-pjsua_0: To: <sip:457007454442622976@192.168.1.101>;tag=00a628fc-065f-4785-983b-c3a88bb38036

05-09 16:07:55.110  3883  3972 D pjsip-pjsua_0: CSeq: 679 INVITE

05-09 16:07:55.110  3883  3972 D pjsip-pjsua_0: Contact: <sip:457007454442622976@192.168.1.101:5060>

05-09 16:07:55.110  3883  3972 D pjsip-pjsua_0: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS

05-09 16:07:55.110  3883  3972 D pjsip-pjsua_0: Content-Length:  0

05-09 16:07:55.110  3883  3972 D pjsip-pjsua_0: 

05-09 16:07:55.110  3883  3972 D pjsip-pjsua_0: 
05-09 16:07:55.110  3883  3972 D pjsip-pjsua_0: --end msg--
05-09 16:07:55.119  3883  3972 D pjsip-pjsua_0: 16:07:55.117  tsx0xc0d0f864  Retransmit timer event
05-09 16:07:55.124  3883  3972 D pjsip-pjsua_0: 16:07:55.120  tsx0xc0d0f864  .Retransmiting Response msg 180/INVITE/cseq=26050 (tdta0xc1124064), count=74, restart?=1
05-09 16:07:55.126  3883  3972 D pjsip-pjsua_0: 16:07:55.125   pjsua_core.c  .TX 546 bytes Response msg 180/INVITE/cseq=26050 (tdta0xc1124064) to UDP 192.168.1.105:5060:
05-09 16:07:55.126  3883  3972 D pjsip-pjsua_0: SIP/2.0 180 Ringing

05-09 16:07:55.126  3883  3972 D pjsip-pjsua_0: Via: SIP/2.0/UDP 192.168.1.105:5060;rport=5060;received=192.168.1.105;branch=z9hG4bKPjV7up2JcD9E.tIWj50zr9OwnNpGdFVona

05-09 16:07:55.126  3883  3972 D pjsip-pjsua_0: Call-ID: vmLtgqsbiYkZVieGXAgf8gLr2Z9DuWUW

05-09 16:07:55.126  3883  3972 D pjsip-pjsua_0: From: <sip:405579132320223232@192.168.1.105>;tag=GfzalxJUoyqZV4x8osehyWOXnjnwfmFm

05-09 16:07:55.126  3883  3972 D pjsip-pjsua_0: To: <sip:457007454442622976@192.168.1.101>;tag=4d993e27-331c-4ef3-8292-4bfdac19abc6

05-09 16:07:55.126  3883  3972 D pjsip-pjsua_0: CSeq: 26050 INVITE

05-09 16:07:55.126  3883  3972 D pjsip-pjsua_0: Contact: <sip:457007454442622976@192.168.1.101:5060>

05-09 16:07:55.126  3883  3972 D pjsip-pjsua_0: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS

05-09 16:07:55.126  3883  3972 D pjsip-pjsua_0: Content-Length:  0

05-09 16:07:55.126  3883  3972 D pjsip-pjsua_0: 

05-09 16:07:55.126  3883  3972 D pjsip-pjsua_0: 
05-09 16:07:55.126  3883  3972 D pjsip-pjsua_0: --end msg--
05-09 16:07:55.139  3883  3972 D pjsip-pjsua_0: 16:07:55.137  tsx0xc1da7064  Retransmit timer event
05-09 16:07:55.141  3883  3972 D pjsip-pjsua_0: 16:07:55.140  tsx0xc1da7064  .Retransmiting Response msg 180/INVITE/cseq=30412 (tdta0xc0125064), count=82, restart?=1
05-09 16:07:55.143  3883  3972 D pjsip-pjsua_0: 16:07:55.141   pjsua_core.c  .TX 546 bytes Response msg 180/INVITE/cseq=30412 (tdta0xc0125064) to UDP 192.168.1.105:5060:
05-09 16:07:55.143  3883  3972 D pjsip-pjsua_0: SIP/2.0 180 Ringing

05-09 16:07:55.143  3883  3972 D pjsip-pjsua_0: Via: SIP/2.0/UDP 192.168.1.105:5060;rport=5060;received=192.168.1.105;branch=z9hG4bKPj.fufsZLRxAI1i6byfP6tUtOvkRMqBKdG

05-09 16:07:55.143  3883  3972 D pjsip-pjsua_0: Call-ID: EcOYuklP7kKWKf-2QRQJdFuWeLeORjCN

05-09 16:07:55.143  3883  3972 D pjsip-pjsua_0: From: <sip:405579132320223232@192.168.1.105>;tag=rqKmQywreazelbtNLDqUzjZ6VYMzTbmy

05-09 16:07:55.143  3883  3972 D pjsip-pjsua_0: To: <sip:457007454442622976@192.168.1.101>;tag=3d29c01a-04de-45b6-87a3-c6475ec8d946

05-09 16:07:55.143  3883  3972 D pjsip-pjsua_0: CSeq: 30412 INVITE

05-09 16:07:55.143  3883  3972 D pjsip-pjsua_0: Contact: <sip:457007454442622976@192.168.1.101:5060>

05-09 16:07:55.143  3883  3972 D pjsip-pjsua_0: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS

05-09 16:07:55.143  3883  3972 D pjsip-pjsua_0: Content-Length:  0

05-09 16:07:55.143  3883  3972 D pjsip-pjsua_0: 

05-09 16:07:55.143  3883  3972 D pjsip-pjsua_0: 
05-09 16:07:55.143  3883  3972 D pjsip-pjsua_0: --end msg--
05-09 16:07:55.150  3883  3972 D pjsip-pjsua_0: 16:07:55.148  tsx0xc0ebc864  Retransmit timer event
05-09 16:07:55.151  3883  3972 D pjsip-pjsua_0: 16:07:55.150  tsx0xc0ebc864  .Retransmiting Response msg 180/INVITE/cseq=23522 (tdta0xc0c7f064), count=81, restart?=1
05-09 16:07:55.154  3883  3972 D pjsip-pjsua_0: 16:07:55.152   pjsua_core.c  .TX 546 bytes Response msg 180/INVITE/cseq=23522 (tdta0xc0c7f064) to UDP 192.168.1.105:5060:
05-09 16:07:55.154  3883  3972 D pjsip-pjsua_0: SIP/2.0 180 Ringing

05-09 16:07:55.154  3883  3972 D pjsip-pjsua_0: Via: SIP/2.0/UDP 192.168.1.105:5060;rport=5060;received=192.168.1.105;branch=z9hG4bKPj6B4D5qhyx.rHQLEqlP5cjfPEcNu6ps5F

05-09 16:07:55.154  3883  3972 D pjsip-pjsua_0: Call-ID: .OMi0scLXCdIs4BClqRMotG7GXmzMwl4

05-09 16:07:55.154  3883  3972 D pjsip-pjsua_0: From: <sip:405579132320223232@192.168.1.105>;tag=2efpyz-MbiubDBnVtBr6YgToI3Z.qz3F

05-09 16:07:55.154  3883  3972 D pjsip-pjsua_0: To: <sip:457007454442622976@192.168.1.101>;tag=9178bef9-4599-4614-bb60-542b3f3207d1

05-09 16:07:55.154  3883  3972 D pjsip-pjsua_0: CSeq: 23522 INVITE

05-09 16:07:55.154  3883  3972 D pjsip-pjsua_0: Contact: <sip:457007454442622976@192.168.1.101:5060>

05-09 16:07:55.154  3883  3972 D pjsip-pjsua_0: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS

05-09 16:07:55.154  3883  3972 D pjsip-pjsua_0: Content-Length:  0

05-09 16:07:55.154  3883  3972 D pjsip-pjsua_0: 

05-09 16:07:55.154  3883  3972 D pjsip-pjsua_0: 
05-09 16:07:55.154  3883  3972 D pjsip-pjsua_0: --end msg--
05-09 16:07:55.165  3883  3972 D pjsip-pjsua_0: 16:07:55.163  tsx0xc0d7e864  Retransmit timer event
05-09 16:07:55.167  3883  3972 D pjsip-pjsua_0: 16:07:55.166  tsx0xc0d7e864  .Retransmiting Response msg 180/INVITE/cseq=7762 (tdta0xc10d0064), count=80, restart?=1
05-09 16:07:55.169  3883  3972 D pjsip-pjsua_0: 16:07:55.168   pjsua_core.c  .TX 545 bytes Response msg 180/INVITE/cseq=7762 (tdta0xc10d0064) to UDP 192.168.1.105:5060:
05-09 16:07:55.169  3883  3972 D pjsip-pjsua_0: SIP/2.0 180 Ringing

05-09 16:07:55.169  3883  3972 D pjsip-pjsua_0: Via: SIP/2.0/UDP 192.168.1.105:5060;rport=5060;received=192.168.1.105;branch=z9hG4bKPjNXbHI9Ct7cziGjznLTWVpuw6LcyXUf6t

05-09 16:07:55.169  3883  3972 D pjsip-pjsua_0: Call-ID: 8G0WKnApuRye-miT-AFuabDuFUQaAug-

05-09 16:07:55.169  3883  3972 D pjsip-pjsua_0: From: <sip:405579132320223232@192.168.1.105>;tag=FmsPwB9540iZcx1wOJ-yTVV8uCxjGIfY

05-09 16:07:55.169  3883  3972 D pjsip-pjsua_0: To: <sip:457007454442622976@192.168.1.101>;tag=71a18fc9-52f6-4ba2-8171-29d2a071b849

05-09 16:07:55.169  3883  3972 D pjsip-pjsua_0: CSeq: 7762 INVITE

05-09 16:07:55.169  3883  3972 D pjsip-pjsua_0: Contact: <sip:457007454442622976@192.168.1.101:5060>

05-09 16:07:55.169  3883  3972 D pjsip-pjsua_0: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS

05-09 16:07:55.169  3883  3972 D pjsip-pjsua_0: Content-Length:  0

05-09 16:07:55.169  3883  3972 D pjsip-pjsua_0: 

05-09 16:07:55.169  3883  3972 D pjsip-pjsua_0: 
05-09 16:07:55.169  3883  3972 D pjsip-pjsua_0: --end msg--
05-09 16:07:55.176  3883  3972 D pjsip-pjsua_0: 16:07:55.174  tsx0xc0d7e064  Retransmit timer event
05-09 16:07:55.178  3883  3972 D pjsip-pjsua_0: 16:07:55.177  tsx0xc0d7e064  .Retransmiting Response msg 180/INVITE/cseq=8655 (tdta0xc0dfe064), count=84, restart?=1
05-09 16:07:55.180  3883  3972 D pjsip-pjsua_0: 16:07:55.178   pjsua_core.c  .TX 545 bytes Response msg 180/INVITE/cseq=8655 (tdta0xc0dfe064) to UDP 192.168.1.105:5060:
05-09 16:07:55.180  3883  3972 D pjsip-pjsua_0: SIP/2.0 180 Ringing

05-09 16:07:55.180  3883  3972 D pjsip-pjsua_0: Via: SIP/2.0/UDP 192.168.1.105:5060;rport=5060;received=192.168.1.105;branch=z9hG4bKPjoU2dooBGFrhPN1eNGNisViDB2Y1z2zbq

05-09 16:07:55.180  3883  3972 D pjsip-pjsua_0: Call-ID: aiBo92FsAyqrbfwTrKH4-BGbhBO31ezy

05-09 16:07:55.180  3883  3972 D pjsip-pjsua_0: From: <sip:405579132320223232@192.168.1.105>;tag=MdsJRulFfwjDC6WGeBTFixzaKNs-xrBc

05-09 16:07:55.180  3883  3972 D pjsip-pjsua_0: To: <sip:457007454442622976@192.168.1.101>;tag=453a63da-7ff7-498f-bc34-8841b5c427ed

05-09 16:07:55.180  3883  3972 D pjsip-pjsua_0: CSeq: 8655 INVITE

05-09 16:07:55.180  3883  3972 D pjsip-pjsua_0: Contact: <sip:457007454442622976@192.168.1.101:5060>

05-09 16:07:55.180  3883  3972 D pjsip-pjsua_0: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS

05-09 16:07:55.180  3883  3972 D pjsip-pjsua_0: Content-Length:  0

05-09 16:07:55.180  3883  3972 D pjsip-pjsua_0: 

05-09 16:07:55.180  3883  3972 D pjsip-pjsua_0: 
05-09 16:07:55.180  3883  3972 D pjsip-pjsua_0: --end msg--
05-09 16:07:55.182  3883  3972 D pjsip-pjsua_0: 16:07:55.181  tsx0xc017f864  Retransmit timer event
05-09 16:07:55.183  3883  3972 D pjsip-pjsua_0: 16:07:55.182  tsx0xc017f864  .Retransmiting Response msg 180/INVITE/cseq=29690 (tdta0xc1d6e064), count=75, restart?=1
05-09 16:07:55.186  3883  3972 D pjsip-pjsua_0: 16:07:55.184   pjsua_core.c  .TX 546 bytes Response msg 180/INVITE/cseq=29690 (tdta0xc1d6e064) to UDP 192.168.1.105:5060:
05-09 16:07:55.186  3883  3972 D pjsip-pjsua_0: SIP/2.0 180 Ringing

05-09 16:07:55.186  3883  3972 D pjsip-pjsua_0: Via: SIP/2.0/UDP 192.168.1.105:5060;rport=5060;received=192.168.1.105;branch=z9hG4bKPjef3Mw8bUuK2lMPI6Q.7K-I3ECqdXgZs0

05-09 16:07:55.186  3883  3972 D pjsip-pjsua_0: Call-ID: 7f0COaJwbSKrQ6R1rXm64htMZZxR7SAS

05-09 16:07:55.186  3883  3972 D pjsip-pjsua_0: From: <sip:405579132320223232@192.168.1.105>;tag=xWDeKTArb4emfgLpT.Ekywm-OXySYsKh

05-09 16:07:55.186  3883  3972 D pjsip-pjsua_0: To: <sip:457007454442622976@192.168.1.101>;tag=a82a25a1-f03b-4883-bb6a-77560d97d94c

05-09 16:07:55.186  3883  3972 D pjsip-pjsua_0: CSeq: 29690 INVITE

05-09 16:07:55.186  3883  3972 D pjsip-pjsua_0: Contact: <sip:457007454442622976@192.168.1.101:5060>

05-09 16:07:55.186  3883  3972 D pjsip-pjsua_0: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS

05-09 16:07:55.186  3883  3972 D pjsip-pjsua_0: Content-Length:  0

05-09 16:07:55.186  3883  3972 D pjsip-pjsua_0: 

05-09 16:07:55.186  3883  3972 D pjsip-pjsua_0: 
05-09 16:07:55.186  3883  3972 D pjsip-pjsua_0: --end msg--
05-09 16:07:55.199  3883  3972 D pjsip-pjsua_0: 16:07:55.197  tsx0xc01ce064  Retransmit timer event

05-09 16:07:55.204  3883  3972 D pjsip-pjsua_0: 16:07:55.203  tsx0xc01ce064  .Retransmiting Response msg 180/INVITE/cseq=1006 (tdta0xc10d3064), count=68, restart?=1
05-09 16:07:55.209  3883  3972 D pjsip-pjsua_0: 16:07:55.206   pjsua_core.c  .TX 545 bytes Response msg 180/INVITE/cseq=1006 (tdta0xc10d3064) to UDP 192.168.1.105:5060:
05-09 16:07:55.209  3883  3972 D pjsip-pjsua_0: SIP/2.0 180 Ringing

05-09 16:07:55.209  3883  3972 D pjsip-pjsua_0: Via: SIP/2.0/UDP 192.168.1.105:5060;rport=5060;received=192.168.1.105;branch=z9hG4bKPj5ob98Xjjryk99ozetpTpsFqNredNRxtZ

05-09 16:07:55.209  3883  3972 D pjsip-pjsua_0: Call-ID: ixnRdZ3hLli2aY73vRz9j7Hdz-QwUeEy

05-09 16:07:55.209  3883  3972 D pjsip-pjsua_0: From: <sip:405579132320223232@192.168.1.105>;tag=RAVXAk-7qr6oh2h5wShpP4B4c4GT6HoI

05-09 16:07:55.209  3883  3972 D pjsip-pjsua_0: To: <sip:457007454442622976@192.168.1.101>;tag=7881380d-8b50-40ad-b340-7c7744261100

05-09 16:07:55.209  3883  3972 D pjsip-pjsua_0: CSeq: 1006 INVITE

05-09 16:07:55.209  3883  3972 D pjsip-pjsua_0: Contact: <sip:457007454442622976@192.168.1.101:5060>

05-09 16:07:55.209  3883  3972 D pjsip-pjsua_0: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS

05-09 16:07:55.209  3883  3972 D pjsip-pjsua_0: Content-Length:  0

05-09 16:07:55.209  3883  3972 D pjsip-pjsua_0: 

05-09 16:07:55.209  3883  3972 D pjsip-pjsua_0: 
05-09 16:07:55.209  3883  3972 D pjsip-pjsua_0: --end msg--


05-09 16:07:55.294  3883  3972 D pjsip-pjsua_0: 16:07:55.293  tsx0xc0ca3864  Timeout timer event
05-09 16:07:55.296  3883  3972 D pjsip-pjsua_0: 16:07:55.295  tsx0xc0ca3864  .State changed from Calling to Terminated, event=TIMER
05-09 16:07:55.297  3883  3972 D pjsip-pjsua_0: 16:07:55.297    pjsua_acc.c  ...2contact_rewritten:0,rewrite_method:6,code:408

05-09 16:07:55.299  3883  3972 D pjsip-pjsua_0: 16:07:55.298    pjsua_acc.c  ...SIP registration failed, status=408 (Request Timeout)
05-09 16:07:55.300  3883  3972 D pjsip-pjsua_0: 16:07:55.299    pjsua_acc.c  ...Scheduling re-registration retry for acc 1 in 18 seconds..


05-09 16:07:55.302  3883  3972 D PjsipBusiness: loginCloudAccount:1737342028959842304-----open-sips.gvs-icloud.com-----408

05-09 16:07:55.313  3883  3972 D pjsip-pjsua_0: 16:07:55.311  tsx0xc0ca3864  Timeout timer event
05-09 16:07:55.314  3883  3972 D pjsip-pjsua_0: 16:07:55.313  tsx0xc0ca3864  .State changed from Terminated to Destroyed, event=TIMER
05-09 16:07:55.316  3883  3972 D pjsip-pjsua_0: 16:07:55.315 tdta0xc0aea064  ..Destroying txdata Request msg REGISTER/cseq=20030 (tdta0xc0aea064)
05-09 16:07:55.317  3883  3972 D pjsip-pjsua_0: 16:07:55.316  tsx0xc0ca3864  Transaction destroyed!




















05-09 16:08:05.001  3883  3972 D pjsip-pjsua_0: 16:08:04.999 tlsc0xc1035414  TLS connection closed
05-09 16:08:05.011  3883  3972 D pjsip-pjsua_0: 16:08:05.007    pjsua_acc.c  Disconnected notification for transport tlsc0xc1035414
05-09 16:08:05.014  3883  3972 D pjsip-pjsua_0: 16:08:05.013 sip_transport.  .Transport tlsc0xc1035414 shutting down, force=0
05-09 16:08:05.020  3883  3972 D pjsip-pjsua_0: 16:08:05.018 tlsc0xc1035414  TLS transport destroyed with reason 120110: Connection timed out























05-09 16:08:13.321  3883  3972 D pjsip-pjsua_0: 16:08:13.320 addr_resolv_so  DNS resolver pj_getaddrinfo
05-09 16:08:13.322  3883  3972 D pjsip-pjsua_0: 16:08:13.321 addr_resolv_so  DNS resolver pj_getaddrinfo1
05-09 16:08:13.335  3883  3972 D pjsip-pjsua_0: 16:08:13.334 addr_resolv_so  DNS resolver pj_getaddrinfo2
05-09 16:08:13.336  3883  3972 D pjsip-pjsua_0: 16:08:13.335 addr_resolv_so  DNS resolver pj_getaddrinfo3
05-09 16:08:13.338  3883  3972 D pjsip-pjsua_0: 16:08:13.337 tlsc0xc1034014  TLS client transport created
05-09 16:08:13.341  3883  3972 D pjsip-pjsua_0: 16:08:13.340 tlsc0xc1034014  TLS transport 192.168.1.101:49159 is connecting to open-sips.gvs-icloud.com:5061...
05-09 16:08:13.343  3883  3972 D pjsip-pjsua_0: 16:08:13.342    pjsua_acc.c  2acc->contact: <sip:1737342028959842304@192.168.1.101:49159;transport=TLS;ob>
05-09 16:08:13.344  3883  3972 D pjsip-pjsua_0: 16:08:13.344    pjsua_acc.c  Contact for acc 1 updated: <sip:1737342028959842304@192.168.1.101:49159;transport=TLS;ob>;reg-id=1;+sip.instance="<urn:uuid:00000000-0000-0000-0000-0000e922f243>"
05-09 16:08:13.345  3883  3972 D pjsip-pjsua_0: 16:08:13.345    pjsua_acc.c  Acc 1: setting registration..
05-09 16:08:13.349  3883  3972 D pjsip-pjsua_0: 16:08:13.348       endpoint  .Request msg REGISTER/cseq=43928 (tdta0xc1f7f064) created.
05-09 16:08:13.352  3883  3972 D pjsip-pjsua_0: 16:08:13.351 addr_resolv_so  .DNS resolver pj_getaddrinfo
05-09 16:08:13.354  3883  3972 D pjsip-pjsua_0: 16:08:13.353 addr_resolv_so  .DNS resolver pj_getaddrinfo1
05-09 16:08:13.365  3883  3972 D pjsip-pjsua_0: 16:08:13.363 addr_resolv_so  .DNS resolver pj_getaddrinfo2
05-09 16:08:13.370  3883  3972 D pjsip-pjsua_0: 16:08:13.366 addr_resolv_so  .DNS resolver pj_getaddrinfo3
05-09 16:08:13.373  3883  3972 D pjsip-pjsua_0: 16:08:13.372  tsx0xcbd5f064  ..Transaction created for Request msg REGISTER/cseq=43929 (tdta0xc1f7f064)
05-09 16:08:13.374  3883  3972 D pjsip-pjsua_0: 16:08:13.374  tsx0xcbd5f064  .Sending Request msg REGISTER/cseq=43929 (tdta0xc1f7f064) in state Null
05-09 16:08:13.376  3883  3972 D pjsip-pjsua_0: 16:08:13.375  sip_resolve.c  ..DNS resolver not available, target 'open-sips.gvs-icloud.com:5061' type=TLS will be resolved with getaddrinfo()
05-09 16:08:13.377  3883  3972 D pjsip-pjsua_0: 16:08:13.376 addr_resolv_so  ..DNS resolver pj_getaddrinfo
05-09 16:08:13.378  3883  3972 D pjsip-pjsua_0: 16:08:13.377 addr_resolv_so  ..DNS resolver pj_getaddrinfo1
05-09 16:08:13.381  3883  3972 D pjsip-pjsua_0: 16:08:13.380 addr_resolv_so  ..DNS resolver pj_getaddrinfo2
05-09 16:08:13.383  3883  3972 D pjsip-pjsua_0: 16:08:13.382 addr_resolv_so  ..DNS resolver pj_getaddrinfo3
05-09 16:08:13.385  3883  3972 D pjsip-pjsua_0: 16:08:13.384  sip_resolve.c  ..Target 'open-sips.gvs-icloud.com:5061' type=TLS resolved to '47.112.115.103:5061' type=TLS (TLS transport)
05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: 16:08:13.385   pjsua_core.c  ..TX 804 bytes Request msg REGISTER/cseq=43929 (tdta0xc1f7f064) to TLS 47.112.115.103:5061:
05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: REGISTER sip:open-sips.gvs-icloud.com:5061 SIP/2.0

05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: Via: SIP/2.0/TLS 192.168.1.101:49159;rport;branch=z9hG4bKPjc8343b47-c40f-4319-abe7-94db97f4c739;alias

05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: Route: <sip:open-sips.gvs-icloud.com:5061;transport=tls;lr>

05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: Max-Forwards: 70

05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: From: <sip:1737342028959842304@open-sips.gvs-icloud.com>;tag=d630a749-7127-46f1-af2c-c5016c5ede59

05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: To: <sip:1737342028959842304@open-sips.gvs-icloud.com>

05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: Call-ID: 9d44aa7c-d76a-4672-aa29-4e403014f5d9

05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: CSeq: 43929 REGISTER

05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: User-Agent: Pjsua2 Android 2.12.1-dev

05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: Supported: outbound, path

05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: Contact: <sip:1737342028959842304@192.168.1.101:49159;transport=TLS;ob>;reg-id=1;+sip.instance="<urn:uuid:00000000-0000-0000-0000-0000e922f243>"

05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: Expires: 600

05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS

05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: Content-Length:  0

05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: 

05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: 
05-09 16:08:13.386  3883  3972 D pjsip-pjsua_0: --end msg--
05-09 16:08:13.387  3883  3972 D pjsip-pjsua_0: 16:08:13.386  tsx0xcbd5f064  ..State changed from Null to Calling, event=TX_MSG
05-09 16:08:13.388  3883  3972 D pjsip-pjsua_0: 16:08:13.388    pjsua_acc.c  .Acc 1: Registration sent
05-09 16:08:13.467  3883  3972 D pjsip-pjsua_0: 16:08:13.466 tlsc0xc1034014  TLS transport 192.168.1.101:49159 is connected to open-sips.gvs-icloud.com:5061
05-09 16:08:13.501  3883  3972 D pjsip-pjsua_0: 16:08:13.499 sip_endpoint.c  Processing incoming message: Response msg 401/REGISTER/cseq=43929 (rdata0xc10341cc)
05-09 16:08:13.503  3883  3972 D pjsip-pjsua_0: 16:08:13.502   pjsua_core.c  .RX 611 bytes Response msg 401/REGISTER/cseq=43929 (rdata0xc10341cc) from TLS 47.112.115.103:5061:
05-09 16:08:13.503  3883  3972 D pjsip-pjsua_0: SIP/2.0 401 Unauthorized

05-09 16:08:13.503  3883  3972 D pjsip-pjsua_0: Via: SIP/2.0/TLS 192.168.1.101:49159;received=14.19.179.110;rport=49159;branch=z9hG4bKPjc8343b47-c40f-4319-abe7-94db97f4c739;alias

05-09 16:08:13.503  3883  3972 D pjsip-pjsua_0: From: <sip:1737342028959842304@open-sips.gvs-icloud.com>;tag=d630a749-7127-46f1-af2c-c5016c5ede59

05-09 16:08:13.503  3883  3972 D pjsip-pjsua_0: To: <sip:1737342028959842304@open-sips.gvs-icloud.com>;tag=70ab.3be40540eecccc4a43089b901f971570

05-09 16:08:13.503  3883  3972 D pjsip-pjsua_0: Call-ID: 9d44aa7c-d76a-4672-aa29-4e403014f5d9

05-09 16:08:13.503  3883  3972 D pjsip-pjsua_0: CSeq: 43929 REGISTER

05-09 16:08:13.503  3883  3972 D pjsip-pjsua_0: WWW-Authenticate: Digest realm="open-sips.gvs-icloud.com", nonce="7/5SzcwD30nXCLMFLQ5dabVZC9OiznLPgzLkG0mbjNAA", qop="auth"

05-09 16:08:13.503  3883  3972 D pjsip-pjsua_0: Server: OpenSIPS (3.2.3 (x86_64/linux))

05-09 16:08:13.503  3883  3972 D pjsip-pjsua_0: Content-Length: 0

05-09 16:08:13.503  3883  3972 D pjsip-pjsua_0: 

05-09 16:08:13.503  3883  3972 D pjsip-pjsua_0: 
05-09 16:08:13.503  3883  3972 D pjsip-pjsua_0: --end msg--
05-09 16:08:13.504  3883  3972 D pjsip-pjsua_0: 16:08:13.503  tsx0xcbd5f064  .Incoming Response msg 401/REGISTER/cseq=43929 (rdata0xc10341cc) in state Calling
05-09 16:08:13.505  3883  3972 D pjsip-pjsua_0: 16:08:13.504  tsx0xcbd5f064  ..State changed from Calling to Completed, event=RX_MSG
05-09 16:08:13.506  3883  3972 D pjsip-pjsua_0: 16:08:13.505    pjsua_acc.c  ....1contact_rewritten:0,rewrite_method:6,code:401

05-09 16:08:13.507  3883  3972 D pjsip-pjsua_0: 16:08:13.506    pjsua_acc.c  ....acc->contact:<sip:1737342028959842304@192.168.1.101:49159;transport=TLS;ob>
05-09 16:08:13.508  3883  3972 D pjsip-pjsua_0: 16:08:13.507    pjsua_acc.c  ....IP address change detected for account 1 (192.168.1.101:49159 --> 14.19.179.110:49159). Updating registration (using method 4)
05-09 16:08:13.509  3883  3972 D pjsip-pjsua_0: 16:08:13.508    pjsua_acc.c  ....1acc->contact: <sip:1737342028959842304@14.19.179.110:49159;transport=TLS;ob>
05-09 16:08:13.511  3883  3972 D pjsip-pjsua_0: 16:08:13.510    pjsua_acc.c  ....Contact for acc 1 updated: <sip:1737342028959842304@14.19.179.110:49159;transport=TLS;ob>;reg-id=1;+sip.instance="<urn:uuid:00000000-0000-0000-0000-0000e922f243>"
05-09 16:08:13.514  3883  3972 D pjsip-pjsua_0: 16:08:13.513 sip_auth_clien  ...Digest algorithm is ""
05-09 16:08:13.523  3883  3972 D pjsip-pjsua_0: 16:08:13.521  tsx0xc0156864  ....Transaction created for Request msg REGISTER/cseq=43930 (tdta0xc1f7f064)
05-09 16:08:13.529  3883  3972 D pjsip-pjsua_0: 16:08:13.525  tsx0xc0156864  ...Sending Request msg REGISTER/cseq=43930 (tdta0xc1f7f064) in state Null
05-09 16:08:13.531  3883  3972 D pjsip-pjsua_0: 16:08:13.530       endpoint  ....Request msg REGISTER/cseq=43930 (tdta0xc1f7f064): skipping target resolution because address is already set
05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: 16:08:13.534   pjsua_core.c  ....TX 1098 bytes Request msg REGISTER/cseq=43930 (tdta0xc1f7f064) to TLS 47.112.115.103:5061:
05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: REGISTER sip:open-sips.gvs-icloud.com:5061 SIP/2.0

05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: Via: SIP/2.0/TLS 14.19.179.110:49159;rport;branch=z9hG4bKPj4f144793-7910-4e22-a173-a81cb48e51ff;alias

05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: Route: <sip:open-sips.gvs-icloud.com:5061;transport=tls;lr>

05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: Max-Forwards: 70

05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: From: <sip:1737342028959842304@open-sips.gvs-icloud.com>;tag=d630a749-7127-46f1-af2c-c5016c5ede59

05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: To: <sip:1737342028959842304@open-sips.gvs-icloud.com>

05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: Call-ID: 9d44aa7c-d76a-4672-aa29-4e403014f5d9

05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: CSeq: 43930 REGISTER

05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: User-Agent: Pjsua2 Android 2.12.1-dev

05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: Supported: outbound, path

05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: Contact: <sip:1737342028959842304@14.19.179.110:49159;transport=TLS;ob>;reg-id=1;+sip.instance="<urn:uuid:00000000-0000-0000-0000-0000e922f243>"

05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: Expires: 600

05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS

05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: Authorization: Digest username="1737342028959842304", realm="open-sips.gvs-icloud.com", nonce="7/5SzcwD30nXCLMFLQ5dabVZC9OiznLPgzLkG0mbjNAA", uri="sip:open-sips.gvs-icloud.com:5061", response="b2856052e744cd42003bb2fe71f3b55f", cnonce="8fff2aee4a084081a007b3b75791d505", qop=auth, nc=00000001

05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: Content-Length:  0

05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: 

05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: 
05-09 16:08:13.536  3883  3972 D pjsip-pjsua_0: --end msg--
05-09 16:08:13.538  3883  3972 D pjsip-pjsua_0: 16:08:13.537  tsx0xc0156864  ....State changed from Null to Calling, event=TX_MSG
05-09 16:08:13.541  3883  3972 D pjsip-pjsua_0: 16:08:13.539  tsx0xcbd5f064  Timeout timer event
05-09 16:08:13.543  3883  3972 D pjsip-pjsua_0: 16:08:13.541  tsx0xcbd5f064  .State changed from Completed to Terminated, event=TIMER
05-09 16:08:13.549  3883  3972 D pjsip-pjsua_0: 16:08:13.547  tsx0xcbd5f064  Timeout timer event
05-09 16:08:13.550  3883  3972 D pjsip-pjsua_0: 16:08:13.549  tsx0xcbd5f064  .State changed from Terminated to Destroyed, event=TIMER
05-09 16:08:13.551  3883  3972 D pjsip-pjsua_0: 16:08:13.551  tsx0xcbd5f064  Transaction destroyed!
05-09 16:08:13.572  3883  3972 D pjsip-pjsua_0: 16:08:13.571 sip_endpoint.c  Processing incoming message: Response msg 200/REGISTER/cseq=43930 (rdata0xc10341cc)
05-09 16:08:13.574  3883  3972 D pjsip-pjsua_0: 16:08:13.573   pjsua_core.c  .RX 610 bytes Response msg 200/REGISTER/cseq=43930 (rdata0xc10341cc) from TLS 47.112.115.103:5061:
05-09 16:08:13.574  3883  3972 D pjsip-pjsua_0: SIP/2.0 200 OK

05-09 16:08:13.574  3883  3972 D pjsip-pjsua_0: Via: SIP/2.0/TLS 14.19.179.110:49159;received=14.19.179.110;rport=49159;branch=z9hG4bKPj4f144793-7910-4e22-a173-a81cb48e51ff;alias

05-09 16:08:13.574  3883  3972 D pjsip-pjsua_0: From: <sip:1737342028959842304@open-sips.gvs-icloud.com>;tag=d630a749-7127-46f1-af2c-c5016c5ede59

05-09 16:08:13.574  3883  3972 D pjsip-pjsua_0: To: <sip:1737342028959842304@open-sips.gvs-icloud.com>;tag=70ab.d82bfc473dfa0beff6934b2053ea1df8

05-09 16:08:13.574  3883  3972 D pjsip-pjsua_0: Call-ID: 9d44aa7c-d76a-4672-aa29-4e403014f5d9

05-09 16:08:13.574  3883  3972 D pjsip-pjsua_0: CSeq: 43930 REGISTER

05-09 16:08:13.574  3883  3972 D pjsip-pjsua_0: Contact: <sip:1737342028959842304@14.19.179.110:49159;transport=TLS;ob>;expires=600;received="sip:14.19.179.110:49159;transport=tls"

05-09 16:08:13.574  3883  3972 D pjsip-pjsua_0: Server: OpenSIPS (3.2.3 (x86_64/linux))

05-09 16:08:13.574  3883  3972 D pjsip-pjsua_0: Content-Length: 0

05-09 16:08:13.574  3883  3972 D pjsip-pjsua_0: 

05-09 16:08:13.574  3883  3972 D pjsip-pjsua_0: 
05-09 16:08:13.574  3883  3972 D pjsip-pjsua_0: --end msg--
05-09 16:08:13.575  3883  3972 D pjsip-pjsua_0: 16:08:13.574  tsx0xc0156864  .Incoming Response msg 200/REGISTER/cseq=43930 (rdata0xc10341cc) in state Calling
05-09 16:08:13.577  3883  3972 D pjsip-pjsua_0: 16:08:13.576  tsx0xc0156864  ..State changed from Calling to Completed, event=RX_MSG
05-09 16:08:13.578  3883  3972 D pjsip-pjsua_0: 16:08:13.577    pjsua_acc.c  ....2contact_rewritten:0,rewrite_method:6,code:200

05-09 16:08:13.579  3883  3972 D pjsip-pjsua_0: 16:08:13.578    pjsua_acc.c  ....SIP outbound status for acc 1 is not active
05-09 16:08:13.580  3883  3972 D pjsip-pjsua_0: 16:08:13.579    pjsua_acc.c  ....acc->contact:<sip:1737342028959842304@14.19.179.110:49159;transport=TLS;ob>
05-09 16:08:13.581  3883  3972 D pjsip-pjsua_0: 16:08:13.580    pjsua_acc.c  ....sip:1737342028959842304@open-sips.gvs-icloud.com: registration success, status=200 (OK), will re-register in 600 seconds


05-09 16:08:13.583  3883  3972 D PjsipBusiness: loginCloudAccount:1737342028959842304-----open-sips.gvs-icloud.com-----200
05-09 16:08:13.585  3883  3972 D pjsip-pjsua_0: 16:08:13.584  tsx0xc0156864  Timeout timer event
05-09 16:08:13.587  3883  3972 D pjsip-pjsua_0: 16:08:13.586  tsx0xc0156864  .State changed from Completed to Terminated, event=TIMER
05-09 16:08:13.592  3883  3972 D pjsip-pjsua_0: 16:08:13.590  tsx0xc0156864  Timeout timer event
05-09 16:08:13.593  3883  3972 D pjsip-pjsua_0: 16:08:13.592  tsx0xc0156864  .State changed from Terminated to Destroyed, event=TIMER
05-09 16:08:13.598  3883  3972 D pjsip-pjsua_0: 16:08:13.594 tdta0xc1f7f064  ..Destroying txdata Request msg REGISTER/cseq=43930 (tdta0xc1f7f064)
05-09 16:08:13.600  3883  3972 D pjsip-pjsua_0: 16:08:13.598  tsx0xc0156864  Transaction destroyed!
@sauwming
Copy link
Member

sauwming commented May 10, 2024

This seems to be a pretty standard scenario: Remote cancelled the call during early state.
When testing it here using 100rel, it seems to work fine. The 180 retransmission will cease upon the call cancellation.

So there might be some unexpected situation here that caused the retransmission to continue (Btw, the timing is also a bit curious here with the exception happening at 15:20 while the rest of the log is at 16:07.) But unfortunately, without a more detailed log (that should at least include the INVITE message and the CANCEL), or steps to reproduce, there's not much we can do here.

@zhuangguangkang0013
Copy link
Author

This seems to be a pretty standard scenario: Remote cancelled the call during early state. When testing it here using 100rel, it seems to work fine. The 180 retransmission will cease upon the call cancellation.

So there might be some unexpected situation here that caused the retransmission to continue (Btw, the timing is also a bit curious here with the exception happening at 15:20 while the rest of the log is at 16:07.) But unfortunately, without a more detailed log (that should at least include the INVITE message and the CANCEL), or steps to reproduce, there's not much we can do here.

Yes, both A and B. Device B crashed during the call, causing device A to have problems as described in the log. Regarding exceptions, the exception log at 15 o'clock is not stored. Starting from the 180 retransmission, the null exception begins to exist. I just found a more detailed log. Observing from the latest logs obtained does not seem to have anything to do with this. About this exception. Let me ask another question

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants