Publishing Online / Offline By PUA_USRLOC

Hi,

I'm engaging to publish online / offline status for terminals which have not equipped with PRESENCE functionality. As the Subscriber's view the terminal remains offline even after successful registration. Here is the trace log for REGISTER procedure. As i see in send_publish.c must be additional logs after the "DBG:pua:send_publish: UPDATE_TYPE and no record found".

Regards,
-- afshin


DBG:core:parse_msg: SIP Request:
DBG:core:parse_msg: method: <REGISTER>
DBG:core:parse_msg: uri: <sip:192.168.129.65>
DBG:core:parse_msg: version: <SIP/2.0>
DBG:core:parse_headers: flags=2
DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKe1822f321d95f49a>; state=16
DBG:core:parse_via: end of header reached, state=5
DBG:core:parse_headers: via found, flags=2
DBG:core:parse_headers: this is the first via
DBG:core:receive_msg: After parse_msg...
DBG:core:receive_msg: preparing to run routing scripts...
DBG:core:parse_headers: flags=100
DBG:core:parse_to: end of header reached, state=9
DBG:core:parse_to: display={}, ruri={sip:iphone@192.168.129.65}
DBG:core:get_hdr_field: <To> [27]; uri=[sip:iphone@192.168.129.65]
DBG:core:get_hdr_field: to body [sip:iphone@192.168.129.65^M ]
DBG:core:get_hdr_field: cseq <CSeq>: <129> <REGISTER>
DBG:maxfwd:is_maxfwd_present: value = 70
DBG:siputils:has_totag: no totag
DBG:core:parse_headers: flags=78
DBG:tm:t_lookup_request: start searching: hash=55794, isACK=0
DBG:tm:matching_3261: RFC3261 transaction matching failed
DBG:tm:t_lookup_request: no transaction found
DBG:core:parse_headers: flags=4000
DBG:auth:check_nonce: comparing [4a1a77800000000b0b5eed30117056fade074dd16f70e738] and [4a1a77800000000b681708d1cc820c5d00380d115f30e4b0]
DBG:auth:pre_auth: invalid nonce value received
DBG:auth:reserve_nonce_index: second= 12, sec_monit= -1, index= 2
DBG:auth:build_auth_hf: nonce index= 2
DBG:auth:build_auth_hf: 'WWW-Authenticate: Digest realm="192.168.129.65", nonce="4a1a786c0000000258e5bed46f99fa8bf27077d371105f18", stale=true^M '
DBG:sl:send_reply: reply in stateless mode (sl)
DBG:core:parse_headers: flags=ffffffffffffffff
DBG:core:get_hdr_field: content_length=0
DBG:core:get_hdr_field: found end of header
DBG:core:check_via_address: params 192.168.128.25, 192.168.128.25, 0
DBG:core:destroy_avp_list: destroying list (nil)
DBG:core:receive_msg: cleaning up
DBG:core:parse_msg: SIP Request:
DBG:core:parse_msg: method: <REGISTER>
DBG:core:parse_msg: uri: <sip:192.168.129.65>
DBG:core:parse_msg: version: <SIP/2.0>
DBG:core:parse_headers: flags=2
DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKeda809c38e4e1fa5>; state=16
DBG:core:parse_via: end of header reached, state=5
DBG:core:parse_headers: via found, flags=2
DBG:core:parse_headers: this is the first via
DBG:core:receive_msg: After parse_msg...
DBG:core:receive_msg: preparing to run routing scripts...
DBG:core:parse_headers: flags=100
DBG:core:parse_to: end of header reached, state=9
DBG:core:parse_to: display={}, ruri={sip:iphone@192.168.129.65}
DBG:core:get_hdr_field: <To> [27]; uri=[sip:iphone@192.168.129.65]
DBG:core:get_hdr_field: to body [sip:iphone@192.168.129.65^M ]
DBG:core:get_hdr_field: cseq <CSeq>: <130> <REGISTER>
DBG:maxfwd:is_maxfwd_present: value = 70
DBG:siputils:has_totag: no totag
DBG:core:parse_headers: flags=78
DBG:tm:t_lookup_request: start searching: hash=56010, isACK=0
DBG:tm:matching_3261: RFC3261 transaction matching failed
DBG:tm:t_lookup_request: no transaction found
DBG:core:parse_headers: flags=4000
DBG:auth:check_nonce: comparing [4a1a786c0000000258e5bed46f99fa8bf27077d371105f18] and [4a1a786c0000000258e5bed46f99fa8bf27077d371105f18]
DBG:core:db_new_result: allocate 28 bytes for result set at 0x81795c8
DBG:db_mysql:db_mysql_get_columns: 1 columns returned from the query
DBG:core:db_allocate_columns: allocate 4 bytes for result names at 0x8166ec0
DBG:core:db_allocate_columns: allocate 4 bytes for result types at 0x8166ed0
DBG:db_mysql:db_mysql_get_columns: allocate 8 bytes for RES_NAMES[0] at 0x8179620
DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x8179620)[0]=[password]
DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
DBG:core:db_allocate_rows: allocate 8 bytes for rows at 0x8166eb0
DBG:core:db_allocate_row: allocate 20 bytes for row values at 0x8167050
DBG:core:db_str2val: converting STRING [123aaa)]
DBG:auth_db:get_ha1: HA1 string calculated: 32fab6e1851495179151f029a8d42355
DBG:auth:check_response: our result = '2115bb184ffb939148e93614d2b508eb'
DBG:auth:check_response: authorization is OK
DBG:auth:post_auth: nonce index= 2
DBG:core:db_free_columns: freeing 1 columns
DBG:core:db_free_columns: freeing RES_NAMES[0] at 0x8179620
DBG:core:db_free_columns: freeing result names at 0x8166ec0
DBG:core:db_free_columns: freeing result types at 0x8166ed0
DBG:core:db_free_rows: freeing 1 rows
DBG:core:db_free_row: freeing row values at 0x8167050
DBG:core:db_free_rows: freeing rows at 0x8166eb0
DBG:core:db_free_result: freeing result set at 0x81795c8
DBG:core:parse_headers: flags=14000
DBG:core:get_hdr_field: content_length=0
DBG:core:get_hdr_field: found end of header
DBG:core:comp_scriptvar: str 29 : iphone
DBG:core:grep_sock_info: checking if host==us: 14==9 && [192.168.129.65] == [127.0.0.1]
DBG:core:grep_sock_info: checking if port 5060 matches port 5060
DBG:core:grep_sock_info: checking if host==us: 14==14 && [192.168.129.65] == [192.168.129.65]
DBG:core:grep_sock_info: checking if port 5060 matches port 5060
DBG:core:grep_sock_info: match found for: [192.168.129.65:5060]
DBG:core:check_self: host == me
DBG:core:parse_to_param: tag=604129704
DBG:core:parse_to: end of header reached, state=29
DBG:core:parse_to: display={}, ruri={sip:iphone@192.168.129.65}
DBG:pua_usrloc:pua_set_publish: set send publish
DBG:core:parse_headers: flags=ffffffffffffffff
DBG:core:parse_headers: flags=8000000
DBG:core:parse_headers: flags=ffffffffffffffff
DBG:core:parse_methods: methods 0x1CEF
DBG:usrloc:update_ucontact: exists callback for type= UL_CONTACT_UPDATE
DBG:usrloc:run_ul_callbacks: contact=0xb5a64c58, callback type 2/2, id 2 entered
DBG:pua_usrloc:ul_publish: ul_publish: UPDATE type
DBG:pua_usrloc:ul_publish: ul_publish: uri= sip:iphone@cc.basamad.acc
DBG:pua_usrloc:print_publ: publ:
DBG:pua_usrloc:print_publ: uri= sip:iphone@cc.basamad.acc
DBG:pua_usrloc:print_publ: id= UL_PUBLISH.3368564252 [!at] 192.168.128.25 (replace the [!at] with a @)
DBG:pua_usrloc:print_publ: expires= 300
DBG:pua:send_publish: pres_uri=sip:iphone@cc.basamad.acc
DBG:pua:search_htable: core_hash= 173
DBG:pua:search_htable: record not found
DBG:pua:send_publish: insert type
DBG:pua:send_publish: UPDATE_TYPE and no record found
DBG:registrar:build_contact: created Contact HF: Contact: <sip:iphone@192.168.128.25:5060;transport=udp>;expires=300^M
DBG:sl:send_reply: reply in stateless mode (sl)
DBG:core:parse_headers: flags=ffffffffffffffff
DBG:core:check_via_address: params 192.168.128.25, 192.168.128.25, 0
DBG:core:destroy_avp_list: destroying list (nil)
DBG:core:receive_msg: cleaning up

What is your kamailio version? IIRC, something similar was fixed in the latest version.

The cause is that the registration is an update (refresh), but there is no corresponding presence record.

Kamailio 1.5.1-notls