kamailio_request.log

DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request:
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg():  method:  <INVITE>
DEBUG: <core> [parser/msg_parser.c:604]: parse_msg():  uri:     <sip:676216531@212.106.213.5:5060;user=phone;transport=udp>
DEBUG: <core> [parser/msg_parser.c:606]: parse_msg():  version: <SIP/2.0>
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK71b248a2>; state=16
DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
DEBUG: <core> [parser/msg_parser.c:491]: parse_headers(): Via found, flags=2
DEBUG: <core> [parser/msg_parser.c:493]: parse_headers(): this is the first via
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=gf2cfd0d-CC-50
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29
DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [47]; uri=[sip:676216531@10.10.9.90]
DEBUG: <core> [parser/msg_parser.c:172]: get_hdr_field(): to body [<sip:676216531@10.10.9.90>]
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <103> <INVITE>
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=as08d1711f
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29
DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request:
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg():  method:  <INVITE>
DEBUG: <core> [parser/msg_parser.c:604]: parse_msg():  uri:     <sip:676216531@212.106.213.5:5060;user=phone;transport=udp>
DEBUG: <core> [parser/msg_parser.c:606]: parse_msg():  version: <SIP/2.0>
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK71b248a2>; state=16
DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
DEBUG: <core> [parser/msg_parser.c:491]: parse_headers(): Via found, flags=2
DEBUG: <core> [parser/msg_parser.c:493]: parse_headers(): this is the first via
DEBUG: <core> [receive.c:184]: receive_msg(): After parse_msg...
DEBUG: <core> [receive.c:227]: receive_msg(): preparing to run routing scripts...
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=gf2cfd0d-CC-50
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29
DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [47]; uri=[sip:676216531@10.10.9.90]
DEBUG: <core> [parser/msg_parser.c:172]: get_hdr_field(): to body [<sip:676216531@10.10.9.90>]
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=as08d1711f
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29
DEBUG: dialog [dlg_hash.c:749]: internal_get_dlg(): ref dlg 0x7f5d7bc5a480 with 1 -> 2
DEBUG: dialog [dlg_hash.c:752]: internal_get_dlg(): dialog callid='4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060' found on entry 2104, dir=1
DEBUG: dialog [dlg_profile.c:530]: set_current_dialog(): setting current dialog [2104:3012]
DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request:
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg():  method:  <INVITE>
DEBUG: <core> [parser/msg_parser.c:604]: parse_msg():  uri:     <sip:676216531@212.106.213.5:5060;user=phone;transport=udp>
DEBUG: <core> [parser/msg_parser.c:606]: parse_msg():  version: <SIP/2.0>
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK71b248a2>; state=16
DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
DEBUG: <core> [parser/msg_parser.c:491]: parse_headers(): Via found, flags=20
DEBUG: <core> [parser/msg_parser.c:504]: parse_headers(): this is the second via
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=gf2cfd0d-CC-50
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29
DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [47]; uri=[sip:676216531@10.10.9.90]
DEBUG: <core> [parser/msg_parser.c:172]: get_hdr_field(): to body [<sip:676216531@10.10.9.90>]
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <103> <INVITE>
DEBUG: dialog [dlg_cseq.c:209]: dlg_cseq_refresh(): initiating cseq refresh
DEBUG: dialog [dlg_cseq.c:245]: dlg_cseq_refresh(): adding cseq refresh header value: 104
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=204
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header
DEBUG: <core> [data_lump.c:770]: sr_hdr_add(): added new header [P-K-CSeq-Refresh: 104#015#012]
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1
DEBUG: dialog [dlg_hash.c:1120]: next_state_dlg(): dialog 0x7f5d7bc5a480 changed from state 4 to state 4, due event 8 (ref 1)
DEBUG: dialog [dlg_timer.c:110]: insert_dialog_timer_unsafe(): inserting 0x7f5d7bc5a4e0 for 131338301
DEBUG: dialog [dlg_hash.c:636]: dlg_update_contact(): same contact for leg[0] - [sip:CBT143502TC@10.10.1.2:5060]
DEBUG: dialog [dlg_hash.c:607]: dlg_update_cseq(): cseq of leg[0] is 103
DEBUG: dialog [dlg_handlers.c:1406]: dlg_onroute(): sequential request successfully processed
DEBUG: rr [record.c:919]: add_rr_param(): rr_param_buf=<;did=838.4cb>
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 0
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): ref <=0 for dialog 0x7f5d7bc5a480
DEBUG: dialog [dlg_hash.c:329]: destroy_dlg(): destroying dialog 0x7f5d7bc5a480 (ref 0)
DEBUG: siputils [checks.c:123]: has_totag(): totag found
ERROR: <script>: Reinvite que falla
DEBUG: rr [loose.c:89]: is_preloaded(): is_preloaded: No
DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 13==10 && [212.106.213.5] == [10.10.9.90]
DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060
DEBUG: <core> [forward.c:412]: check_self(): host != me
DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 10==10 && [10.10.9.90] == [10.10.9.90]
DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060
DEBUG: rr [loose.c:788]: after_loose(): Topmost route URI: 'sip:10.10.9.90;lr;ftag=as08d1711f;did=838.4cb' is me
DEBUG: rr [loose.c:877]: after_loose(): URI to be processed: 'sip:194.30.0.111;lr;ftag=as08d1711f;nat=yes'
DEBUG: rr [loose.c:886]: after_loose(): Next URI is a loose router
DEBUG: rr [rr_cb.c:95]: run_rr_callbacks(): callback id 1 entered with <lr;ftag=as08d1711f;did=838.4cb>
DEBUG: uac [replace.c:533]: restore_uri(): getting 'vsf' Route param
DEBUG: uac [replace.c:537]: restore_uri(): route param 'vsf' not found
DEBUG: uac [replace.c:533]: restore_uri(): getting 'vst' Route param
DEBUG: uac [replace.c:537]: restore_uri(): route param 'vst' not found
DEBUG: rr [rr_cb.c:95]: run_rr_callbacks(): callback id 0 entered with <lr;ftag=as08d1711f;did=838.4cb>
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3012 found on entry 2104
DEBUG: dialog [dlg_handlers.c:1204]: dlg_onroute(): route param is '838.4cb' (len=7)
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3012 found on entry 2104
WARNING: dialog [dlg_handlers.c:1215]: dlg_onroute(): unable to find dialog for INVITE with route param '838.4cb' [2104:3012]
DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=5 , global msg id=4 , T on entrance=0xffffffffffffffff
DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=55049, isACK=0
DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed
DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
DEBUG: tm [t_hooks.c:358]: run_reqin_callbacks_internal(): DBG: trans=0x7f5d7bc5bfd0, callback type 1, id 0 entered
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3012 found on entry 2104
DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: 5c4312e72360dc514bdcbac581b3d45d
DEBUG: tm [t_funcs.c:321]: t_relay_to(): SER: new INVITE
DEBUG: <core> [msg_translator.c:159]: check_via_address(): (10.10.1.2, 10.10.1.2, 0)
DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): SIP Reply  (status):
DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():  version: <SIP/2.0>
DEBUG: <core> [parser/msg_parser.c:614]: parse_msg():  status:  <100>
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg():  reason:  <trying -- your call is important to us>
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK71b248a2>; state=16
DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
DEBUG: <core> [parser/msg_parser.c:491]: parse_headers(): Via found, flags=2
DEBUG: <core> [parser/msg_parser.c:493]: parse_headers(): this is the first via
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=gf2cfd0d-CC-50
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29
DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [47]; uri=[sip:676216531@10.10.9.90]
DEBUG: <core> [parser/msg_parser.c:172]: get_hdr_field(): to body [<sip:676216531@10.10.9.90>]
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK71b248a2>; state=16
DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
DEBUG: <core> [parser/msg_parser.c:491]: parse_headers(): Via found, flags=20
DEBUG: <core> [parser/msg_parser.c:504]: parse_headers(): this is the second via
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=gf2cfd0d-CC-50
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29
DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [47]; uri=[sip:676216531@10.10.9.90]
DEBUG: <core> [parser/msg_parser.c:172]: get_hdr_field(): to body [<sip:676216531@10.10.9.90>]
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <103> <INVITE>
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=as08d1711f
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29
DEBUG: tm [t_reply.c:649]: _reply_light(): DEBUG: reply sent out. buf=0x7f5d81ab1330: SIP/2.0 100 trying -..., shmem=0x7f5d7bc5a480: SIP/2.0 100 trying -
DEBUG: tm [t_reply.c:659]: _reply_light(): DEBUG: _reply_light: finished
DEBUG: <core> [msg_translator.c:159]: check_via_address(): (10.10.1.2, 10.10.1.2, 0)
DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request:
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg():  method:  <INVITE>
DEBUG: <core> [parser/msg_parser.c:604]: parse_msg():  uri:     <sip:676216531@212.106.213.5:5060;user=phone;transport=udp>
DEBUG: <core> [parser/msg_parser.c:606]: parse_msg():  version: <SIP/2.0>
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK907d.5c4312e72360dc514bdcbac581b3d45d.0>; state=16
DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
DEBUG: <core> [parser/msg_parser.c:491]: parse_headers(): Via found, flags=2
DEBUG: <core> [parser/msg_parser.c:493]: parse_headers(): this is the first via
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK71b248a2>; state=16
DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
DEBUG: <core> [parser/msg_parser.c:491]: parse_headers(): Via found, flags=20
DEBUG: <core> [parser/msg_parser.c:504]: parse_headers(): this is the second via
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=gf2cfd0d-CC-50
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29
DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [47]; uri=[sip:676216531@10.10.9.90]
DEBUG: <core> [parser/msg_parser.c:172]: get_hdr_field(): to body [<sip:676216531@10.10.9.90>]
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <103> <INVITE>
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=as08d1711f
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29
DEBUG: dialog [dlg_cseq.c:364]: dlg_cseq_msg_sent(): traking cseq updates
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3012 found on entry 2104
DEBUG: dialog [dlg_hash.c:758]: internal_get_dlg(): no dialog callid='4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060' found
DEBUG: dialog [dlg_hash.c:789]: get_dlg(): no dialog callid='4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060' found
DEBUG: dialog [dlg_handlers.c:1145]: dlg_lookup_msg_dialog(): dlg with callid '4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060' not found
DEBUG: dialog [dlg_cseq.c:376]: dlg_cseq_msg_sent(): no dialog for this request
DEBUG: tm [t_funcs.c:368]: t_relay_to(): SER: new transaction fwd'ed
DEBUG: <core> [receive.c:268]: receive_msg(): request-route executed in: 3134 usec
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3012 found on entry 2104
DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil)
DEBUG: <core> [receive.c:361]: receive_msg(): cleaning up