Last active
September 20, 2016 13:25
-
-
Save cruzccl/c3c0aeede9304caf17708fd84cdcd754 to your computer and use it in GitHub Desktop.
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
DEBUG: <core> [receive.c:341]: receive_msg(): reply-route executed in: 741 usec | |
DEBUG: dialog [dlg_var.c:87]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483652 | |
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 | |
DEBUG: tm [h_table.c:128]: free_cell_helper(): freeing transaction 0x7f5d7bc5a920 from timer.c:648 | |
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@10.10.9.90> | |
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> = <z9hG4bK0081fad4>; 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:894]: parse_addr_spec(): end of header reached, state=10 | |
DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [28]; 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>#015#012] | |
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <102> <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@10.10.9.90> | |
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> = <z9hG4bK0081fad4>; 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:894]: parse_addr_spec(): end of header reached, state=10 | |
DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [28]; 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>#015#012] | |
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:758]: internal_get_dlg(): no dialog callid='4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060' found | |
DEBUG: dialog [dlg_hash.c:823]: dlg_search(): dialog with callid='4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060' not found | |
DEBUG: dialog [dlg_hash.c:454]: build_new_dlg(): new dialog on hash 2104 | |
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <102> <INVITE> | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=200 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_handlers.c:250]: populate_leg_info(): route_set , contact sip:CBT143502TC@10.10.1.2:5060, cseq 102 and bind_addr udp:10.10.9.90:5060 | |
DEBUG: dialog [dlg_hash.c:877]: link_dlg(): linking dialog [2104:3012] | |
DEBUG: dialog [dlg_hash.c:886]: link_dlg(): ref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
DEBUG: rr [record.c:919]: add_rr_param(): rr_param_buf=<;did=838.4cb> | |
DEBUG: dialog [dlg_profile.c:530]: set_current_dialog(): setting current dialog [2104:3012] | |
DEBUG: dialog [dlg_hash.c:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: dialog [dlg_handlers.c:1670]: dlg_manage(): dialog created before transaction | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
DEBUG: siputils [checks.c:120]: has_totag(): no totag | |
DEBUG: rr [loose.c:1202]: is_direction(): param ftag not found | |
DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=3 , global msg id=2 , T on entrance=0xffffffffffffffff | |
DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=55048, 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=0x7f5d7bc5a920, callback type 1, id 0 entered | |
DEBUG: dialog [dlg_hash.c:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: dialog [dlg_handlers.c:722]: dlg_onreq(): dialog added to tm callbacks | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: 26c36e3ab886fcb7bf8a6b932ae293a5 | |
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> = <z9hG4bK0081fad4>; 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:894]: parse_addr_spec(): end of header reached, state=10 | |
DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [28]; 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>#015#012] | |
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <102> <INVITE> | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=0 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_cseq.c:83]: dlg_cseq_prepare_msg(): no second via in this message | |
DEBUG: tm [t_reply.c:649]: _reply_light(): DEBUG: reply sent out. buf=0x7f5d81ab1c90: SIP/2.0 100 trying -..., shmem=0x7f5d7bc59668: SIP/2.0 100 trying - | |
DEBUG: tm [t_reply.c:659]: _reply_light(): DEBUG: _reply_light: finished | |
DEBUG: <core> [dns_cache.c:537]: _dns_hash_find(): (_sip._udp.sarevoz.com(21), 33), h=930 | |
DEBUG: <core> [resolve.c:937]: get_record(): skipping 0 NS (p=0xa486a6, end=0xa486a6) | |
DEBUG: <core> [resolve.c:952]: get_record(): parsing 0 ARs (p=0xa486a6, end=0xa486a6) | |
DEBUG: <core> [dns_cache.c:1741]: dns_get_related(): (0x7f5d7bc594a0 (_sip._udp.sarevoz.com, 33), 33, *(nil)) (0) | |
DEBUG: <core> [dns_cache.c:840]: dns_cache_add_unsafe(): adding _sip._udp.sarevoz.com(21) 33 (flags=0) at 930 | |
DEBUG: <core> [dns_cache.c:537]: _dns_hash_find(): (sarevoz.com(11), 1), h=725 | |
DEBUG: <core> [resolve.c:937]: get_record(): skipping 0 NS (p=0xa4868d, end=0xa4868d) | |
DEBUG: <core> [resolve.c:952]: get_record(): parsing 0 ARs (p=0xa4868d, end=0xa4868d) | |
DEBUG: <core> [dns_cache.c:1741]: dns_get_related(): (0x7f5d7bc59590 (sarevoz.com, 1), 1, *(nil)) (0) | |
DEBUG: <core> [dns_cache.c:840]: dns_cache_add_unsafe(): adding sarevoz.com(11) 1 (flags=0) at 725 | |
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@sarevoz.com> | |
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> = <z9hG4bK807d.26c36e3ab886fcb7bf8a6b932ae293a5.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> = <z9hG4bK0081fad4>; 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:894]: parse_addr_spec(): end of header reached, state=10 | |
DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [28]; 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>#015#012] | |
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <102> <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:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=200 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_cseq.c:396]: dlg_cseq_msg_sent(): new cseq inc not requested | |
DEBUG: dialog [dlg_cseq.c:409]: dlg_cseq_msg_sent(): cseq refresh requested, but no new value found | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
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: 484136 usec | |
DEBUG: dialog [dlg_hash.c:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
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 | |
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: <407> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <Proxy Authentication Required> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK807d.26c36e3ab886fcb7bf8a6b932ae293a5.0>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; 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> = <z9hG4bK0081fad4>; 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=cb72197effc0f8f51b22b97e38a16b35.9491 | |
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> [70]; 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>: <102> <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:293]: dlg_cseq_msg_received(): no matching the starting point length | |
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: <407> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <Proxy Authentication Required> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK807d.26c36e3ab886fcb7bf8a6b932ae293a5.0>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; 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: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=4 global id=3 T start=0xffffffffffffffff | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK0081fad4>; 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=ffffffffffffffff | |
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=cb72197effc0f8f51b22b97e38a16b35.9491 | |
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> [70]; 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>: <102> <INVITE> | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=0 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 55048 label 0 branch 0 | |
DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f5d7bc5a920)! | |
DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f5d7bc5a920, callback type 2, id 0 entered | |
DEBUG: dialog [dlg_hash.c:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: dialog [dlg_profile.c:530]: set_current_dialog(): setting current dialog [2104:3012] | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=4 global id=4 T end=0x7f5d7bc5a920 | |
DEBUG: tm [t_reply.c:2182]: reply_received(): DEBUG: reply_received: org. status uas=100, uac[0]=0 local=0 is_invite=1) | |
DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request: | |
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg(): method: <ACK> | |
DEBUG: <core> [parser/msg_parser.c:604]: parse_msg(): uri: <sip:676216531@sarevoz.com> | |
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> = <z9hG4bK807d.26c36e3ab886fcb7bf8a6b932ae293a5.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_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=cb72197effc0f8f51b22b97e38a16b35.9491 | |
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> [70]; 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>: <102> <ACK> | |
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:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=0 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_cseq.c:409]: dlg_cseq_msg_sent(): cseq refresh requested, but no new value found | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
DEBUG: tm [t_reply.c:1252]: t_should_relay_response(): ->>>>>>>>> T_code=100, new_code=407 | |
DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f5d7bc5a920, callback type 128, id 0 entered | |
DEBUG: dialog [dlg_hash.c:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: dialog [dlg_profile.c:530]: set_current_dialog(): setting current dialog [2104:3012] | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=2 global id=2 T start=0x7f5d7bc5a920 | |
DEBUG: tm [t_lookup.c:1083]: t_check_msg(): DEBUG: t_check_msg: T already found! | |
DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=2 global id=2 T start=0x7f5d7bc5a920 | |
DEBUG: tm [t_lookup.c:1083]: t_check_msg(): DEBUG: t_check_msg: T already found! | |
DEBUG: tm [tm.c:943]: t_check_status(): checked status is <407> | |
DEBUG: uac [auth.c:409]: uac_auth(): picked reply is 0x7f5d81aafb10, code 407 | |
DEBUG: uac [auth.c:226]: get_autenticate_hdr(): looking for header "Proxy-Authenticate" | |
DEBUG: uac [auth.c:430]: uac_auth(): header found; body=<Digest realm="sarevoz.com", nonce="V+Efz1fhHqMx+iYzI1Yg3Pbil1bwahaq"> | |
DEBUG: uac [auth_hdr.c:210]: parse_authenticate_body(): <realm>="sarevoz.com" state=2 | |
DEBUG: uac [auth_hdr.c:210]: parse_authenticate_body(): <nonce>="V+Efz1fhHqMx+iYzI1Yg3Pbil1bwahaq" state=3 | |
DEBUG: uac [auth_hdr.c:409]: build_authorization_hdr(): hdr is <Proxy-Authorization: Digest username="20195", realm="sarevoz.com", nonce="V+Efz1fhHqMx+iYzI1Yg3Pbil1bwahaq", uri="sip:676216531@sarevoz.com", response="2ebd28641985cd2a222f0dad04c2be7d", algorithm=MD5#015#012> | |
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@10.10.9.90> | |
DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): version: <SIP/2.0> | |
DEBUG: dialog [dlg_cseq.c:129]: dlg_cseq_update(): initiating cseq updates | |
DEBUG: dialog [dlg_hash.c:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: dialog [dlg_var.c:251]: print_lists(): Internal var-list ((nil)): | |
DEBUG: dialog [dlg_var.c:261]: print_lists(): Dialog var-list (0x7f5d7bc59cb8): | |
DEBUG: dialog [dlg_var.c:266]: print_lists(): cseq_diff=1 (flags 1) | |
DEBUG: dialog [dlg_cseq.c:176]: dlg_cseq_update(): adding auth cseq header value: 103 | |
DEBUG: <core> [data_lump.c:770]: sr_hdr_add(): added new header [P-K-Auth-CSeq: 103#015#012] | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
DEBUG: <core> [dns_cache.c:537]: _dns_hash_find(): (_sip._udp.sarevoz.com(21), 33), h=930 | |
DEBUG: <core> [dns_cache.c:537]: _dns_hash_find(): (sarevoz.com(11), 1), h=725 | |
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@sarevoz.com> | |
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> = <z9hG4bK807d.26c36e3ab886fcb7bf8a6b932ae293a5.1>; 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> = <z9hG4bK0081fad4>; 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:894]: parse_addr_spec(): end of header reached, state=10 | |
DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [28]; 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>#015#012] | |
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <102> <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:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=200 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_cseq.c:392]: dlg_cseq_msg_sent(): new cseq inc requested | |
DEBUG: dialog [dlg_cseq.c:417]: dlg_cseq_msg_sent(): updating cseq to: 103 | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
DEBUG: dialog [dlg_var.c:87]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483650 | |
DEBUG: tm [t_reply.c:1771]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=-1 icode=0 | |
DEBUG: <core> [receive.c:341]: receive_msg(): reply-route executed in: 3850 usec | |
DEBUG: dialog [dlg_var.c:87]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483652 | |
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 | |
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> = <z9hG4bK807d.26c36e3ab886fcb7bf8a6b932ae293a5.1.cs102>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; 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> = <z9hG4bK0081fad4>; 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:894]: parse_addr_spec(): end of header reached, state=10 | |
DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [28]; 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>#015#012] | |
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:307]: dlg_cseq_msg_received(): via cseq cookie [.cs102] val [102] | |
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> = <z9hG4bK807d.26c36e3ab886fcb7bf8a6b932ae293a5.1>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 253, <z> = <102>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; 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: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=4 global id=3 T start=(nil) | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK0081fad4>; 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=62 | |
DEBUG: <core> [parser/msg_parser.c:504]: parse_headers(): this is the second via | |
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=10 | |
DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [28]; 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>#015#012] | |
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <102> <INVITE> | |
DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 55048 label 0 branch 1 | |
DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f5d7bc5a920)! | |
DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f5d7bc5a920, callback type 2, id 0 entered | |
DEBUG: dialog [dlg_hash.c:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: dialog [dlg_profile.c:530]: set_current_dialog(): setting current dialog [2104:3012] | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=4 global id=4 T end=0x7f5d7bc5a920 | |
DEBUG: tm [t_reply.c:2182]: reply_received(): DEBUG: reply_received: org. status uas=100, uac[1]=0 local=0 is_invite=1) | |
DEBUG: tm [t_reply.c:1252]: t_should_relay_response(): ->>>>>>>>> T_code=100, new_code=100 | |
DEBUG: tm [t_reply.c:1771]: relay_reply(): DEBUG: relay_reply: branch=1, save=0, relay=-1 icode=0 | |
DEBUG: <core> [receive.c:341]: receive_msg(): reply-route executed in: 287 usec | |
DEBUG: dialog [dlg_var.c:87]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483652 | |
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 | |
DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request: | |
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg(): method: <BYE> | |
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> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=as288de6bb | |
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: <BYE> | |
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> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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=as288de6bb | |
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 | |
DEBUG: dialog [dlg_hash.c:758]: internal_get_dlg(): no dialog callid='2009e6f165740b1216d2639510a63730@10.10.1.2:5060' found | |
DEBUG: dialog [dlg_hash.c:789]: get_dlg(): no dialog callid='2009e6f165740b1216d2639510a63730@10.10.1.2:5060' found | |
DEBUG: dialog [dlg_handlers.c:1261]: dlg_onroute(): Callid '2009e6f165740b1216d2639510a63730@10.10.1.2:5060' not found | |
DEBUG: siputils [checks.c:123]: has_totag(): totag found | |
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=as288de6bb;did=a39.eac' is me | |
DEBUG: rr [loose.c:877]: after_loose(): URI to be processed: 'sip:194.30.0.111;lr;ftag=as288de6bb;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=as288de6bb;did=a39.eac> | |
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=as288de6bb;did=a39.eac> | |
DEBUG: dialog [dlg_handlers.c:1204]: dlg_onroute(): route param is 'a39.eac' (len=7) | |
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3246 found on entry 2362 | |
WARNING: dialog [dlg_handlers.c:1215]: dlg_onroute(): unable to find dialog for BYE with route param 'a39.eac' [2362:3246] | |
DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=4 , global msg id=3 , T on entrance=0xffffffffffffffff | |
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <105> <BYE> | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=0 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=6153, 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: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: addb5567f67a5eaaee645240d279d49d | |
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: <BYE> | |
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> = <z9hG4bK9081.addb5567f67a5eaaee645240d279d49d.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> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=as288de6bb | |
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:758]: internal_get_dlg(): no dialog callid='2009e6f165740b1216d2639510a63730@10.10.1.2:5060' found | |
DEBUG: dialog [dlg_hash.c:789]: get_dlg(): no dialog callid='2009e6f165740b1216d2639510a63730@10.10.1.2:5060' found | |
DEBUG: dialog [dlg_handlers.c:1145]: dlg_lookup_msg_dialog(): dlg with callid '2009e6f165740b1216d2639510a63730@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: 1532 usec | |
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 | |
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: <481> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <Unknown Dialog> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK9081.addb5567f67a5eaaee645240d279d49d.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> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=as288de6bb | |
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 | |
DEBUG: dialog [dlg_cseq.c:293]: dlg_cseq_msg_received(): no matching the starting point length | |
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: <481> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <Unknown Dialog> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK9081.addb5567f67a5eaaee645240d279d49d.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> [receive.c:184]: receive_msg(): After parse_msg... | |
DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=4 global id=3 T start=0xffffffffffffffff | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK784a3c9f>; 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=62 | |
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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 6153 label 0 branch 0 | |
DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f5d7bc5bfd0)! | |
DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=4 global id=4 T end=0x7f5d7bc5bfd0 | |
DEBUG: tm [t_reply.c:2182]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0 local=0 is_invite=0) | |
DEBUG: tm [t_reply.c:1252]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=481 | |
DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=3 global id=3 T start=0x7f5d7bc5bfd0 | |
DEBUG: tm [t_lookup.c:1083]: t_check_msg(): DEBUG: t_check_msg: T already found! | |
DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=3 global id=3 T start=0x7f5d7bc5bfd0 | |
DEBUG: tm [t_lookup.c:1083]: t_check_msg(): DEBUG: t_check_msg: T already found! | |
DEBUG: tm [tm.c:943]: t_check_status(): checked status is <481> | |
ERROR: tm [t_fwd.c:1734]: t_forward_nonack(): ERROR: t_forward_nonack: no branches for forwarding | |
ERROR: tm [tm.c:1426]: _w_t_relay_to(): t_forward_noack failed | |
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: <500> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <I'm terribly sorry, server error occurred (6/SL)> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=0 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_cseq.c:83]: dlg_cseq_prepare_msg(): no second via in this message | |
ERROR: sl [sl_funcs.c:363]: sl_reply_error(): ERROR: sl_reply_error used: I'm terribly sorry, server error occurred (6/SL) | |
DEBUG: dialog [dlg_var.c:87]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483650 | |
DEBUG: tm [t_reply.c:1771]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0 | |
DEBUG: <core> [msg_translator.c:2261]: generate_res_buf_from_sip_res(): old size: 388, new size: 274 | |
DEBUG: <core> [msg_translator.c:2279]: generate_res_buf_from_sip_res(): copied size: orig:142, new: 28, rest: 246 msg=#012SIP/2.0 481 Unknown Dialog#015#012Via: SIP/2.0/UDP 10.10.1.2:5060;branch=z9hG4bK784a3c9f#015#012From: "CBT143502TC" <sip:CBT143502TC@10.10.1.2>;tag=as288de6bb#015#012To: <sip:676216531@10.10.9.90>;tag=3gx1ammf-CC-31#015#012Call-ID: 2009e6f165740b1216d2639510a63730@10.10.1.2:5060#015#012CSeq: 105 BYE#015#012#015#012 | |
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: <481> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <Unknown Dialog> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_cseq.c:83]: dlg_cseq_prepare_msg(): no second via in this message | |
DEBUG: tm [t_reply.c:1612]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset | |
DEBUG: <core> [receive.c:341]: receive_msg(): reply-route executed in: 1772 usec | |
DEBUG: dialog [dlg_var.c:87]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483652 | |
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 | |
DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request: | |
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg(): method: <BYE> | |
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> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=as288de6bb | |
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: <BYE> | |
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> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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=as288de6bb | |
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 | |
DEBUG: dialog [dlg_hash.c:758]: internal_get_dlg(): no dialog callid='2009e6f165740b1216d2639510a63730@10.10.1.2:5060' found | |
DEBUG: dialog [dlg_hash.c:789]: get_dlg(): no dialog callid='2009e6f165740b1216d2639510a63730@10.10.1.2:5060' found | |
DEBUG: dialog [dlg_handlers.c:1261]: dlg_onroute(): Callid '2009e6f165740b1216d2639510a63730@10.10.1.2:5060' not found | |
DEBUG: siputils [checks.c:123]: has_totag(): totag found | |
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=as288de6bb;did=a39.eac' is me | |
DEBUG: rr [loose.c:877]: after_loose(): URI to be processed: 'sip:194.30.0.111;lr;ftag=as288de6bb;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=as288de6bb;did=a39.eac> | |
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=as288de6bb;did=a39.eac> | |
DEBUG: dialog [dlg_handlers.c:1204]: dlg_onroute(): route param is 'a39.eac' (len=7) | |
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3246 found on entry 2362 | |
WARNING: dialog [dlg_handlers.c:1215]: dlg_onroute(): unable to find dialog for BYE with route param 'a39.eac' [2362:3246] | |
DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=4 , global msg id=3 , T on entrance=0xffffffffffffffff | |
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <105> <BYE> | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=0 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=6153, isACK=0 | |
DEBUG: tm [t_lookup.c:409]: matching_3261(): DEBUG: RFC3261 transaction matched, tid=784a3c9f | |
DEBUG: tm [t_lookup.c:665]: t_lookup_request(): DEBUG: t_lookup_request: transaction found (T=0x7f5d7bc5bfd0) | |
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: <481> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <Unknown Dialog> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_cseq.c:83]: dlg_cseq_prepare_msg(): no second via in this message | |
DEBUG: tm [t_reply.c:1572]: t_retransmit_reply(): DEBUG: reply retransmitted. buf=0x7f5d81a21ce0: SIP/2.0 4..., shmem=0x7f5d7bc5d900: SIP/2.0 4 | |
DEBUG: <core> [receive.c:268]: receive_msg(): request-route executed in: 993 usec | |
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 | |
DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request: | |
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg(): method: <BYE> | |
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> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=as288de6bb | |
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: <BYE> | |
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> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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=as288de6bb | |
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 | |
DEBUG: dialog [dlg_hash.c:758]: internal_get_dlg(): no dialog callid='2009e6f165740b1216d2639510a63730@10.10.1.2:5060' found | |
DEBUG: dialog [dlg_hash.c:789]: get_dlg(): no dialog callid='2009e6f165740b1216d2639510a63730@10.10.1.2:5060' found | |
DEBUG: dialog [dlg_handlers.c:1261]: dlg_onroute(): Callid '2009e6f165740b1216d2639510a63730@10.10.1.2:5060' not found | |
DEBUG: siputils [checks.c:123]: has_totag(): totag found | |
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=as288de6bb;did=a39.eac' is me | |
DEBUG: rr [loose.c:877]: after_loose(): URI to be processed: 'sip:194.30.0.111;lr;ftag=as288de6bb;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=as288de6bb;did=a39.eac> | |
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=as288de6bb;did=a39.eac> | |
DEBUG: dialog [dlg_handlers.c:1204]: dlg_onroute(): route param is 'a39.eac' (len=7) | |
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3246 found on entry 2362 | |
WARNING: dialog [dlg_handlers.c:1215]: dlg_onroute(): unable to find dialog for BYE with route param 'a39.eac' [2362:3246] | |
DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=4 , global msg id=3 , T on entrance=0xffffffffffffffff | |
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <105> <BYE> | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=0 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=6153, isACK=0 | |
DEBUG: tm [t_lookup.c:409]: matching_3261(): DEBUG: RFC3261 transaction matched, tid=784a3c9f | |
DEBUG: tm [t_lookup.c:665]: t_lookup_request(): DEBUG: t_lookup_request: transaction found (T=0x7f5d7bc5bfd0) | |
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: <481> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <Unknown Dialog> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_cseq.c:83]: dlg_cseq_prepare_msg(): no second via in this message | |
DEBUG: tm [t_reply.c:1572]: t_retransmit_reply(): DEBUG: reply retransmitted. buf=0x7f5d81a21ce0: SIP/2.0 4..., shmem=0x7f5d7bc5d900: SIP/2.0 4 | |
DEBUG: <core> [receive.c:268]: receive_msg(): request-route executed in: 1214 usec | |
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 | |
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: <180> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <Ringing> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK807d.26c36e3ab886fcb7bf8a6b932ae293a5.1.cs102>; 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> = <z9hG4bK0081fad4>; 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:307]: dlg_cseq_msg_received(): via cseq cookie [.cs102] val [102] | |
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: <180> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <Ringing> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK807d.26c36e3ab886fcb7bf8a6b932ae293a5.1>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 253, <z> = <102>; 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: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=5 global id=4 T start=0xffffffffffffffff | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK0081fad4>; 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=62 | |
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>: <102> <INVITE> | |
DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 55048 label 0 branch 1 | |
DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f5d7bc5a920)! | |
DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f5d7bc5a920, callback type 2, id 0 entered | |
DEBUG: dialog [dlg_hash.c:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: dialog [dlg_profile.c:530]: set_current_dialog(): setting current dialog [2104:3012] | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=5 global id=5 T end=0x7f5d7bc5a920 | |
DEBUG: tm [t_reply.c:2182]: reply_received(): DEBUG: reply_received: org. status uas=100, uac[1]=100 local=0 is_invite=1) | |
DEBUG: tm [t_reply.c:1252]: t_should_relay_response(): ->>>>>>>>> T_code=100, new_code=180 | |
DEBUG: tm [t_reply.c:1771]: relay_reply(): DEBUG: relay_reply: branch=1, save=0, relay=1 icode=0 | |
DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f5d7bc5a920, callback type 32, id 0 entered | |
DEBUG: dialog [dlg_hash.c:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
DEBUG: <core> [msg_translator.c:2261]: generate_res_buf_from_sip_res(): old size: 894, new size: 774 | |
DEBUG: <core> [msg_translator.c:2279]: generate_res_buf_from_sip_res(): copied size: orig:141, new: 21, rest: 753 msg=#012SIP/2.0 180 Ringing#015#012Via: SIP/2.0/UDP 10.10.1.2:5060;branch=z9hG4bK0081fad4#015#012From: "CBT143502TC" <sip:20195@sarevoz.com>;tag=as08d1711f#015#012To: <sip:676216531@10.10.9.90>;tag=gf2cfd0d-CC-50#015#012Call-ID: 4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060#015#012CSeq: 102 INVITE#015#012Record-Route: <sip:194.30.0.111;lr;ftag=as08d1711f;nat=yes>#015#012Record-Route: <sip:10.10.9.90;lr;ftag=as08d1711f;did=838.4cb>#015#012Contact: <sip:676216531@212.106.213.5:5060;user=phone;transport=udp>#015#012Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER#015#012Content-Length: 166#015#012Content-Type: application/sdp#015#012#015#012v=0#015#012o=HuaweiSoftX3000 162413883 162413883 IN IP4 212.106.213.9#015#012s=Sip Call#015#012c=IN IP4 194.30.0.111#015#012t=0 0#015#012m=audio 52336 RTP/AVP 8#015#012a=rtpmap:8 PCMA/8000#015#012a=ptime:20#015#012 | |
DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f5d7bc5a920, callback type 1048576, id 0 entered | |
DEBUG: dialog [dlg_hash.c:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: dialog [dlg_hash.c:1120]: next_state_dlg(): dialog 0x7f5d7bc5a480 changed from state 1 to state 2, due event 2 (ref 2) | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
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: <180> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <Ringing> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK0081fad4>; 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>: <102> <INVITE> | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=166 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_cseq.c:83]: dlg_cseq_prepare_msg(): no second via in this message | |
DEBUG: <core> [receive.c:341]: receive_msg(): reply-route executed in: 1099 usec | |
DEBUG: dialog [dlg_var.c:87]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483652 | |
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 | |
DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request: | |
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg(): method: <BYE> | |
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> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=as288de6bb | |
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: <BYE> | |
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> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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=as288de6bb | |
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 | |
DEBUG: dialog [dlg_hash.c:758]: internal_get_dlg(): no dialog callid='2009e6f165740b1216d2639510a63730@10.10.1.2:5060' found | |
DEBUG: dialog [dlg_hash.c:789]: get_dlg(): no dialog callid='2009e6f165740b1216d2639510a63730@10.10.1.2:5060' found | |
DEBUG: dialog [dlg_handlers.c:1261]: dlg_onroute(): Callid '2009e6f165740b1216d2639510a63730@10.10.1.2:5060' not found | |
DEBUG: siputils [checks.c:123]: has_totag(): totag found | |
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=as288de6bb;did=a39.eac' is me | |
DEBUG: rr [loose.c:877]: after_loose(): URI to be processed: 'sip:194.30.0.111;lr;ftag=as288de6bb;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=as288de6bb;did=a39.eac> | |
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=as288de6bb;did=a39.eac> | |
DEBUG: dialog [dlg_handlers.c:1204]: dlg_onroute(): route param is 'a39.eac' (len=7) | |
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3246 found on entry 2362 | |
WARNING: dialog [dlg_handlers.c:1215]: dlg_onroute(): unable to find dialog for BYE with route param 'a39.eac' [2362:3246] | |
DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=4 , global msg id=3 , T on entrance=0xffffffffffffffff | |
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <105> <BYE> | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=0 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=6153, isACK=0 | |
DEBUG: tm [t_lookup.c:409]: matching_3261(): DEBUG: RFC3261 transaction matched, tid=784a3c9f | |
DEBUG: tm [t_lookup.c:665]: t_lookup_request(): DEBUG: t_lookup_request: transaction found (T=0x7f5d7bc5bfd0) | |
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: <481> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <Unknown Dialog> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_cseq.c:83]: dlg_cseq_prepare_msg(): no second via in this message | |
DEBUG: tm [t_reply.c:1572]: t_retransmit_reply(): DEBUG: reply retransmitted. buf=0x7f5d81a21ce0: SIP/2.0 4..., shmem=0x7f5d7bc5d900: SIP/2.0 4 | |
DEBUG: <core> [receive.c:268]: receive_msg(): request-route executed in: 1268 usec | |
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 | |
DEBUG: tm [h_table.c:128]: free_cell_helper(): freeing transaction 0x7f5d7bc5bfd0 from timer.c:648 | |
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: <200> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <OK> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK807d.26c36e3ab886fcb7bf8a6b932ae293a5.1.cs102>; 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> = <z9hG4bK0081fad4>; 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:307]: dlg_cseq_msg_received(): via cseq cookie [.cs102] val [102] | |
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: <200> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <OK> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK807d.26c36e3ab886fcb7bf8a6b932ae293a5.1>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 253, <z> = <102>; 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: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=5 global id=4 T start=0xffffffffffffffff | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK0081fad4>; 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=62 | |
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>: <102> <INVITE> | |
DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 55048 label 0 branch 1 | |
DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f5d7bc5a920)! | |
DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f5d7bc5a920, callback type 2, id 0 entered | |
DEBUG: dialog [dlg_hash.c:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: dialog [dlg_profile.c:530]: set_current_dialog(): setting current dialog [2104:3012] | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=5 global id=5 T end=0x7f5d7bc5a920 | |
DEBUG: tm [t_reply.c:2182]: reply_received(): DEBUG: reply_received: org. status uas=180, uac[1]=180 local=0 is_invite=1) | |
DEBUG: tm [t_reply.c:1252]: t_should_relay_response(): ->>>>>>>>> T_code=180, new_code=200 | |
DEBUG: tm [t_reply.c:1771]: relay_reply(): DEBUG: relay_reply: branch=1, save=0, relay=1 icode=0 | |
DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f5d7bc5a920, callback type 32, id 0 entered | |
DEBUG: dialog [dlg_hash.c:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
DEBUG: <core> [msg_translator.c:2261]: generate_res_buf_from_sip_res(): old size: 793, new size: 673 | |
DEBUG: <core> [msg_translator.c:2279]: generate_res_buf_from_sip_res(): copied size: orig:136, new: 16, rest: 657 msg=#012SIP/2.0 200 OK#015#012Via: SIP/2.0/UDP 10.10.1.2:5060;branch=z9hG4bK0081fad4#015#012From: "CBT143502TC" <sip:20195@sarevoz.com>;tag=as08d1711f#015#012To: <sip:676216531@10.10.9.90>;tag=gf2cfd0d-CC-50#015#012Call-ID: 4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060#015#012CSeq: 102 INVITE#015#012Record-Route: <sip:194.30.0.111;lr;ftag=as08d1711f;nat=yes>#015#012Record-Route: <sip:10.10.9.90;lr;ftag=as08d1711f;did=838.4cb>#015#012Contact: <sip:676216531@212.106.213.5:5060;user=phone;transport=udp>#015#012Content-Length: 166#015#012Content-Type: application/sdp#015#012#015#012v=0#015#012o=HuaweiSoftX3000 162413883 162413884 IN IP4 212.106.213.9#015#012s=Sip Call#015#012c=IN IP4 194.30.0.111#015#012t=0 0#015#012m=audio 52336 RTP/AVP 8#015#012a=rtpmap:8 PCMA/8000#015#012a=ptime:20#015#012 | |
DEBUG: tm [t_reply.c:336]: update_totag_set(): DEBUG: update_totag_set: new totag | |
DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f5d7bc5a920, callback type 1048576, id 0 entered | |
DEBUG: dialog [dlg_hash.c:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: dialog [dlg_hash.c:1120]: next_state_dlg(): dialog 0x7f5d7bc5a480 changed from state 2 to state 3, due event 3 (ref 2) | |
DEBUG: dialog [dlg_handlers.c:464]: dlg_onreply(): dialog 0x7f5d7bc5a480 confirmed (ACK pending) | |
DEBUG: <core> [parser/parse_rr.c:370]: print_rr_body(): current rr is <sip:194.30.0.111;lr;ftag=as08d1711f;nat=yes> | |
DEBUG: <core> [parser/parse_rr.c:370]: print_rr_body(): current rr is <sip:10.10.9.90;lr;ftag=as08d1711f;did=838.4cb> | |
DEBUG: <core> [parser/parse_rr.c:395]: print_rr_body(): skipping 1 route records | |
DEBUG: <core> [parser/parse_rr.c:433]: print_rr_body(): out rr [<sip:194.30.0.111;lr;ftag=as08d1711f;nat=yes>] | |
DEBUG: <core> [parser/parse_rr.c:434]: print_rr_body(): we have 1 records | |
DEBUG: dialog [dlg_handlers.c:250]: populate_leg_info(): route_set <sip:194.30.0.111;lr;ftag=as08d1711f;nat=yes>, contact sip:676216531@212.106.213.5:5060;user=phone;transport=udp, cseq and bind_addr udp:10.10.9.90:5060 | |
DEBUG: dialog [dlg_timer.c:110]: insert_dialog_timer_unsafe(): inserting 0x7f5d7bc5a4e0 for 131338301 | |
DEBUG: dialog [dlg_hash.c:906]: dlg_ref(): ref dlg 0x7f5d7bc5a480 with 1 -> 3 | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
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: <200> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <OK> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK0081fad4>; 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>: <102> <INVITE> | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=166 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_cseq.c:83]: dlg_cseq_prepare_msg(): no second via in this message | |
DEBUG: tm [t_reply.c:1612]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset | |
DEBUG: <core> [receive.c:341]: receive_msg(): reply-route executed in: 1269 usec | |
DEBUG: dialog [dlg_var.c:87]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483652 | |
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 | |
DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request: | |
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg(): method: <ACK> | |
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> = <z9hG4bK65c0ccaa>; 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>: <102> <ACK> | |
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: <ACK> | |
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> = <z9hG4bK65c0ccaa>; 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: sl [sl_funcs.c:388]: sl_filter_ACK(): DEBUG : sl_filter_ACK: to late to be a local ACK! | |
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 -> 3 | |
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: <ACK> | |
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> = <z9hG4bK65c0ccaa>; 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>: <102> <ACK> | |
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: 103 | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=0 | |
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: 103#015#012] | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:1120]: next_state_dlg(): dialog 0x7f5d7bc5a480 changed from state 3 to state 4, due event 6 (ref 2) | |
DEBUG: dialog [dlg_timer.c:110]: insert_dialog_timer_unsafe(): inserting 0x7f5d7bc5a4e0 for 131338301 | |
DEBUG: dialog [dlg_handlers.c:1401]: dlg_onroute(): confirming ACK successfully processed | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
DEBUG: siputils [checks.c:123]: has_totag(): totag found | |
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:692]: dlg_lookup(): ref dlg 0x7f5d7bc5a480 with 1 -> 2 | |
DEBUG: dialog [dlg_hash.c:694]: dlg_lookup(): dialog id=3012 found on entry 2104 | |
DEBUG: dialog [dlg_hash.c:924]: dlg_unref(): unref dlg 0x7f5d7bc5a480 with 1 -> 1 | |
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=55048, isACK=1 | |
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_funcs.c:285]: t_relay_to(): SER: forwarding ACK statelessly | |
DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: 7efe160314610c57ce5a19f3d30117f5 | |
DEBUG: <core> [msg_translator.c:159]: check_via_address(): (10.10.1.2, 10.10.1.2, 0) | |
DEBUG: <core> [forward.c:556]: forward_request(): Sending:#012ACK sip:676216531@212.106.213.5:5060;user=phone;transport=udp SIP/2.0#015#012Via: SIP/2.0/UDP 10.10.9.90;branch=z9hG4bK807d.7efe160314610c57ce5a19f3d30117f5.0#015#012Via: SIP/2.0/UDP 10.10.1.2:5060;branch=z9hG4bK65c0ccaa#015#012Route: <sip:194.30.0.111;lr;ftag=as08d1711f;nat=yes>#015#012Max-Forwards: 70#015#012From: "CBT143502TC" <sip:CBT143502TC@10.10.1.2>;tag=as08d1711f#015#012To: <sip:676216531@10.10.9.90>;tag=gf2cfd0d-CC-50#015#012Contact: <sip:CBT143502TC@10.10.1.2:5060>#015#012Call-ID: 4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060#015#012CSeq: 102 ACK#015#012User-Agent: "Irontec IVOZ"#015#012Content-Length: 0#015#012P-K-CSeq-Refresh: 103#015#012#015#012. | |
DEBUG: <core> [forward.c:558]: forward_request(): orig. len=527, new_len=585, proto=1 | |
DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request: | |
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg(): method: <ACK> | |
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> = <z9hG4bK807d.7efe160314610c57ce5a19f3d30117f5.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> = <z9hG4bK65c0ccaa>; 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: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:152]: get_hdr_field(): cseq <CSeq>: <102> <ACK> | |
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: <core> [receive.c:268]: receive_msg(): request-route executed in: 4541 usec | |
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3012 found on entry 2104 | |
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: <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 | |
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 | |
DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request: | |
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg(): method: <BYE> | |
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> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=as288de6bb | |
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: <BYE> | |
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> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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=as288de6bb | |
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 | |
DEBUG: dialog [dlg_hash.c:758]: internal_get_dlg(): no dialog callid='2009e6f165740b1216d2639510a63730@10.10.1.2:5060' found | |
DEBUG: dialog [dlg_hash.c:789]: get_dlg(): no dialog callid='2009e6f165740b1216d2639510a63730@10.10.1.2:5060' found | |
DEBUG: dialog [dlg_handlers.c:1261]: dlg_onroute(): Callid '2009e6f165740b1216d2639510a63730@10.10.1.2:5060' not found | |
DEBUG: siputils [checks.c:123]: has_totag(): totag found | |
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=as288de6bb;did=a39.eac' is me | |
DEBUG: rr [loose.c:877]: after_loose(): URI to be processed: 'sip:194.30.0.111;lr;ftag=as288de6bb;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=as288de6bb;did=a39.eac> | |
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=as288de6bb;did=a39.eac> | |
DEBUG: dialog [dlg_handlers.c:1204]: dlg_onroute(): route param is 'a39.eac' (len=7) | |
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3246 found on entry 2362 | |
WARNING: dialog [dlg_handlers.c:1215]: dlg_onroute(): unable to find dialog for BYE with route param 'a39.eac' [2362:3246] | |
DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=5 , global msg id=4 , T on entrance=0xffffffffffffffff | |
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <105> <BYE> | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=0 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=6153, 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=0x7f5d7bc5fcd0, callback type 1, id 0 entered | |
DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: addb5567f67a5eaaee645240d279d49d | |
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: <BYE> | |
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> = <z9hG4bK9081.addb5567f67a5eaaee645240d279d49d.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> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=as288de6bb | |
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:758]: internal_get_dlg(): no dialog callid='2009e6f165740b1216d2639510a63730@10.10.1.2:5060' found | |
DEBUG: dialog [dlg_hash.c:789]: get_dlg(): no dialog callid='2009e6f165740b1216d2639510a63730@10.10.1.2:5060' found | |
DEBUG: dialog [dlg_handlers.c:1145]: dlg_lookup_msg_dialog(): dlg with callid '2009e6f165740b1216d2639510a63730@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: 2274 usec | |
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 | |
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: <481> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <Unknown Dialog> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK9081.addb5567f67a5eaaee645240d279d49d.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> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=as288de6bb | |
DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 | |
DEBUG: dialog [dlg_cseq.c:293]: dlg_cseq_msg_received(): no matching the starting point length | |
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: <481> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <Unknown Dialog> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK9081.addb5567f67a5eaaee645240d279d49d.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> [receive.c:184]: receive_msg(): After parse_msg... | |
DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=5 global id=4 T start=0xffffffffffffffff | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK784a3c9f>; 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=62 | |
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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 6153 label 0 branch 0 | |
DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f5d7bc5fcd0)! | |
DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=5 global id=5 T end=0x7f5d7bc5fcd0 | |
DEBUG: tm [t_reply.c:2182]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0 local=0 is_invite=0) | |
DEBUG: tm [t_reply.c:1252]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=481 | |
DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=4 global id=4 T start=0x7f5d7bc5fcd0 | |
DEBUG: tm [t_lookup.c:1083]: t_check_msg(): DEBUG: t_check_msg: T already found! | |
DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=4 global id=4 T start=0x7f5d7bc5fcd0 | |
DEBUG: tm [t_lookup.c:1083]: t_check_msg(): DEBUG: t_check_msg: T already found! | |
DEBUG: tm [tm.c:943]: t_check_status(): checked status is <481> | |
ERROR: tm [t_fwd.c:1734]: t_forward_nonack(): ERROR: t_forward_nonack: no branches for forwarding | |
ERROR: tm [tm.c:1426]: _w_t_relay_to(): t_forward_noack failed | |
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: <500> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <I'm terribly sorry, server error occurred (6/SL)> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=0 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_cseq.c:83]: dlg_cseq_prepare_msg(): no second via in this message | |
ERROR: sl [sl_funcs.c:363]: sl_reply_error(): ERROR: sl_reply_error used: I'm terribly sorry, server error occurred (6/SL) | |
DEBUG: dialog [dlg_var.c:87]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483650 | |
DEBUG: tm [t_reply.c:1771]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0 | |
DEBUG: <core> [msg_translator.c:2261]: generate_res_buf_from_sip_res(): old size: 388, new size: 274 | |
DEBUG: <core> [msg_translator.c:2279]: generate_res_buf_from_sip_res(): copied size: orig:142, new: 28, rest: 246 msg=#012SIP/2.0 481 Unknown Dialog#015#012Via: SIP/2.0/UDP 10.10.1.2:5060;branch=z9hG4bK784a3c9f#015#012From: "CBT143502TC" <sip:CBT143502TC@10.10.1.2>;tag=as288de6bb#015#012To: <sip:676216531@10.10.9.90>;tag=3gx1ammf-CC-31#015#012Call-ID: 2009e6f165740b1216d2639510a63730@10.10.1.2:5060#015#012CSeq: 105 BYE#015#012#015#012 | |
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: <481> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <Unknown Dialog> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK784a3c9f>; 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=3gx1ammf-CC-31 | |
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>: <105> <BYE> | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_cseq.c:83]: dlg_cseq_prepare_msg(): no second via in this message | |
DEBUG: tm [t_reply.c:1612]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset | |
DEBUG: <core> [receive.c:341]: receive_msg(): reply-route executed in: 1328 usec | |
DEBUG: dialog [dlg_var.c:87]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483652 | |
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 | |
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: <200> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <OK> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK807d.26c36e3ab886fcb7bf8a6b932ae293a5.1.cs102>; 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> = <z9hG4bK0081fad4>; 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:307]: dlg_cseq_msg_received(): via cseq cookie [.cs102] val [102] | |
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: <200> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <OK> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK807d.26c36e3ab886fcb7bf8a6b932ae293a5.1>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 253, <z> = <102>; 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: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=5 global id=4 T start=0xffffffffffffffff | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK0081fad4>; 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=62 | |
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>: <102> <INVITE> | |
DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 55048 label 0 branch 1 | |
DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f5d7bc5a920)! | |
DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f5d7bc5a920, callback type 2, id 0 entered | |
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3012 found on entry 2104 | |
DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=5 global id=5 T end=0x7f5d7bc5a920 | |
DEBUG: tm [t_reply.c:2182]: reply_received(): DEBUG: reply_received: org. status uas=200, uac[1]=200 local=0 is_invite=1) | |
DEBUG: tm [t_reply.c:1252]: t_should_relay_response(): ->>>>>>>>> T_code=200, new_code=200 | |
DEBUG: tm [t_reply.c:1257]: t_should_relay_response(): DBG: t_should_relay_response: 200 INV after final sent | |
DEBUG: tm [t_reply.c:1771]: relay_reply(): DEBUG: relay_reply: branch=1, save=0, relay=1 icode=0 | |
DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f5d7bc5a920, callback type 32, id 0 entered | |
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3012 found on entry 2104 | |
DEBUG: <core> [msg_translator.c:2261]: generate_res_buf_from_sip_res(): old size: 793, new size: 673 | |
DEBUG: <core> [msg_translator.c:2279]: generate_res_buf_from_sip_res(): copied size: orig:136, new: 16, rest: 657 msg=#012SIP/2.0 200 OK#015#012Via: SIP/2.0/UDP 10.10.1.2:5060;branch=z9hG4bK0081fad4#015#012From: "CBT143502TC" <sip:20195@sarevoz.com>;tag=as08d1711f#015#012To: <sip:676216531@10.10.9.90>;tag=gf2cfd0d-CC-50#015#012Call-ID: 4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060#015#012CSeq: 102 INVITE#015#012Record-Route: <sip:194.30.0.111;lr;ftag=as08d1711f;nat=yes>#015#012Record-Route: <sip:10.10.9.90;lr;ftag=as08d1711f;did=838.4cb>#015#012Contact: <sip:676216531@212.106.213.5:5060;user=phone;transport=udp>#015#012Content-Length: 166#015#012Content-Type: application/sdp#015#012#015#012v=0#015#012o=HuaweiSoftX3000 162413883 162413884 IN IP4 212.106.213.9#015#012s=Sip Call#015#012c=IN IP4 194.30.0.111#015#012t=0 0#015#012m=audio 52336 RTP/AVP 8#015#012a=rtpmap:8 PCMA/8000#015#012a=ptime:20#015#012 | |
DEBUG: tm [t_reply.c:306]: update_totag_set(): DEBUG: update_totag_set: totag retransmission | |
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: <200> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <OK> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK0081fad4>; 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>: <102> <INVITE> | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=166 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_cseq.c:83]: dlg_cseq_prepare_msg(): no second via in this message | |
DEBUG: <core> [receive.c:341]: receive_msg(): reply-route executed in: 548 usec | |
DEBUG: dialog [dlg_var.c:87]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483652 | |
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 | |
DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request: | |
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg(): method: <ACK> | |
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> = <z9hG4bK55614172>; 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>: <102> <ACK> | |
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: <ACK> | |
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> = <z9hG4bK55614172>; 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: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:1261]: dlg_onroute(): Callid '4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060' not found | |
DEBUG: siputils [checks.c:123]: has_totag(): totag found | |
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_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 ACK with route param '838.4cb' [2104:3012] | |
DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=6 , global msg id=5 , T on entrance=0xffffffffffffffff | |
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <102> <ACK> | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=0 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=55048, isACK=1 | |
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_funcs.c:285]: t_relay_to(): SER: forwarding ACK statelessly | |
DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: 9eb6c7dd99c75155fd8ad5aae4df02d2 | |
DEBUG: <core> [msg_translator.c:159]: check_via_address(): (10.10.1.2, 10.10.1.2, 0) | |
DEBUG: <core> [forward.c:556]: forward_request(): Sending:#012ACK sip:676216531@212.106.213.5:5060;user=phone;transport=udp SIP/2.0#015#012Via: SIP/2.0/UDP 10.10.9.90;branch=z9hG4bK807d.9eb6c7dd99c75155fd8ad5aae4df02d2.0#015#012Via: SIP/2.0/UDP 10.10.1.2:5060;branch=z9hG4bK55614172#015#012Route: <sip:194.30.0.111;lr;ftag=as08d1711f;nat=yes>#015#012Max-Forwards: 70#015#012From: "CBT143502TC" <sip:CBT143502TC@10.10.1.2>;tag=as08d1711f#015#012To: <sip:676216531@10.10.9.90>;tag=gf2cfd0d-CC-50#015#012Contact: <sip:CBT143502TC@10.10.1.2:5060>#015#012Call-ID: 4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060#015#012CSeq: 102 ACK#015#012User-Agent: "Irontec IVOZ"#015#012Content-Length: 0#015#012#015#012. | |
DEBUG: <core> [forward.c:558]: forward_request(): orig. len=527, new_len=562, proto=1 | |
DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request: | |
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg(): method: <ACK> | |
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> = <z9hG4bK807d.9eb6c7dd99c75155fd8ad5aae4df02d2.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> = <z9hG4bK55614172>; 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>: <102> <ACK> | |
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: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: <core> [receive.c:268]: receive_msg(): request-route executed in: 871 usec | |
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 | |
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: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:1261]: dlg_onroute(): Callid '4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060' not found | |
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_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: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <103> <INVITE> | |
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: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=55049, isACK=0 | |
DEBUG: tm [t_lookup.c:409]: matching_3261(): DEBUG: RFC3261 transaction matched, tid=71b248a2 | |
DEBUG: tm [t_lookup.c:665]: t_lookup_request(): DEBUG: t_lookup_request: transaction found (T=0x7f5d7bc5bfd0) | |
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:1572]: t_retransmit_reply(): DEBUG: reply retransmitted. buf=0x7f5d81a21ce0: SIP/2.0 1..., shmem=0x7f5d7bc5a480: SIP/2.0 1 | |
DEBUG: <core> [receive.c:268]: receive_msg(): request-route executed in: 690 usec | |
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 | |
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: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: <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> = <z9hG4bK907d.5c4312e72360dc514bdcbac581b3d45d.0>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; 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:293]: dlg_cseq_msg_received(): no matching the starting point length | |
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> = <z9hG4bK907d.5c4312e72360dc514bdcbac581b3d45d.0>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; 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: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=6 global id=5 T start=0xffffffffffffffff | |
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=62 | |
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: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 55049 label 0 branch 0 | |
DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f5d7bc5bfd0)! | |
DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f5d7bc5bfd0, callback type 2, id 0 entered | |
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3012 found on entry 2104 | |
DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=6 global id=6 T end=0x7f5d7bc5bfd0 | |
DEBUG: tm [t_reply.c:2182]: reply_received(): DEBUG: reply_received: org. status uas=100, uac[0]=0 local=0 is_invite=1) | |
DEBUG: tm [t_reply.c:1252]: t_should_relay_response(): ->>>>>>>>> T_code=100, new_code=100 | |
DEBUG: tm [t_reply.c:1771]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=-1 icode=0 | |
DEBUG: <core> [receive.c:341]: receive_msg(): reply-route executed in: 333 usec | |
DEBUG: dialog [dlg_var.c:87]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483652 | |
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 | |
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: <200> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <OK> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK807d.26c36e3ab886fcb7bf8a6b932ae293a5.1.cs102>; 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> = <z9hG4bK0081fad4>; 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:307]: dlg_cseq_msg_received(): via cseq cookie [.cs102] val [102] | |
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: <200> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <OK> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK807d.26c36e3ab886fcb7bf8a6b932ae293a5.1>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 253, <z> = <102>; 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: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=6 global id=5 T start=(nil) | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK0081fad4>; 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=62 | |
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>: <102> <INVITE> | |
DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 55048 label 0 branch 1 | |
DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f5d7bc5a920)! | |
DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f5d7bc5a920, callback type 2, id 0 entered | |
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3012 found on entry 2104 | |
DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=6 global id=6 T end=0x7f5d7bc5a920 | |
DEBUG: tm [t_reply.c:2182]: reply_received(): DEBUG: reply_received: org. status uas=200, uac[1]=200 local=0 is_invite=1) | |
DEBUG: tm [t_reply.c:1252]: t_should_relay_response(): ->>>>>>>>> T_code=200, new_code=200 | |
DEBUG: tm [t_reply.c:1257]: t_should_relay_response(): DBG: t_should_relay_response: 200 INV after final sent | |
DEBUG: tm [t_reply.c:1771]: relay_reply(): DEBUG: relay_reply: branch=1, save=0, relay=1 icode=0 | |
DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f5d7bc5a920, callback type 32, id 0 entered | |
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3012 found on entry 2104 | |
DEBUG: <core> [msg_translator.c:2261]: generate_res_buf_from_sip_res(): old size: 793, new size: 673 | |
DEBUG: <core> [msg_translator.c:2279]: generate_res_buf_from_sip_res(): copied size: orig:136, new: 16, rest: 657 msg=#012SIP/2.0 200 OK#015#012Via: SIP/2.0/UDP 10.10.1.2:5060;branch=z9hG4bK0081fad4#015#012From: "CBT143502TC" <sip:20195@sarevoz.com>;tag=as08d1711f#015#012To: <sip:676216531@10.10.9.90>;tag=gf2cfd0d-CC-50#015#012Call-ID: 4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060#015#012CSeq: 102 INVITE#015#012Record-Route: <sip:194.30.0.111;lr;ftag=as08d1711f;nat=yes>#015#012Record-Route: <sip:10.10.9.90;lr;ftag=as08d1711f;did=838.4cb>#015#012Contact: <sip:676216531@212.106.213.5:5060;user=phone;transport=udp>#015#012Content-Length: 166#015#012Content-Type: application/sdp#015#012#015#012v=0#015#012o=HuaweiSoftX3000 162413883 162413884 IN IP4 212.106.213.9#015#012s=Sip Call#015#012c=IN IP4 194.30.0.111#015#012t=0 0#015#012m=audio 52336 RTP/AVP 8#015#012a=rtpmap:8 PCMA/8000#015#012a=ptime:20#015#012 | |
DEBUG: tm [t_reply.c:306]: update_totag_set(): DEBUG: update_totag_set: totag retransmission | |
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: <200> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <OK> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK0081fad4>; 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>: <102> <INVITE> | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=166 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_cseq.c:83]: dlg_cseq_prepare_msg(): no second via in this message | |
DEBUG: <core> [receive.c:341]: receive_msg(): reply-route executed in: 635 usec | |
DEBUG: dialog [dlg_var.c:87]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483652 | |
DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) | |
DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request: | |
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg(): method: <ACK> | |
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> = <z9hG4bK05fcc322>; 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>: <102> <ACK> | |
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: <ACK> | |
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> = <z9hG4bK05fcc322>; 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: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:1261]: dlg_onroute(): Callid '4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060' not found | |
DEBUG: siputils [checks.c:123]: has_totag(): totag found | |
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 | |
drop messages from pid 21361 due to rate-limiting | |
DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) | |
DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) | |
drop messages from pid 21364 due to rate-limiting | |
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: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:1261]: dlg_onroute(): Callid '4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060' not found | |
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_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=6 , global msg id=5 , T on entrance=0xffffffffffffffff | |
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <103> <INVITE> | |
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: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=55049, isACK=0 | |
DEBUG: tm [t_lookup.c:409]: matching_3261(): DEBUG: RFC3261 transaction matched, tid=71b248a2 | |
DEBUG: tm [t_lookup.c:665]: t_lookup_request(): DEBUG: t_lookup_request: transaction found (T=0x7f5d7bc5bfd0) | |
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:1572]: t_retransmit_reply(): DEBUG: reply retransmitted. buf=0x7f5d81a21ce0: SIP/2.0 1..., shmem=0x7f5d7bc5a480: SIP/2.0 1 | |
DEBUG: <core> [receive.c:268]: receive_msg(): request-route executed in: 1108 usec | |
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 | |
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: <200> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <OK> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
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:293]: dlg_cseq_msg_received(): no matching the starting point length | |
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: <200> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <OK> | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <62.99.78.6>; state=6 | |
DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6 | |
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> [receive.c:184]: receive_msg(): After parse_msg... | |
DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=6 global id=5 T start=0xffffffffffffffff | |
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=62 | |
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: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 55049 label 0 branch 0 | |
DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f5d7bc5bfd0)! | |
DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f5d7bc5bfd0, callback type 2, id 0 entered | |
DEBUG: dialog [dlg_hash.c:701]: dlg_lookup(): no dialog id=3012 found on entry 2104 | |
DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=6 global id=6 T end=0x7f5d7bc5bfd0 | |
DEBUG: tm [t_reply.c:2182]: reply_received(): DEBUG: reply_received: org. status uas=100, uac[0]=100 local=0 is_invite=1) | |
DEBUG: tm [t_reply.c:1252]: t_should_relay_response(): ->>>>>>>>> T_code=100, new_code=200 | |
DEBUG: tm [t_reply.c:1771]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0 | |
DEBUG: <core> [msg_translator.c:2261]: generate_res_buf_from_sip_res(): old size: 721, new size: 607 | |
DEBUG: <core> [msg_translator.c:2279]: generate_res_buf_from_sip_res(): copied size: orig:130, new: 16, rest: 591 msg=#012SIP/2.0 200 OK#015#012Via: SIP/2.0/UDP 10.10.1.2:5060;branch=z9hG4bK71b248a2#015#012From: "CBT143502TC" <sip:CBT143502TC@10.10.1.2>;tag=as08d1711f#015#012To: <sip:676216531@10.10.9.90>;tag=gf2cfd0d-CC-50#015#012Call-ID: 4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060#015#012CSeq: 103 INVITE#015#012Record-Route: <sip:194.30.0.111;lr;ftag=as08d1711f>#015#012Contact: <sip:676216531@212.106.213.5:5060;user=phone;transport=udp>#015#012Content-Length: 167#015#012Content-Type: application/sdp#015#012#015#012v=0#015#012o=HuaweiSoftX3000 162413883 162413885 IN IP4 212.106.213.9#015#012s=Sip Call#015#012c=IN IP4 212.106.213.9#015#012t=0 0#015#012m=audio 29098 RTP/AVP 8#015#012a=rtpmap:8 PCMA/8000#015#012a=ptime:20#015#012 | |
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: <200> | |
DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): reason: <OK> | |
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/msg_parser.c:183]: get_hdr_field(): content_length=167 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: dialog [dlg_cseq.c:83]: dlg_cseq_prepare_msg(): no second via in this message | |
DEBUG: tm [t_reply.c:1612]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset | |
DEBUG: <core> [receive.c:341]: receive_msg(): reply-route executed in: 571 usec | |
DEBUG: dialog [dlg_var.c:87]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483652 | |
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 | |
DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request: | |
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg(): method: <ACK> | |
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> = <z9hG4bK07416476>; 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> <ACK> | |
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: <ACK> | |
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> = <z9hG4bK07416476>; 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: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:1261]: dlg_onroute(): Callid '4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060' not found | |
DEBUG: siputils [checks.c:123]: has_totag(): totag found | |
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_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 ACK with route param '838.4cb' [2104:3012] | |
DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=6 , global msg id=5 , T on entrance=0xffffffffffffffff | |
DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <103> <ACK> | |
DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=0 | |
DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header | |
DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=55049, isACK=1 | |
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_funcs.c:285]: t_relay_to(): SER: forwarding ACK statelessly | |
DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: a6373ccc538f9f6d7198f976a6466515 | |
DEBUG: <core> [msg_translator.c:159]: check_via_address(): (10.10.1.2, 10.10.1.2, 0) | |
DEBUG: <core> [forward.c:556]: forward_request(): Sending:#012ACK sip:676216531@212.106.213.5:5060;user=phone;transport=udp SIP/2.0#015#012Via: SIP/2.0/UDP 10.10.9.90;branch=z9hG4bK907d.a6373ccc538f9f6d7198f976a6466515.0#015#012Via: SIP/2.0/UDP 10.10.1.2:5060;branch=z9hG4bK07416476#015#012Route: <sip:194.30.0.111;lr;ftag=as08d1711f;nat=yes>#015#012Max-Forwards: 70#015#012From: "CBT143502TC" <sip:CBT143502TC@10.10.1.2>;tag=as08d1711f#015#012To: <sip:676216531@10.10.9.90>;tag=gf2cfd0d-CC-50#015#012Contact: <sip:CBT143502TC@10.10.1.2:5060>#015#012Call-ID: 4fc77ef42cb0667d5c933f2a2a2a4a9c@10.10.1.2:5060#015#012CSeq: 103 ACK#015#012User-Agent: "Irontec IVOZ"#015#012Content-Length: 0#015#012#015#012. | |
DEBUG: <core> [forward.c:558]: forward_request(): orig. len=527, new_len=562, proto=1 | |
DEBUG: <core> [parser/msg_parser.c:600]: parse_msg(): SIP Request: | |
DEBUG: <core> [parser/msg_parser.c:602]: parse_msg(): method: <ACK> | |
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.a6373ccc538f9f6d7198f976a6466515.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> = <z9hG4bK07416476>; 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> <ACK> | |
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: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: <core> [receive.c:268]: receive_msg(): request-route executed in: 2169 usec | |
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 |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment