Re: [sr-dev] [kamailio/kamailio] Problem with re-invite and bye from uas when re-invite fail using topos module (#1571)

Thank's for your reply. I see that the a_contact field in topos_d table have the re-Invite contact value and it will be update only when will be receive 200 ok. I think that the problem is in db update management when requests within dialog receives failure/redirect final reply. I try to comment the code where del topos_d will be update at re-invite receive and my scenario works. I don't know if this is a valid modify.

Here are the required traces with master version:

**topos_d during call in stable state**

id: 4 rectime: 2018-06-25 10:10:37 s_method: INVITE s_cseq: 102 a_callid: 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133 a_uuid: atpsh-5b309a9f-35ed-1 b_uuid: btpsh-5b309a9f-35ed-1 a_contact: sip:0861772506@10.10.10.133 b_contact: sip:200@10.10.10.84 as_contact: sip:atpsh-5b309a9f-35ed-1@10.10.10.1 bs_contact: sip:btpsh-5b309a9f-35ed-1@10.10.10.1 a_tag: as159ff76a b_tag: 8A1124D-F7DB8FD4 a_rr: sip:10.10.10.1;lr b_rr: 2 s_rr: iflags: a_uri: b_uri: r_uri: a_srcaddr: b_srcaddr: a_socket: b_socket:

**topos_d after re-invite failure** id: 4 rectime: 2018-06-25 10:10:37 s_method: INVITE s_cseq: 102 a_callid: 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133 a_uuid: atpsh-5b309a9f-35ed-1 b_uuid: btpsh-5b309a9f-35ed-1 **a_contact: sip:200@10.10.10.84** b_contact: sip:200@10.10.10.84 as_contact: sip:atpsh-5b309a9f-35ed-1@10.10.10.1 bs_contact: sip:btpsh-5b309a9f-35ed-1@10.10.10.1 a_tag: as159ff76a b_tag: 8A1124D-F7DB8FD4 a_rr: sip:10.10.10.1;lr b_rr: 2 s_rr: iflags: a_uri: b_uri: r_uri: a_srcaddr: b_srcaddr: a_socket: b_socket:

Syslog trace grepped for "topos":

**when Enstablish call** Jun 25 10:10:37 kamailio[13805]: {1 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:934]: tps_request_sent(): handling outgoing request (0, 0) Jun 25 10:10:37 kamailio[13805]: {1 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 10.10.10.1;branch=z9hG4bKf9bf.5ca6ea47b4e30bae0c42b77f8e6fdb3a.0](76) - x_via2: [SIP/2.0/UDP 10.10.10.133:5060;received=10.10.10.133;branch=z9hG4bK4860a41f;rport=5060](85) - x_vbranch1: [z9hG4bKf9bf.5ca6ea47b4e30bae0c42b77f8e6fdb3a.0](46) Jun 25 10:10:37 kamailio[13805]: {1 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:435]: tps_pack_message(): single record routing by proxy Jun 25 10:10:37 kamailio[13805]: {1 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [sip:10.10.10.1;lr](19) Jun 25 10:10:37 kamailio[13805]: {1 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [sip:10.10.10.1;lr](17) - bs_contact: [sip:10.10.10.1;lr](17) Jun 25 10:10:37 kamailio[13805]: {1 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 206 Jun 25 10:10:37 kamailio[13805]: {1 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:972]: tps_db_load_branch(): no stored record for <z9hG4bKf9bf.5ca6ea47b4e30bae0c42b77f8e6fdb3a.0> Jun 25 10:10:37 kamailio[13805]: {1 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:363]: tps_storage_link_msg(): downstream: yes acontact: [sip:0861772506@10.10.10.133] bcontact: [] Jun 25 10:10:37 kamailio[13805]: {1 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(1) - [Via: SIP/2.0/UDP 10.10.10.1;branch=z9hG4bKf9bf.5ca6ea47b4e30bae0c42b77f8e6fdb3a.0#015#012] Jun 25 10:10:37 kamailio[13805]: {1 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [Contact: sip:btpsh-5b309a9f-35ed-1@10.10.10.1#015#012] Jun 25 10:10:37 kamailio[13805]: {1 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 206 Jun 25 10:10:37 kamailio[13807]: DEBUG: topos [tps_msg.c:868]: tps_response_received(): handling incoming response Jun 25 10:10:37 kamailio[13807]: DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 10.10.10.1;branch=z9hG4bKf9bf.5ca6ea47b4e30bae0c42b77f8e6fdb3a.0](76) - x_via2: [](0) - x_vbranch1: [z9hG4bKf9bf.5ca6ea47b4e30bae0c42b77f8e6fdb3a.0](46) Jun 25 10:10:37 kamailio[13807]: DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) Jun 25 10:10:37 kamailio[13807]: DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) Jun 25 10:10:37 kamailio[13807]: DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 206 Jun 25 10:10:37 kamailio[13807]: DEBUG: topos [tps_msg.c:889]: tps_response_received(): loaded dialog a_uuid [atpsh-5b309a9f-35ed-1] Jun 25 10:10:37 kamailio[13807]: DEBUG: topos [tps_storage.c:363]: tps_storage_link_msg(): downstream: yes acontact: [] bcontact: [sip:200@10.10.10.84] Jun 25 10:10:37 kamailio[13807]: DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 206 Jun 25 10:10:37 kamailio[13807]: DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [Via: SIP/2.0/UDP 10.10.10.133:5060;received=10.10.10.133;branch=z9hG4bK4860a41f;rport=5060#015#012] Jun 25 10:10:37 kamailio[13807]: DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [Record-Route: sip:10.10.10.1;lr#015#012] Jun 25 10:10:37 kamailio[13807]: DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [P-SR-XBranch: z9hG4bKf9bf.5ca6ea47b4e30bae0c42b77f8e6fdb3a.0#015#012] Jun 25 10:10:37 kamailio[13807]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:1031]: tps_response_sent(): handling outgoing response Jun 25 10:10:37 kamailio[13807]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 10.10.10.133:5060;received=10.10.10.133;branch=z9hG4bK4860a41f;rport=5060](85) - x_via2: [](0) - x_vbranch1: [z9hG4bK4860a41f](15) Jun 25 10:10:37 kamailio[13807]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [sip:10.10.10.1;lr](19) - s_rr: [](0) Jun 25 10:10:37 kamailio[13807]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) Jun 25 10:10:37 kamailio[13807]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 206 Jun 25 10:10:37 kamailio[13807]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:1061]: tps_response_sent(): loaded branch a_uuid [atpsh-5b309a9f-35ed-1] Jun 25 10:10:37 kamailio[13807]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 206 Jun 25 10:10:37 kamailio[13807]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [Contact: sip:atpsh-5b309a9f-35ed-1@10.10.10.1#015#012] Jun 25 10:10:37 kamailio[13807]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:363]: tps_storage_link_msg(): downstream: yes acontact: [] bcontact: [sip:200@10.10.10.84] Jun 25 10:10:39 kamailio[13808]: DEBUG: topos [tps_msg.c:868]: tps_response_received(): handling incoming response Jun 25 10:10:39 kamailio[13808]: DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 10.10.10.1;branch=z9hG4bKf9bf.5ca6ea47b4e30bae0c42b77f8e6fdb3a.0](76) - x_via2: [](0) - x_vbranch1: [z9hG4bKf9bf.5ca6ea47b4e30bae0c42b77f8e6fdb3a.0](46) Jun 25 10:10:39 kamailio[13808]: DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) Jun 25 10:10:39 kamailio[13808]: DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) Jun 25 10:10:39 kamailio[13808]: DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 206 Jun 25 10:10:39 kamailio[13808]: DEBUG: topos [tps_msg.c:889]: tps_response_received(): loaded dialog a_uuid [atpsh-5b309a9f-35ed-1] Jun 25 10:10:39 kamailio[13808]: DEBUG: topos [tps_storage.c:363]: tps_storage_link_msg(): downstream: yes acontact: [] bcontact: [sip:200@10.10.10.84] Jun 25 10:10:39 kamailio[13808]: DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 206 Jun 25 10:10:39 kamailio[13808]: DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [Via: SIP/2.0/UDP 10.10.10.133:5060;received=10.10.10.133;branch=z9hG4bK4860a41f;rport=5060#015#012] Jun 25 10:10:39 kamailio[13808]: DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [Record-Route: sip:10.10.10.1;lr#015#012] Jun 25 10:10:39 kamailio[13808]: DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [P-SR-XBranch: z9hG4bKf9bf.5ca6ea47b4e30bae0c42b77f8e6fdb3a.0#015#012] Jun 25 10:10:39 kamailio[13808]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:1031]: tps_response_sent(): handling outgoing response Jun 25 10:10:39 kamailio[13808]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 10.10.10.133:5060;received=10.10.10.133;branch=z9hG4bK4860a41f;rport=5060](85) - x_via2: [](0) - x_vbranch1: [z9hG4bK4860a41f](15) Jun 25 10:10:39 kamailio[13808]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [sip:10.10.10.1;lr](19) - s_rr: [](0) Jun 25 10:10:39 kamailio[13808]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) Jun 25 10:10:39 kamailio[13808]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 206 Jun 25 10:10:39 kamailio[13808]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:1061]: tps_response_sent(): loaded branch a_uuid [atpsh-5b309a9f-35ed-1] Jun 25 10:10:39 kamailio[13808]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 206 Jun 25 10:10:39 kamailio[13808]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [Contact: sip:atpsh-5b309a9f-35ed-1@10.10.10.1#015#012] Jun 25 10:10:39 kamailio[13808]: {2 102 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:363]: tps_storage_link_msg(): downstream: yes acontact: [] bcontact: [sip:200@10.10.10.84] Jun 25 10:10:39 kamailio[13803]: DEBUG: topos [tps_msg.c:742]: tps_request_received(): handling incoming request Jun 25 10:10:39 kamailio[13803]: DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 10.10.10.133:5060;branch=z9hG4bK23de55ba;rport](58) - x_via2: [](0) - x_vbranch1: [z9hG4bK23de55ba](15) Jun 25 10:10:39 kamailio[13803]: DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) Jun 25 10:10:39 kamailio[13803]: DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) Jun 25 10:10:39 kamailio[13803]: DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 206 Jun 25 10:10:39 kamailio[13803]: DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 206 Jun 25 10:10:39 kamailio[13803]: DEBUG: topos [tps_msg.c:816]: tps_request_received(): r-uri updated to: [sip:200@10.10.10.84] Jun 25 10:10:39 kamailio[13803]: DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [Route: sip:10.10.10.1;lr#015#012] Jun 25 10:10:39 kamailio[13803]: DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [P-SR-XUID: atpsh-5b309a9f-35ed-1#015#012] Jun 25 10:10:39 kamailio[13803]: {1 102 ACK 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:934]: tps_request_sent(): handling outgoing request (1, 0) Jun 25 10:10:39 kamailio[13803]: {1 102 ACK 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 10.10.10.1;branch=z9hG4bKf9bf.70b67570f198c08f4c8bfccda86ed225.0](76) - x_via2: [SIP/2.0/UDP 10.10.10.133:5060;received=10.10.10.133;branch=z9hG4bK23de55ba;rport=5060](85) - x_vbranch1: [z9hG4bKf9bf.70b67570f198c08f4c8bfccda86ed225.0](46) Jun 25 10:10:39 kamailio[13803]: {1 102 ACK 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) Jun 25 10:10:39 kamailio[13803]: {1 102 ACK 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) Jun 25 10:10:39 kamailio[13803]: {1 102 ACK 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 206 Jun 25 10:10:39 kamailio[13803]: {1 102 ACK 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:972]: tps_db_load_branch(): no stored record for <z9hG4bKf9bf.70b67570f198c08f4c8bfccda86ed225.0> Jun 25 10:10:39 kamailio[13803]: {1 102 ACK 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:363]: tps_storage_link_msg(): downstream: yes acontact: [sip:0861772506@10.10.10.133] bcontact: [] Jun 25 10:10:39 kamailio[13803]: {1 102 ACK 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(1) - [Via: SIP/2.0/UDP 10.10.10.1;branch=z9hG4bKf9bf.70b67570f198c08f4c8bfccda86ed225.0#015#012] Jun 25 10:10:39 kamailio[13803]: {1 102 ACK 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [Contact: sip:btpsh-5b309a9f-35ed-1@10.10.10.1#015#012] Jun 25 10:10:39 kamailio[13803]: {1 102 ACK 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 206 Jun 25 10:10:50 kamailio[13811]: DEBUG: topos [tps_storage.c:818]: tps_db_clean_branches(): cleaning expired branch records Jun 25 10:10:50 kamailio[13811]: DEBUG: topos [tps_storage.c:631]: tps_db_clean_dialogs(): cleaning expired dialog records

**when send re-invite:** Jun 25 10:11:17 kamailio[13801]: DEBUG: topos [tps_msg.c:742]: tps_request_received(): handling incoming request Jun 25 10:11:17 kamailio[13801]: DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 10.10.10.84;branch=z9hG4bKe96e1a173BDE1396](54) - x_via2: [](0) - x_vbranch1: [z9hG4bKe96e1a173BDE1396](23) Jun 25 10:11:17 kamailio[13801]: DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) Jun 25 10:11:17 kamailio[13801]: DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) Jun 25 10:11:17 kamailio[13801]: DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 206 Jun 25 10:11:17 kamailio[13801]: DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 206 Jun 25 10:11:17 kamailio[13801]: DEBUG: topos [tps_msg.c:816]: tps_request_received(): r-uri updated to: [sip:0861772506@10.10.10.133] Jun 25 10:11:17 kamailio[13801]: DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [Route: sip:10.10.10.1;lr#015#012] Jun 25 10:11:17 kamailio[13801]: DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [P-SR-XUID: atpsh-5b309a9f-35ed-1#015#012] Jun 25 10:11:17 kamailio[13801]: {1 1 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:934]: tps_request_sent(): handling outgoing request (1, 0) Jun 25 10:11:17 kamailio[13801]: {1 1 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 10.10.10.1;branch=z9hG4bK50dd.2ffa072d1cfcb5cc4d65a70c44dbf472.0](76) - x_via2: [SIP/2.0/UDP 10.10.10.84;branch=z9hG4bKe96e1a173BDE1396](54) - x_vbranch1: [z9hG4bK50dd.2ffa072d1cfcb5cc4d65a70c44dbf472.0](46) Jun 25 10:11:17 kamailio[13801]: {1 1 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) Jun 25 10:11:17 kamailio[13801]: {1 1 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) Jun 25 10:11:17 kamailio[13801]: {1 1 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 206 Jun 25 10:11:18 kamailio[13801]: {1 1 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:972]: tps_db_load_branch(): no stored record for <z9hG4bK50dd.2ffa072d1cfcb5cc4d65a70c44dbf472.0> Jun 25 10:11:18 kamailio[13801]: {1 1 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:363]: tps_storage_link_msg(): downstream: yes acontact: [sip:200@10.10.10.84] bcontact: [] Jun 25 10:11:18 kamailio[13801]: {1 1 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(1) - [Via: SIP/2.0/UDP 10.10.10.1;branch=z9hG4bK50dd.2ffa072d1cfcb5cc4d65a70c44dbf472.0#015#012] Jun 25 10:11:18 kamailio[13801]: {1 1 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [Contact: sip:atpsh-5b309a9f-35ed-1@10.10.10.1#015#012] Jun 25 10:11:18 kamailio[13801]: {1 1 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:363]: tps_storage_link_msg(): downstream: no acontact: [sip:200@10.10.10.84] bcontact: [sip:200@10.10.10.84] Jun 25 10:11:18 kamailio[13801]: {1 1 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 206 Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_msg.c:868]: tps_response_received(): handling incoming response Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 10.10.10.1;branch=z9hG4bK50dd.2ffa072d1cfcb5cc4d65a70c44dbf472.0;received=10.10.10.1](96) - x_via2: [](0) - x_vbranch1: [z9hG4bK50dd.2ffa072d1cfcb5cc4d65a70c44dbf472.0](46) Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 206 Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_msg.c:889]: tps_response_received(): loaded dialog a_uuid [atpsh-5b309a9f-35ed-1] Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 206 Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [Via: SIP/2.0/UDP 10.10.10.84;branch=z9hG4bKe96e1a173BDE1396#015#012] Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [P-SR-XBranch: z9hG4bK50dd.2ffa072d1cfcb5cc4d65a70c44dbf472.0#015#012] Jun 25 10:11:18 kamailio[13805]: {2 1 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:934]: tps_request_sent(): handling outgoing request (1, 1) Jun 25 10:11:18 kamailio[13805]: {2 1 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 10.10.10.1;branch=z9hG4bK50dd.2ffa072d1cfcb5cc4d65a70c44dbf472.0](76) - x_via2: [](0) - x_vbranch1: [z9hG4bK50dd.2ffa072d1cfcb5cc4d65a70c44dbf472.0](46) Jun 25 10:11:18 kamailio[13805]: {2 1 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) Jun 25 10:11:18 kamailio[13805]: {2 1 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) Jun 25 10:11:18 kamailio[13805]: {2 1 INVITE 561fa4601e12e9e0174a5a5447aa3910@10.10.10.133} DEBUG: topos [tps_msg.c:948]: tps_request_sent(): no x-uuid header - nothing to do Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_msg.c:1031]: tps_response_sent(): handling outgoing response Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 10.10.10.84;branch=z9hG4bKe96e1a173BDE1396](54) - x_via2: [](0) - x_vbranch1: [z9hG4bKe96e1a173BDE1396](23) Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 206 Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_msg.c:1061]: tps_response_sent(): loaded branch a_uuid [atpsh-5b309a9f-35ed-1] Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 206 Jun 25 10:11:18 kamailio[13805]: DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [Contact: sip:btpsh-5b309a9f-35ed-1@10.10.10.1#015#012] Jun 25 10:11:18 kamailio[13806]: DEBUG: topos [tps_msg.c:742]: tps_request_received(): handling incoming request Jun 25 10:11:18 kamailio[13806]: DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 10.10.10.84;branch=z9hG4bKe96e1a173BDE1396](54) - x_via2: [](0) - x_vbranch1: [z9hG4bKe96e1a173BDE1396](23) Jun 25 10:11:18 kamailio[13806]: DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) Jun 25 10:11:18 kamailio[13806]: DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) Jun 25 10:11:18 kamailio[13806]: DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 206 Jun 25 10:11:18 kamailio[13806]: DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 206 Jun 25 10:11:18 kamailio[13806]: DEBUG: topos [tps_msg.c:816]: tps_request_received(): r-uri updated to: [sip:200@10.10.10.84] Jun 25 10:11:18 kamailio[13806]: DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [Route: sip:10.10.10.1;lr#015#012] Jun 25 10:11:18 kamailio[13806]: DEBUG: topos [tps_msg.c:182]: tps_add_headers(): adding to headers(0) - [P-SR-XUID: atpsh-5b309a9f-35ed-1#015#012]