<!DOCTYPE html PUBLIC "-//W3C//DTD XHTML 1.0 Strict//EN" "http://www.w3.org/TR/xhtml1/DTD/xhtml1-strict.dtd"><html xmlns="http://www.w3.org/1999/xhtml"><head>
<meta content="text/html; charset=UTF-8" http-equiv="Content-Type"/>
</head><body style="">
<div>
Hello Daniel,
</div>
<div>
</div>
<div>
I´ve reproduced the scenario with Kamailio version 4.1.3. Behaviour is the same and this is the output from kamailio-4.1.3 with debug=3:
</div>
<div>
</div>
<div>
</div>
<div>
May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: INFO: -<|XLOG|>-: <PRESENCE> handle_subscribe From: sip:117711@10.16.48.14 executed
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [event_list.c:351]: search_event(): start event= [dialog/5]
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [subscribe.c:1098]: extract_sdialog_info(): 'Expires' header found, value= 900
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [subscribe.c:1120]: extract_sdialog_info(): 'To' header ALREADY PARSED: <sip:116001@10.16.48.44>
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [subscribe.c:1191]: extract_sdialog_info(): generating to_tag
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [subscribe.c:1251]: extract_sdialog_info(): subs->contact= sip:117711@10.16.48.14:5070 - len = 27
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [subscribe.c:981]: handle_subscribe(): subscription status= active - found in watcher table
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:117]: printf_subs(): pres_uri: sip:116001@10.16.48.44
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:118]: printf_subs(): watcher_user@watcher_domain: 117711@10.16.48.14
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:119]: printf_subs(): to_user@to_domain: 116001@10.16.48.44
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:120]: printf_subs(): from_user@from_domain: 117711@10.16.48.14
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:121]: printf_subs(): callid/from_tag/to_tag: 1543162531/749132449/4f7a7e54f75c89f5b968c90011d693b5-56dd
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:122]: printf_subs(): local_cseq/remote_cseq: 0/20
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:123]: printf_subs(): local_contact/contact: sip:10.16.48.44:5060/sip:117711@10.16.48.14:5070
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:124]: printf_subs(): record_route:
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:125]: printf_subs(): sockinfo_str: udp:10.16.48.44:5060
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:127]: printf_subs(): event: dialog
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:128]: printf_subs(): status: active
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:129]: printf_subs(): reason:
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:130]: printf_subs(): version: 1
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:131]: printf_subs(): expires: 900
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:133]: printf_subs(): updated/updated_winfo: 46/-1
<br/>May 6 09:08:54 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [subscribe.c:354]: insert_subs_db(): inserting subscription in active_watchers table
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [event_list.c:351]: search_event(): start event= [dialog/5]
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:1471]: send_notify_request(): dialog info:
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:117]: printf_subs(): pres_uri: sip:116001@10.16.48.44
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:118]: printf_subs(): watcher_user@watcher_domain: 117711@10.16.48.14
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:119]: printf_subs(): to_user@to_domain: 116001@10.16.48.44
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:120]: printf_subs(): from_user@from_domain: 117711@10.16.48.14
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:121]: printf_subs(): callid/from_tag/to_tag: 1543162531/749132449/4f7a7e54f75c89f5b968c90011d693b5-56dd
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:122]: printf_subs(): local_cseq/remote_cseq: 1/20
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:123]: printf_subs(): local_contact/contact: sip:10.16.48.44:5060/sip:117711@10.16.48.14:5070
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:124]: printf_subs(): record_route:
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:125]: printf_subs(): sockinfo_str: udp:10.16.48.44:5060
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:127]: printf_subs(): event: dialog
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:128]: printf_subs(): status: active
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:129]: printf_subs(): reason:
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:130]: printf_subs(): version: 2
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:131]: printf_subs(): expires: 898
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:133]: printf_subs(): updated/updated_winfo: 46/-1
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [hash.c:461]: search_phtable(): pres_uri= sip:116001@10.16.48.44
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:616]: get_p_notify_body(): No record exists in hash_table
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence_dialoginfo [notify_body.c:75]: dlginfo_agg_nbody(): [pres_user]=116001 [pres_domain]= 10.16.48.44, [n]=0
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:1525]: send_notify_request(): Could not get the notify_body
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:1564]: send_notify_request(): headers:#012Max-Forwards: 70#015#012Event: dialog#015#012Contact: <sip:10.16.48.44:5060>#015#012Subscription-State: active;expires=898#015#012
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:926]: ps_build_dlg_t(): CONTACT = sip:117711@10.16.48.14:5070
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:1574]: send_notify_request(): expires 898 status 1
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:1727]: shm_dup_cbparam(): === 22/6/37
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: INFO: presence [notify.c:1601]: send_notify_request(): NOTIFY sip:117711@10.16.48.14 via sip:117711@10.16.48.14:5070 on behalf of sip:116001@10.16.48.44 for event dialog
<br/>May 6 09:08:56 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:1701]: p_tm_callback(): completed with status 200 [to_tag:4f7a7e54f75c89f5b968c90011d693b5-56dd]
<br/>May 6 09:09:00 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: INFO: -<|XLOG|>-: <PRESENCE> handle_publish From: sip:117103@172.31.60.87 executed
<br/>May 6 09:09:00 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [event_list.c:351]: search_event(): start event= [dialog/5]
<br/>May 6 09:09:00 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [publish.c:318]: handle_publish(): SIP-If-Match header not found
<br/>May 6 09:09:00 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [presentity.c:87]: generate_ETag(): etag= a.1399360111.20480.4.0 / 22#012
<br/>May 6 09:09:00 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [publish.c:327]: handle_publish(): new etag = a.1399360111.20480.4.0
<br/>May 6 09:09:00 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [publish.c:352]: handle_publish(): Expires header found, value= 1800
<br/>May 6 09:09:00 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [publish.c:396]: handle_publish(): KFe - the PUBLISH body is processed...
<br/>May 6 09:09:00 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [publish.c:407]: handle_publish(): KFe - the content length is: 962
<br/>May 6 09:09:00 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [publish.c:444]: handle_publish(): KFe - event->evs_publ_handl is executed...
<br/>May 6 09:09:00 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [publish.c:467]: handle_publish(): KFe - update_presentity is executed
<br/>May 6 09:09:00 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [publish.c:468]: handle_publish(): KFe - body = <?xml version="1.0"?>#015#012<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="00000000004" state="full" entity="sip:117103@172.31.60.87">#015#012<dialog id="CBE263FF-CEAE11E3-80E8CCBC-52135ACA@172.31.60.13" call-id="CBE263FF-CEAE11E3-80E8CCBC-52135ACA@172.31.60.13" direction="recipient">#015#012<state>terminated</state>#015#012<remote>#015#012<identity>sip:1101015004@172.31.60.13</identity>#015#012<target uri="sip:1101015004@172.31.60.13"/>#015#012</remote>#015#012<local>#015#012<identity>sip:117103@172.31.60.87</identity>#015#012<target uri="sip:117103@172.31.60.87"/>#015#012</local>#015#012</dialog>#015#012<dialog id="1041054395-29684904-1398759995534@172.31.60.53" call-id="1041054395-29684904-1398759995534@172.31.60.53" direction="initiator">#015#012<state>confirmed</state>#015#012<remote>#015#012<identity>sip:117101@172.31.60.87</identity>#015#012<target uri="sip:117101@172.31.60.87"/>#015#012</remote>#015#012<local>#015#012<identity>sip:117103@172.31.60.87</identity>#015#012<target uri="sip:117103@172.31.60.87"/>#015#012</local>#015#012</dialog>#015#012</dialog-info>#015#012
<br/>May 6 09:09:00 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [hash.c:461]: search_phtable(): pres_uri= sip:116001@10.16.48.44
<br/>May 6 09:09:00 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [presentity.c:398]: update_presentity(): inserting 8 cols into table
<br/>May 6 09:09:00 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [presentity.c:104]: publ_send200ok(): send 200OK reply
<br/>May 6 09:09:00 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [presentity.c:105]: publ_send200ok(): etag= a.1399360111.20480.4.0 - len= 22
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [event_list.c:351]: search_event(): start event= [dialog/5]
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:1471]: send_notify_request(): dialog info:
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:117]: printf_subs(): pres_uri: sip:116001@10.16.48.44
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:118]: printf_subs(): watcher_user@watcher_domain: 117711@10.16.48.14
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:119]: printf_subs(): to_user@to_domain: 116001@10.16.48.44
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:120]: printf_subs(): from_user@from_domain: 117711@10.16.48.14
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:121]: printf_subs(): callid/from_tag/to_tag: 1543162531/749132449/4f7a7e54f75c89f5b968c90011d693b5-56dd
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:122]: printf_subs(): local_cseq/remote_cseq: 2/20
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:123]: printf_subs(): local_contact/contact: sip:10.16.48.44:5060/sip:117711@10.16.48.14:5070
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:124]: printf_subs(): record_route:
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:125]: printf_subs(): sockinfo_str: udp:10.16.48.44:5060
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:127]: printf_subs(): event: dialog
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:128]: printf_subs(): status: active
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:129]: printf_subs(): reason:
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:130]: printf_subs(): version: 3
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:131]: printf_subs(): expires: 893
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:133]: printf_subs(): updated/updated_winfo: 46/-1
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [hash.c:461]: search_phtable(): pres_uri= sip:116001@10.16.48.44
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:744]: get_p_notify_body(): Event requires aggregation
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence_dialoginfo [notify_body.c:75]: dlginfo_agg_nbody(): [pres_user]=116001 [pres_domain]= 10.16.48.44, [n]=1
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence_dialoginfo [notify_body.c:126]: agregate_xmls(): [pres_user]=116001 [pres_domain]= 10.16.48.44, [n]=1
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence_dialoginfo [notify_body.c:145]: agregate_xmls(): parsing XML body: [n]=1, [i]=0, [j]=0 xml_array[j]=0x9bef820
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence_dialoginfo [notify_body.c:166]: agregate_xmls(): number of bodies in total [n]=1, number of useful bodies [j]=1
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence_dialoginfo [notify_body.c:210]: agregate_xmls(): [n]=1, [i]=0, [j]=1 xml_array[i]=0xc0c0c0c0
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence_dialoginfo [notify_body.c:219]: agregate_xmls(): node type: Element, name: dialog
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence_dialoginfo [notify_body.c:81]: dlginfo_agg_nbody(): [n_body]=0xb7a9d298
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence_dialoginfo [notify_body.c:84]: dlginfo_agg_nbody(): [*n_body]=<?xml version="1.0"?>#012<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="00000000000" state="full" entity="sip:116001@10.16.48.44">#012 <dialog id="CBE263FF-CEAE11E3-80E8CCBC-52135ACA@172.31.60.13" call-id="CBE263FF-CEAE11E3-80E8CCBC-52135ACA@172.31.60.13" direction="recipient">#012<state>terminated</state>#012<remote>#012<identity>sip:1101015004@172.31.60.13</identity>#012<target uri="sip:1101015004@172.31.60.13"/>#012</remote>#012<local>#012<identity>sip:117103@172.31.60.87</identity>#012<target uri="sip:117103@172.31.60.87"/>#012</local>#012</dialog>#012</dialog-info>#012
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:1564]: send_notify_request(): headers:#012Max-Forwards: 70#015#012Event: dialog#015#012Contact: <sip:10.16.48.44:5060>#015#012Subscription-State: active;expires=893#015#012Content-Type: application/dialog-info+xml#015#012
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:926]: ps_build_dlg_t(): CONTACT = sip:117711@10.16.48.14:5070
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:1574]: send_notify_request(): expires 893 status 1
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: DEBUG: presence [notify.c:1727]: shm_dup_cbparam(): === 22/6/37
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20485]: INFO: presence [notify.c:1601]: send_notify_request(): NOTIFY sip:117711@10.16.48.14 via sip:117711@10.16.48.14:5070 on behalf of sip:116001@10.16.48.44 for event dialog
<br/>May 6 09:09:01 sipsrvnode1 /usr/local/kamailio41/sbin/kamailio[20480]: DEBUG: presence [notify.c:1701]: p_tm_callback(): completed with status 200 [to_tag:4f7a7e54f75c89f5b968c90011d693b5-56dd]
<br/>
<br/>
</div>
<div>
</div>
<div>
The DB query returned the whole body incl. 2 dialog entries, as traced with Wireshark:
</div>
<div>
MySQL Query/Result, traced with Wireshark
<br/>Command: Query
<br/>Statement: select body,etag,sender from presentity where domain='10.16.48.44' AND username='116001' AND event='dialog' order by received_time
<br/>
<br/><?xml version="1.0"?>
<br/><dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="00000000004" state="full" entity="sip:117103@172.31.60.87">
<br/><dialog id="CBE263FF-CEAE11E3-80E8CCBC-52135ACA@172.31.60.13" call-id="CBE263FF-CEAE11E3-80E8CCBC-52135ACA@172.31.60.13" direction="recipient">
<br/><state>terminated</state>
<br/><remote>
<br/><identity>sip:1101015004@172.31.60.13</identity>
<br/><target uri="sip:1101015004@172.31.60.13"/>
<br/></remote>
<br/><local>
<br/><identity>sip:117103@172.31.60.87</identity>
<br/><target uri="sip:117103@172.31.60.87"/>
<br/></local>
<br/></dialog>
<br/><dialog id="1041054395-29684904-1398759995534@172.31.60.53" call-id="1041054395-29684904-1398759995534@172.31.60.53" direction="initiator">
<br/><state>confirmed</state>
<br/><remote>
<br/><identity>sip:117101@172.31.60.87</identity>
<br/><target uri="sip:117101@172.31.60.87"/>
<br/></remote>
<br/><local>
<br/><identity>sip:117103@172.31.60.87</identity>
<br/><target uri="sip:117103@172.31.60.87"/>
<br/></local>
<br/></dialog>
<br/></dialog-info>
<br/>a.1399360111.20480.4.0
<br/>
<br/>
</div>
<div>
</div>
<div>
regards,
</div>
<div>
Klaus
</div>
<div>
</div>
<div>
</div>
<blockquote style="position: relative; margin-left: 0px; padding-left: 10px; border-left: solid 1px blue;" type="cite">
Daniel-Constantin Mierla <miconda@gmail.com> hat am 5. Mai 2014 um 17:37 geschrieben:
<br/>
<br/> Can you try with the latest version 4.1.x? It is where I looked in the code and seemed to walk through all dialog nodes.
<br/>
<br/> Cheers, Daniel
<br/>
<br/>
<div class="moz-cite-prefix">
On 05/05/14 16:28, Klaus Feichtinger wrote:
</div>
<blockquote type="cite">
<div>
this is the output (in this example version 3.2.4) in debug level 3 after receiving a PUBLISH request (incl. forming the NOTIFY request):
</div>
<div>
</div>
<div>
May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [event_list.c:350]: start event= [dialog/5]
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [publish.c:349]: SIP-If-Match header not found
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [presentity.c:85]: etag= a.1399299676.23439.2.0 / 22#012
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [publish.c:358]: new etag = a.1399299676.23439.2.0
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [publish.c:383]: Expires header found, value= 1800
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [publish.c:468]: KLAUSDBG - body = <?xml version="1.0"?>#015#012<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="00000000004" state="full" entity=
<a href="sip:117103@172.31.60.87">"sip:117103@172.31.60.87"</a>>#015#012<dialog id=
<a href="mailto:CBE263FF-CEAE11E3-80E8CCBC-52135ACA@172.31.60.13">"CBE263FF-CEAE11E3-80E8CCBC-52135ACA@172.31.60.13"</a> call-id=
<a href="mailto:CBE263FF-CEAE11E3-80E8CCBC-52135ACA@172.31.60.13">"CBE263FF-CEAE11E3-80E8CCBC-52135ACA@172.31.60.13"</a> direction="recipient">#015#012<state>terminated</state>#015#012<remote>#015#012<identity>
<a href="sip:1101015004@172.31.60.13">sip:1101015004@172.31.60.13</a></identity>#015#012<target uri=
<a href="sip:1101015004@172.31.60.13">"sip:1101015004@172.31.60.13"</a>/>#015#012</remote>#015#012<local>#015#012<identity>
<a href="sip:117103@172.31.60.87">sip:117103@172.31.60.87</a></identity>#015#012<target uri=
<a href="sip:117103@172.31.60.87">"sip:117103@172.31.60.87"</a>/>#015#012</local>#015#012</dialog>#015#012<dialog id=
<a href="mailto:1041054395-29684904-1398759995534@172.31.60.53">"1041054395-29684904-1398759995534@172.31.60.53"</a> call-id=
<a href="mailto:1041054395-29684904-1398759995534@172.31.60.53">"1041054395-29684904-1398759995534@172.31.60.53"</a> direction="initiator">#015#012<state>confirmed</state>#015#012<remote>#015#012<identity>
<a href="sip:117101@172.31.60.87">sip:117101@172.31.60.87</a></identity>#015#012<target uri=
<a href="sip:117101@172.31.60.87">"sip:117101@172.31.60.87"</a>/>#015#012</remote>#015#012<local>#015#012<identity>
<a href="sip:117103@172.31.60.87">sip:117103@172.31.60.87</a></identity>#015#012<target uri=
<a href="sip:117103@172.31.60.87">"sip:117103@172.31.60.87"</a>/>#015#012</local>#015#012</dialog>#015#012</dialog-info>#015#012
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [hash.c:470]: pres_uri=
<a href="sip:116001@10.16.48.44">sip:116001@10.16.48.44</a>
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [presentity.c:385]: inserting 8 cols into table
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [presentity.c:99]: send 200OK reply
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [presentity.c:100]: etag= a.1399299676.23439.2.0 - len= 22
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [notify.c:1041]: querying database table = active_watchers
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [notify.c:1116]: found 1 dialogs
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [notify.c:122]: #012#011[pres_uri]=
<a href="sip:116001@10.16.48.44#012#011">sip:116001@10.16.48.44#012#011</a>[to_user]= 116001#011[to_domain]= 10.16.48.44#012#011[w_user]= 117700#011[w_domain]= 10.16.48.14#012#011[event]= dialog#012#011[status]= active#012#011[expires]= 889#012#011[callid]= 1146821297#011[local_cseq]=1#012#011[to_tag]= 4f7a7e54f75c89f5b968c90011d693b5-8c7d#011[from_tag]= 154174339#012#011[contact]=
<a href="sip:117700@10.16.48.14:5070#011">sip:117700@10.16.48.14:5070#011</a>[record_route]=
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [notify.c:1333]: found 1 dialogs( 1 in database and 0 in hash_table)
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [notify.c:782]: Event requires aggregation
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence_dialoginfo [notify_body.c:67]: [pres_user]=116001 [pres_domain]= 10.16.48.44, [n]=1
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence_dialoginfo [notify_body.c:107]: [pres_user]=116001 [pres_domain]= 10.16.48.44, [n]=1
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence_dialoginfo [notify_body.c:199]: node type: Element, name: dialog
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence_dialoginfo [notify_body.c:73]: [n_body]=0xb7a6daa4
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence_dialoginfo [notify_body.c:76]: [*n_body]=<?xml version="1.0"?>#012<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="00000000000" state="full" entity=
<a href="mailto:116001@10.16.48.44">"116001@10.16.48.44"</a>>#012 <dialog id=
<a href="mailto:CBE263FF-CEAE11E3-80E8CCBC-52135ACA@172.31.60.13">"CBE263FF-CEAE11E3-80E8CCBC-52135ACA@172.31.60.13"</a> call-id=
<a href="mailto:CBE263FF-CEAE11E3-80E8CCBC-52135ACA@172.31.60.13">"CBE263FF-CEAE11E3-80E8CCBC-52135ACA@172.31.60.13"</a> direction="recipient">#012<state>terminated</state>#012<remote>#012<identity>
<a href="sip:1101015004@172.31.60.13">sip:1101015004@172.31.60.13</a></identity>#012<target uri=
<a href="sip:1101015004@172.31.60.13">"sip:1101015004@172.31.60.13"</a>/>#012</remote>#012<local>#012<identity>
<a href="sip:117103@172.31.60.87">sip:117103@172.31.60.87</a></identity>#012<target uri=
<a href="sip:117103@172.31.60.87">"sip:117103@172.31.60.87"</a>/>#012</local>#012</dialog>#012</dialog-info>#012
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence_dialoginfo [notify_body.c:328]: replace version with "1"
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [notify.c:1651]: updating subscription to database
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [notify.c:1468]: dialog info:
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [notify.c:122]: #012#011[pres_uri]=
<a href="sip:116001@10.16.48.44#012#011">sip:116001@10.16.48.44#012#011</a>[to_user]= 116001#011[to_domain]= 10.16.48.44#012#011[w_user]= 117700#011[w_domain]= 10.16.48.14#012#011[event]= dialog#012#011[status]= active#012#011[expires]= 889#012#011[callid]= 1146821297#011[local_cseq]=1#012#011[to_tag]= 4f7a7e54f75c89f5b968c90011d693b5-8c7d#011[from_tag]= 154174339#012#011[contact]=
<a href="sip:117700@10.16.48.14:5070#011">sip:117700@10.16.48.14:5070#011</a>[record_route]=
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [notify.c:236]: expires = 889
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [notify.c:1562]: headers:#012Max-Forwards: 70#015#012Event: dialog#015#012Contact:
<a href="sip:10.16.48.44:5060"><sip:10.16.48.44:5060></a>#015#012Subscription-State: active;expires=870#015#012Content-Type: application/dialog-info+xml#015#012
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [notify.c:964]: CONTACT =
<a href="sip:117700@10.16.48.14:5070">sip:117700@10.16.48.14:5070</a>
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [notify.c:1724]: === 22/6/37
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: INFO: presence [notify.c:1593]: NOTIFY
<a href="sip:117700@10.16.48.14">sip:117700@10.16.48.14</a> via
<a href="sip:117700@10.16.48.14:5070">sip:117700@10.16.48.14:5070</a> on behalf of
<a href="sip:116001@10.16.48.44">sip:116001@10.16.48.44</a> for event dialog
<br/> May 5 16:21:49 sipsrvnode1 /usr/sbin/kamailio[23439]: DEBUG: presence [notify.c:1689]: completed with status 200 [to_tag:4f7a7e54f75c89f5b968c90011d693b5-8c7d]
<br/>
</div>
<div>
</div>
<div>
Main visible difference is the xml body output between PUBLISH (publish.c) and NOTIFY (notify_body.c).
</div>
<div>
</div>
<div>
regards,
</div>
<div>
Klaus
</div>
</blockquote>
</blockquote>
</body></html>