[Users] corrupted From with uac_replace_from in case of re-invite

tele tele at plexia.com
Tue Apr 3 10:41:10 CEST 2007


Hi all,

I'm occured in this problem already seen here:
http://www.openser.org/pipermail/users/2006-March/003540.html

My problem occured between two different UA and only in case of a
Re-INVITE for a T.38 fax communication.

Im' using openser 1.2.0.
I'm doing some translation with From for a correct CLI screening.
I'm also checked that the RR parameters are correctly mirrored.

find attached here the debug.log and a network trace.

thank you

:tele


-------------- next part --------------
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: SIP Request:
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]:  method:  <OPTIONS>
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]:  uri:     <sip:82.215.163.100:5060>
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]:  version: <SIP/2.0>
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=2
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: Found param type 235, <rport> = <n/a>; state=6
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: Found param type 232, <branch> = <z9hG4bKc0a8013d0000001046120a5600002e8d00000121>; state=16
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: end of header reached, state=5
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: Via found, flags=2
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: this is the first via
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: After parse_msg...
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: preparing to run routing scripts...
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=100
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: get_hdr_body : content_length=0
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: get_hdr_field: cseq <CSeq>: <143> <OPTIONS>
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:maxfwd:is_maxfwd_present: value = 70 
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=8
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:parse_to:end of header reached, state=10
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: DBUG:parse_to: display={}, ruri={sip:82.215.163.100:5060}
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: get_hdr_field: <To> [27]; uri=[sip:82.215.163.100:5060] 
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: to body [<sip:82.215.163.100:5060>^M ]
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=ffffffffffffffff
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: found end of header
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: check_via_address(82.215.163.186, 82.215.163.186, 0)
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:destroy_avp_list: destroying list 0x0
Apr  3 10:03:34 plx-c5-pbx /usr/local/sbin/openser[25355]: receive_msg: cleaning up
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: SIP Request:
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]:  method:  <REGISTER>
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]:  uri:     <sip:82.215.163.100>
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]:  version: <SIP/2.0>
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=2
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: Found param type 232, <branch> = <z9hG4bK-3670682326>; state=16
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: end of header reached, state=5
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: Via found, flags=2
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: this is the first via
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: After parse_msg...
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: preparing to run routing scripts...
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=100
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:parse_to:end of header reached, state=10
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: DBUG:parse_to: display={396006660093}, ruri={sip:396006660093 at 82.215.163.100}
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: get_hdr_field: <To> [48]; uri=[sip:396006660093 at 82.215.163.100] 
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: to body [396006660093 <sip:396006660093 at 82.215.163.100>^M ]
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: get_hdr_field: cseq <CSeq>: <3021> <REGISTER>
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:maxfwd:is_maxfwd_present: value = 70 
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=4000
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: get_hdr_body : content_length=0
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: found end of header
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: pre_auth(): Credentials with given realm not found
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: build_auth_hf(): 'WWW-Authenticate: Digest realm="plx-c5-pbx.plexia.com", nonce="46120b8442b7777ccbdfbadb16bd0ff3617a80d5"^M '
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=ffffffffffffffff
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: check_via_address(82.215.163.186, 82.215.163.186, 0)
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:destroy_avp_list: destroying list 0x0
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: receive_msg: cleaning up
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: SIP Request:
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]:  method:  <REGISTER>
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]:  uri:     <sip:82.215.163.100>
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]:  version: <SIP/2.0>
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=2
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: Found param type 232, <branch> = <z9hG4bK-3670682326>; state=16
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: end of header reached, state=5
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: Via found, flags=2
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: this is the first via
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: After parse_msg...
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: preparing to run routing scripts...
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=100
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:parse_to:end of header reached, state=10
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: DBUG:parse_to: display={396006660093}, ruri={sip:396006660093 at 82.215.163.100}
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: get_hdr_field: <To> [48]; uri=[sip:396006660093 at 82.215.163.100] 
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: to body [396006660093 <sip:396006660093 at 82.215.163.100>^M ]
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: get_hdr_field: cseq <CSeq>: <3022> <REGISTER>
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:maxfwd:is_maxfwd_present: value = 70 
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: check_nonce(): comparing [46120b8442b7777ccbdfbadb16bd0ff3617a80d5] and [46120b8442b7777ccbdfbadb16bd0ff3617a80d5]
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:auth_radius:radius_authorize_sterman: Success
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:auth_radius:generate_avps: getting SIP AVPs from avpair 225
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=ffffffffffffffff
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: get_hdr_body : content_length=0
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: found end of header
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=8000000
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=ffffffffffffffff
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: build_contact(): Created Contact HF: Contact: <sip:396006660093 at 82.215.163.186:1076>;expires=60^M 
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=ffffffffffffffff
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: check_via_address(82.215.163.186, 82.215.163.186, 0)
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:destroy_avp_list: destroying list 0x0
Apr  3 10:03:36 plx-c5-pbx /usr/local/sbin/openser[25355]: receive_msg: cleaning up
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: SIP Request:
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]:  method:  <INVITE>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]:  uri:     <sip:0104491093 at plx-c5-pbx.plexia.com;transport=UDP>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]:  version: <SIP/2.0>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: parse_headers: flags=2
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG:parse_to:end of header reached, state=10
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: DBUG:parse_to: display={"0104491093"}, ruri={sip:0104491093 at plx-c5-pbx.plexia.com:5060}
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG: get_hdr_field: <To> [57]; uri=[sip:0104491093 at plx-c5-pbx.plexia.com:5060] 
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG: to body ["0104491093"<sip:0104491093 at plx-c5-pbx.plexia.com:5060>^M ]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: get_hdr_field: cseq <CSeq>: <1> <INVITE>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: Found param type 235, <rport> = <n/a>; state=6
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: Found param type 232, <branch> = <z9hG4bK-f1bc-3b04a16-7d60b58b>; state=16
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: end of header reached, state=5
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: parse_headers: Via found, flags=2
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: parse_headers: this is the first via
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: After parse_msg...
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: preparing to run routing scripts...
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: parse_headers: flags=100
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG:maxfwd:is_maxfwd_present: value = 70 
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: grep_sock_info - checking if host==us: 21==14 &&  [plx-c5-pbx.plexia.com] == [82.215.163.100]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG: add_param: tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG:parse_to:end of header reached, state=29
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: DBUG:parse_to: display={"396006660084"}, ruri={sip:396006660084 at plx-c5-pbx.plexia.com:5060}
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: parse_headers: flags=200
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG: get_hdr_body : content_length=179
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: found end of header
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: find_first_route: No Route headers found
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: loose_route: There is no Route HF
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG: has_totag: no totag
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG: has_totag: no totag
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG:avpops:pushto_avps: 1 avps were processed
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG:avpops:delete_avps: 1 avps were removed
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG: has_totag: no totag
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: parse_headers: flags=10000
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: pre_auth(): Credentials with given realm not found
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: build_auth_hf(): 'Proxy-Authenticate: Digest realm="plx-c5-pbx.plexia.com", nonce="46120b87e8fdf68e47bb2b7be4727f00eff80c6f"^M '
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: parse_headers: flags=ffffffffffffffff
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: check_via_address(82.215.163.186, 82.215.163.186, 0)
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG:destroy_avp_list: destroying list 0x288d4900
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25348]: receive_msg: cleaning up
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: SIP Request:
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]:  method:  <ACK>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]:  uri:     <sip:0104491093 at plx-c5-pbx.plexia.com;transport=UDP>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]:  version: <SIP/2.0>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: parse_headers: flags=2
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: DEBUG: add_param: tag=dd62ffe5313c9a530c48a37ac354e814.7362
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: DEBUG:parse_to:end of header reached, state=29
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: DBUG:parse_to: display={"0104491093"}, ruri={sip:0104491093 at plx-c5-pbx.plexia.com:5060}
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: DEBUG: get_hdr_field: <To> [99]; uri=[sip:0104491093 at plx-c5-pbx.plexia.com:5060] 
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: DEBUG: to body ["0104491093"<sip:0104491093 at plx-c5-pbx.plexia.com:5060>]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: get_hdr_field: cseq <CSeq>: <1> <ACK>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: Found param type 235, <rport> = <n/a>; state=6
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: Found param type 232, <branch> = <z9hG4bK-f1bc-3b04a16-7d60b58b>; state=16
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: end of header reached, state=5
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: parse_headers: Via found, flags=2
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: parse_headers: this is the first via
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: After parse_msg...
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: preparing to run routing scripts...
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: parse_headers: flags=8
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: DEBUG: sl_filter_ACK : local ACK found -> dropping it!
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: DEBUG:destroy_avp_list: destroying list 0x0
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25353]: receive_msg: cleaning up
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: SIP Request:
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]:  method:  <INVITE>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]:  uri:     <sip:0104491093 at plx-c5-pbx.plexia.com;transport=UDP>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]:  version: <SIP/2.0>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: parse_headers: flags=2
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:parse_to:end of header reached, state=10
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DBUG:parse_to: display={"0104491093"}, ruri={sip:0104491093 at plx-c5-pbx.plexia.com:5060}
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG: get_hdr_field: <To> [57]; uri=[sip:0104491093 at plx-c5-pbx.plexia.com:5060] 
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG: to body ["0104491093"<sip:0104491093 at plx-c5-pbx.plexia.com:5060>^M ]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: get_hdr_field: cseq <CSeq>: <2> <INVITE>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: Found param type 235, <rport> = <n/a>; state=6
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: Found param type 232, <branch> = <z9hG4bK-f1bd-3b04a70-63526190>; state=16
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: end of header reached, state=5
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: parse_headers: Via found, flags=2
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: parse_headers: this is the first via
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: After parse_msg...
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: preparing to run routing scripts...
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: parse_headers: flags=100
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:maxfwd:is_maxfwd_present: value = 70 
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: grep_sock_info - checking if host==us: 21==14 &&  [plx-c5-pbx.plexia.com] == [82.215.163.100]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG: add_param: tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:parse_to:end of header reached, state=29
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DBUG:parse_to: display={"396006660084"}, ruri={sip:396006660084 at plx-c5-pbx.plexia.com:5060}
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: parse_headers: flags=200
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG: get_hdr_body : content_length=179
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: found end of header
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: find_first_route: No Route headers found
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: loose_route: There is no Route HF
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG: has_totag: no totag
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG: has_totag: no totag
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:avpops:pushto_avps: 1 avps were processed
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:avpops:delete_avps: 1 avps were removed
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG: has_totag: no totag
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: check_nonce(): comparing [46120b87e8fdf68e47bb2b7be4727f00eff80c6f] and [46120b87e8fdf68e47bb2b7be4727f00eff80c6f]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:auth_radius:radius_authorize_sterman: Success
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:auth_radius:generate_avps: getting SIP AVPs from avpair 225
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: [PLX-OBR] INVITE Request-uri(sip:390104491093 at plx-c5-pbx.plexia.com;transport=UDP) To uri: sip:0104491093 at plx-c5-pbx.plexia.com:5060 destination uri 
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: avpops:ops_dbquery_avps: query [SELECT lid,remote_party_id,maxi FROM rpid WHERE remote_party_id = '390104491093']
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: avpops:db_query_avp: rows [1]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: avpops:db_query_avp: row [0]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: close avp query
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: [PLX-OBR] 4 Found CLI390104491093 associated to LID[396006660093]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:avpops:pushto_avps: 1 avps were processed
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: [PLX-OBR] check if maxi == 1
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:avpops:check_avp: check <0> against <1> as int /33554433
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:avpops:check_avp: no match
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:avpops:delete_avps: 1 avps were removed
Apr  3 10:03:39 plx-c5-pbx last message repeated 2 times
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: [PLX-OBR] Lookup location to be sure the is for our UA
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: rewrite_uri: Rewriting Request-URI with 'sip:396006660093 at 82.215.163.186:1076'
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: [PLX-OBR] Call is for our UA check the DIDflag
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: [PLX-OBR] Fix the screening CLI with the correct number
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: avpops:ops_dbquery_avps: query [SELECT lid,remote_party_id,maxi FROM rpid WHERE lid = '396006660084']
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: avpops:db_query_avp: rows [1]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: avpops:db_query_avp: row [0]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: close avp query
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: [PLX-OBR] 5 Found CLI[390104491084] associated to LID[396006660084]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: subst_run: running. r=0
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: subst_run: matched (0, 12): [390104491084]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: avpops:ops_subst: subst to 1 avps
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: [PLX-OBR] 5 Replace from with uac_replace_from[sip:0104491084 at 82.215.163.100]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: [PLX-OBR] uac_replace_from()
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:uac:replace_from: removing display ["396006660084"]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:uac:replace_from: uri to replace [sip:396006660084 at plx-c5-pbx.plexia.com:5060]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:uac:replace_from: replacement uri is [sip:0104491084 at 82.215.163.100]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:uac:replace_from: encode is=<AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA--> len=60
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:avpops:delete_avps: 1 avps were removed
Apr  3 10:03:39 plx-c5-pbx last message repeated 3 times
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: [PLX-OBR] Relay On-NET call to the UA
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG: t_newtran:  T on entrance=0xffffffff
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: parse_headers: flags=ffffffffffffffff
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: parse_headers: flags=78
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: t_lookup_request: start searching: hash=5919, isACK=0
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG: RFC3261 transaction matching failed
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG: t_lookup_request: no transaction found
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DBG: trans=0x288d4a30, callback type 1, id 1 entered
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: parse_headers: flags=78
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG: noisy_timer set for accounting
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:rr:is_direction: param ftag not found
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DBG: trans=0x288d4a30, callback type 1, id 0 entered
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: parse_headers: flags=ffffffffffffffff
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: check_via_address(82.215.163.186, 82.215.163.186, 0)
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: WARNING:vqm_resize: resize(0) called
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:tm:_reply_light: reply sent out. buf=0x8167fb8: SIP/2.0 1..., shmem=0x288d69a0: SIP/2.0 1
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:tm:_reply_light: finished
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG: mk_proxy: doing DNS lookup...
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: check_via_address(82.215.163.186, 82.215.163.186, 0)
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:tm:set_timer: relative timeout is 500000
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG: add_to_tail_of_timer[4]: 0x288d4b7c (24500000)
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:tm:set_timer: relative timeout is 30
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG: add_to_tail_of_timer[0]: 0x288d4b98 (54)
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:tm:t_relay_to: new transaction fwd'ed
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:tm:UNREF_UNSAFE: after is 0
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: DEBUG:destroy_avp_list: destroying list 0x0
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25351]: receive_msg: cleaning up
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: SIP Reply  (status):
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]:  version: <SIP/2.0>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]:  status:  <100>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]:  reason:  <Trying>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: parse_headers: flags=2
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: Found param type 232, <branch> = <z9hG4bKf171.07a21b96.0>; state=16
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: end of header reached, state=5
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: parse_headers: Via found, flags=2
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: parse_headers: this is the first via
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: After parse_msg...
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG:forward_reply: found module tm, passing reply to it
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG: t_check: start=0xffffffff
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: parse_headers: flags=22
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: Found param type 235, <rport> = <1079>; state=6
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: Found param type 232, <branch> = <z9hG4bK-f1bd-3b04a70-63526190>; state=16
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: end of header reached, state=5
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: parse_headers: Via found, flags=22
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: parse_headers: this is the second via
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG:parse_to:end of header reached, state=10
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DBUG:parse_to: display={"0104491093"}, ruri={sip:0104491093 at plx-c5-pbx.plexia.com:5060}
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG: get_hdr_field: <To> [58]; uri=[sip:0104491093 at plx-c5-pbx.plexia.com:5060] 
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG: to body ["0104491093" <sip:0104491093 at plx-c5-pbx.plexia.com:5060>^M ]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: get_hdr_field: cseq <CSeq>: <2> <INVITE>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: parse_headers: flags=8
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG: t_reply_matching: hash 5919 label 1773218416 branch 0
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG:tm:REF_UNSAFE: after is 1
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG: t_reply_matching: reply matched (T=0x288d4a30)!
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DBG: trans=0x288d4a30, callback type 2, id 1 entered
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DBG: trans=0x288d4a30, callback type 2, id 0 entered
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG: add_param: tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG:parse_to:end of header reached, state=29
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DBUG:parse_to: display={"0104491084"}, ruri={sip:0104491084 at 82.215.163.100}
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DBG:uac::restore_from_reply: removing <From: "0104491084" <sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc^M >
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DBG:uac::restore_from_reply: inserting <From: "396006660084"<sip:396006660084 at plx-c5-pbx.plexia.com:5060>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc^M >
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG: t_check: end=0x288d4a30
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG:tm:reply_received: org. status uas=100, uac[0]=0 local=0 is_invite=1)
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG:tm:t_should_relay_response: T_code=100, new_code=100
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG:tm:relay_reply: branch=0, save=0, relay=-1
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG:tm:set_timer: relative timeout is 120
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG: add_to_tail_of_timer[1]: 0x288d4b98 (144)
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG:tm:UNREF_UNSAFE: after is 0
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: DEBUG:destroy_avp_list: destroying list 0x0
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25349]: receive_msg: cleaning up
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: SIP Reply  (status):
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]:  version: <SIP/2.0>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]:  status:  <180>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]:  reason:  <Ringing>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: parse_headers: flags=2
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: Found param type 232, <branch> = <z9hG4bKf171.07a21b96.0>; state=16
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: end of header reached, state=5
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: parse_headers: Via found, flags=2
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: parse_headers: this is the first via
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: After parse_msg...
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG:forward_reply: found module tm, passing reply to it
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG: t_check: start=0xffffffff
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: parse_headers: flags=22
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: Found param type 235, <rport> = <1079>; state=6
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: Found param type 232, <branch> = <z9hG4bK-f1bd-3b04a70-63526190>; state=16
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: end of header reached, state=5
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: parse_headers: Via found, flags=22
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: parse_headers: this is the second via
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG: add_param: tag=2630444615
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG:parse_to:end of header reached, state=29
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DBUG:parse_to: display={"0104491093"}, ruri={sip:0104491093 at plx-c5-pbx.plexia.com:5060}
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG: get_hdr_field: <To> [73]; uri=[sip:0104491093 at plx-c5-pbx.plexia.com:5060] 
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG: to body ["0104491093" <sip:0104491093 at plx-c5-pbx.plexia.com:5060>]
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: get_hdr_field: cseq <CSeq>: <2> <INVITE>
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: parse_headers: flags=8
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG: t_reply_matching: hash 5919 label 1773218416 branch 0
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG:tm:REF_UNSAFE: after is 1
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG: t_reply_matching: reply matched (T=0x288d4a30)!
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DBG: trans=0x288d4a30, callback type 2, id 1 entered
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DBG: trans=0x288d4a30, callback type 2, id 0 entered
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG: add_param: tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG:parse_to:end of header reached, state=29
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DBUG:parse_to: display={"0104491084"}, ruri={sip:0104491084 at 82.215.163.100}
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DBG:uac::restore_from_reply: removing <From: "0104491084" <sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc^M >
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DBG:uac::restore_from_reply: inserting <From: "396006660084"<sip:396006660084 at plx-c5-pbx.plexia.com:5060>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc^M >
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG: t_check: end=0x288d4a30
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG:tm:reply_received: org. status uas=100, uac[0]=100 local=0 is_invite=1)
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG:tm:t_should_relay_response: T_code=100, new_code=180
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG:tm:relay_reply: branch=0, save=0, relay=0
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]:  old size: 606, new size: 558
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: build_res_from_sip_res: copied size: orig:369, new: 321, rest: 237 msg= SIP/2.0 180 Ringing^M Via: SIP/2.0/UDP 82.215.163.186:1057;rport=1079;branch=z9hG4bK-f1bd-3b04a70-63526190^M Record-Route: <sip:82.215.163.100;vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA-->^M From: "396006660084"<sip:396006660084 at plx-c5-pbx.plexia.com:5060>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc^M To: "0104491093" <sip:0104491093 at plx-c5-pbx.plexia.com:5060>;tag=2630444615^M Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com^M CSeq: 2 INVITE^M Contact: <sip:396006660093 at 82.215.163.186:1076>^M Content-Length: 0^M ^M 
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG:tm:relay_reply: sent buf=0x8167a30: SIP/2.0 1..., shmem=0x288d7068: SIP/2.0 1
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DBG: trans=0x288d4a30, callback type 128, id 1 entered
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG:tm:set_timer: relative timeout is 120
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG: add_to_tail_of_timer[1]: 0x288d4b98 (144)
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG:tm:UNREF_UNSAFE: after is 0
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: DEBUG:destroy_avp_list: destroying list 0x0
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25352]: receive_msg: cleaning up
Apr  3 10:03:39 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: timer routine:4,tl=0x288d4b7c next=0x0, timeout=24500000
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: SIP Reply  (status):
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]:  version: <SIP/2.0>
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]:  status:  <200>
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]:  reason:  <OK>
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: parse_headers: flags=2
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: Found param type 232, <branch> = <z9hG4bKf171.07a21b96.0>; state=16
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: end of header reached, state=5
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: parse_headers: Via found, flags=2
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: parse_headers: this is the first via
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: After parse_msg...
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG:forward_reply: found module tm, passing reply to it
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG: t_check: start=0xffffffff
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: parse_headers: flags=22
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: Found param type 235, <rport> = <1079>; state=6
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: Found param type 232, <branch> = <z9hG4bK-f1bd-3b04a70-63526190>; state=16
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: end of header reached, state=5
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: parse_headers: Via found, flags=22
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: parse_headers: this is the second via
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG: add_param: tag=2630444615
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG:parse_to:end of header reached, state=29
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DBUG:parse_to: display={"0104491093"}, ruri={sip:0104491093 at plx-c5-pbx.plexia.com:5060}
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG: get_hdr_field: <To> [73]; uri=[sip:0104491093 at plx-c5-pbx.plexia.com:5060] 
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG: to body ["0104491093" <sip:0104491093 at plx-c5-pbx.plexia.com:5060>]
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: get_hdr_field: cseq <CSeq>: <2> <INVITE>
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: parse_headers: flags=8
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG: t_reply_matching: hash 5919 label 1773218416 branch 0
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG:tm:REF_UNSAFE: after is 1
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG: t_reply_matching: reply matched (T=0x288d4a30)!
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: parse_headers: flags=8
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DBG: trans=0x288d4a30, callback type 2, id 1 entered
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: parse_headers: flags=8
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DBG: trans=0x288d4a30, callback type 2, id 0 entered
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG: add_param: tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG:parse_to:end of header reached, state=29
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DBUG:parse_to: display={"0104491084"}, ruri={sip:0104491084 at 82.215.163.100}
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DBG:uac::restore_from_reply: removing <From: "0104491084" <sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc^M >
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DBG:uac::restore_from_reply: inserting <From: "396006660084"<sip:396006660084 at plx-c5-pbx.plexia.com:5060>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc^M >
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG: t_check: end=0x288d4a30
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG:tm:reply_received: org. status uas=180, uac[0]=180 local=0 is_invite=1)
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG:tm:t_should_relay_response: T_code=180, new_code=200
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG:tm:relay_reply: branch=0, save=0, relay=0
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]:  old size: 917, new size: 869
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: build_res_from_sip_res: copied size: orig:364, new: 316, rest: 553 msg= SIP/2.0 200 OK^M Via: SIP/2.0/UDP 82.215.163.186:1057;rport=1079;branch=z9hG4bK-f1bd-3b04a70-63526190^M Record-Route: <sip:82.215.163.100;vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA-->^M From: "396006660084"<sip:396006660084 at plx-c5-pbx.plexia.com:5060>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc^M To: "0104491093" <sip:0104491093 at plx-c5-pbx.plexia.com:5060>;tag=2630444615^M Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com^M CSeq: 2 INVITE^M Contact: <sip:396006660093 at 82.215.163.186:1076>^M Content-type: application/sdp^M Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, INFO, PRACK, REGISTER^M Content-Length: 216  ^M ^M v=0^M o=aethra 1 10 IN IP4 82.215.163.191^M s=-^M c=IN IP4 82.215.163.186^M t=0 0^M m=audio 5004 RTP/AVP 18 8 97^M a=rtpmap:18 G729/8000^M a=rtpmap:8 PCMA/8000^M a=rtpmap:97 telephone-event/8000^M a=fmtp:97 0-15^M a=ptime:20^M 
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG: update_totag_set: new totag 
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG: add_to_tail_of_timer[2]: 0x288d4a78 (34)
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG:tm:relay_reply: sent buf=0x8167a30: SIP/2.0 2..., shmem=0x288d7340: SIP/2.0 2
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DBG: trans=0x288d4a30, callback type 128, id 1 entered
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG: cleanup_uac_timers: RETR/FR timers reset
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG:tm:UNREF_UNSAFE: after is 0
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: DEBUG:destroy_avp_list: destroying list 0x0
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25347]: receive_msg: cleaning up
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: SIP Request:
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]:  method:  <ACK>
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]:  uri:     <sip:82.215.163.100;vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA-->
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]:  version: <SIP/2.0>
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: parse_headers: flags=2
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG: add_param: tag=2630444615
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:parse_to:end of header reached, state=29
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DBUG:parse_to: display={"0104491093"}, ruri={sip:0104491093 at plx-c5-pbx.plexia.com:5060}
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG: get_hdr_field: <To> [72]; uri=[sip:0104491093 at plx-c5-pbx.plexia.com:5060] 
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG: to body ["0104491093"<sip:0104491093 at plx-c5-pbx.plexia.com:5060>]
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: get_hdr_field: cseq <CSeq>: <2> <ACK>
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: Found param type 235, <rport> = <n/a>; state=6
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: Found param type 232, <branch> = <z9hG4bK-f1c2-3b05f6a-2af3d0d9>; state=16
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: end of header reached, state=5
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: parse_headers: Via found, flags=2
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: parse_headers: this is the first via
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: After parse_msg...
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: preparing to run routing scripts...
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG : sl_filter_ACK: to late to be a local ACK!
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: parse_headers: flags=100
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:maxfwd:is_maxfwd_present: value = 70 
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG: add_param: tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:parse_to:end of header reached, state=29
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DBUG:parse_to: display={"396006660084"}, ruri={sip:396006660084 at plx-c5-pbx.plexia.com:5060}
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: parse_headers: flags=200
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: is_preloaded: No
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: after_strict: Next hop: 'sip:396006660093 at 82.215.163.186:1063' is strict router
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: rewrite_uri: Rewriting Request-URI with 'sip:396006660093 at 82.215.163.186:1063'
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DBG:rr:run_rr_callbacks: callback id 1 entered with <vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA-->
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:uac:restore_from: getting 'vsf' Route param
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:uac:restore_from: Route param is 'AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA--' (len=60)
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:rr:is_direction: param ftag not found
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:uac:restore_from: decoded uris are: new=[sip:0104491084 at 82.215.163.100] old=[sip:396006660084 at plx-c5-pbx.plexia.com:5060]
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DBG:rr:run_rr_callbacks: callback id 0 entered with <vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA-->
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:dialog:dlg_onroute: Route param 'did' not found
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: parse_headers: flags=ffffffffffffffff
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG: get_hdr_body : content_length=0
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: found end of header
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: [PLX-OBR] ACK|BYE|CANCEL Request-uri(sip:396006660093 at 82.215.163.186:1063) To uri: sip:0104491093 at plx-c5-pbx.plexia.com:5060 destination uri 
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: avpops:ops_dbquery_avps: query [SELECT lid,remote_party_id,maxi FROM rpid WHERE remote_party_id = '390104491093']
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: avpops:db_query_avp: rows [1]
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: avpops:db_query_avp: row [0]
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: close avp query
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: [PLX-OBR] 4 Found CLI[390104491093] associated to LID[396006660093]
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:avpops:pushto_avps: 1 avps were processed
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: [PLX-OBR] check if maxi == 1
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:avpops:check_avp: check <0> against <1> as int /33554433
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:avpops:check_avp: no match
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:avpops:delete_avps: 1 avps were removed
Apr  3 10:03:44 plx-c5-pbx last message repeated 2 times
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: rewrite_uri: Rewriting Request-URI with 'sip:396006660093 at 82.215.163.186:1076'
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: avpops:ops_dbquery_avps: query [SELECT lid,remote_party_id,maxi FROM rpid WHERE lid = '396006660084']
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: avpops:db_query_avp: rows [1]
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: avpops:db_query_avp: row [0]
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: close avp query
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: [PLX-OBR] 5 Found CLI[390104491084] associated to LID[396006660084]
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: subst_run: running. r=0
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: subst_run: matched (0, 12): [390104491084]
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: avpops:ops_subst: subst to 1 avps
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: [PLX-OBR] 5 Replace from with uac_replace_from[sip:0104491084 at 82.215.163.100]
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: [PLX-OBR] uac_replace_from()
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:avpops:delete_avps: 1 avps were removed
Apr  3 10:03:44 plx-c5-pbx last message repeated 3 times
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG: t_newtran:  T on entrance=0xffffffff
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: parse_headers: flags=ffffffffffffffff
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: parse_headers: flags=78
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: t_lookup_request: start searching: hash=5919, isACK=1
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: parse_headers: flags=38
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:tm:REF_UNSAFE: after is 1
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG: t_lookup_request: e2e proxy ACK found
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:tm:t_newtran: building branch for end2end ACK
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: parse_headers: flags=8
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG: totag for e2e ACK found: 0
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DBG: trans=0x288d4a30, callback type 4, id 1 entered
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: parse_headers: flags=78
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:tm:t_relay: forwarding ACK
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG: mk_proxy: doing DNS lookup...
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: check_via_address(82.215.163.186, 82.215.163.186, 0)
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:forward_request: sending: ACK sip:396006660093 at 82.215.163.186:1076 SIP/2.0^M Record-Route: <sip:82.215.163.100;lr=on;ftag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc>^M From: "396006660084"<sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc^M To: "0104491093"<sip:0104491093 at plx-c5-pbx.plexia.com:5060>;tag=2630444615^M Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com^M CSeq: 2 ACK^M Via: SIP/2.0/UDP 82.215.163.100;branch=z9hG4bKf171.07a21b96.2^M Via: SIP/2.0/UDP 82.215.163.186:1079;rport=1079;branch=z9hG4bK-f1c2-3b05f6a-2af3d0d9^M Max-Forwards: 69^M User-Agent: SpeedTouch 780^M Accept: application/dtmf-relay, x-application/dtmf-relay, application/sdp^M Contact: <sip:396006660084 at 82.215.163.186:1079;transport=UDP>^M Proxy-Authorization: Digest username="396006660084",realm="plx-c5-pbx.plexia.com",nonce="46120b87e8fdf68e47bb2b7be4727f00eff80c6f",uri="sip:0104491093 at plx-c5-pbx.plexia.com;transport=UDP",respons
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:forward_request: orig. len=953, new_len=1023, proto=1
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:tm:UNREF_UNSAFE: after is 0
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: DEBUG:destroy_avp_list: destroying list 0x0
Apr  3 10:03:44 plx-c5-pbx /usr/local/sbin/openser[25356]: receive_msg: cleaning up
Apr  3 10:03:49 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: timer routine:2,tl=0x288d4a78 next=0x0, timeout=34
Apr  3 10:03:49 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: wait_handler : removing 0x288d4a30 from table 
Apr  3 10:03:49 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: delete transaction 0x288d4a30
Apr  3 10:03:49 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: wait_handler : done
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: SIP Request:
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]:  method:  <OPTIONS>
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]:  uri:     <sip:82.215.163.100:5060>
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]:  version: <SIP/2.0>
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: parse_headers: flags=2
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: Found param type 235, <rport> = <n/a>; state=6
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: Found param type 232, <branch> = <z9hG4bKc0a8013d0000001046120a6a0000295200000123>; state=16
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: end of header reached, state=5
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: parse_headers: Via found, flags=2
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: parse_headers: this is the first via
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: After parse_msg...
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: preparing to run routing scripts...
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: parse_headers: flags=100
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: get_hdr_body : content_length=0
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: get_hdr_field: cseq <CSeq>: <144> <OPTIONS>
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:maxfwd:is_maxfwd_present: value = 70 
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: parse_headers: flags=8
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:parse_to:end of header reached, state=10
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: DBUG:parse_to: display={}, ruri={sip:82.215.163.100:5060}
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: get_hdr_field: <To> [27]; uri=[sip:82.215.163.100:5060] 
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: to body [<sip:82.215.163.100:5060>^M ]
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: parse_headers: flags=ffffffffffffffff
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: found end of header
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: check_via_address(82.215.163.186, 82.215.163.186, 0)
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:destroy_avp_list: destroying list 0x0
Apr  3 10:03:54 plx-c5-pbx /usr/local/sbin/openser[25354]: receive_msg: cleaning up
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: SIP Request:
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]:  method:  <INVITE>
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]:  uri:     <sip:396006660084 at 82.215.163.186:1057;transport=UDP>
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]:  version: <SIP/2.0>
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: parse_headers: flags=2
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: Found param type 232, <branch> = <z9hG4bK-3983741888>; state=16
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: end of header reached, state=5
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: parse_headers: Via found, flags=2
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: parse_headers: this is the first via
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: After parse_msg...
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: preparing to run routing scripts...
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: parse_headers: flags=100
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: add_param: tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:parse_to:end of header reached, state=29
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DBUG:parse_to: display={"0104491084"}, ruri={sip:0104491084 at 82.215.163.100}
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: get_hdr_field: <To> [92]; uri=[sip:0104491084 at 82.215.163.100] 
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: to body ["0104491084" <sip:0104491084 at 82.215.163.100>]
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: get_hdr_field: cseq <CSeq>: <3> <INVITE>
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:maxfwd:is_maxfwd_present: value = 50 
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.186] == [82.215.163.100]
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: grep_sock_info - checking if port 5060 matches port 1057
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:check_self: host != me
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: add_param: tag=2630444615
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:parse_to:end of header reached, state=29
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DBUG:parse_to: display={"0104491093"}, ruri={sip:0104491093 at plx-c5-pbx.plexia.com:5060}
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: parse_headers: flags=200
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: is_preloaded: No
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.186] == [82.215.163.100]
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: grep_sock_info - checking if port 5060 matches port 1057
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:check_self: host != me
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: after_loose: Topmost route URI: 'sip:82.215.163.100;vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA--' is me
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: parse_headers: flags=200
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: get_hdr_body : content_length=276
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: found end of header
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: find_next_route: No next Route HF found
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: after_loose: No next URI found
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DBG:rr:run_rr_callbacks: callback id 1 entered with <vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA-->
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:uac:restore_from: getting 'vsf' Route param
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:uac:restore_from: Route param is 'AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA--' (len=60)
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:rr:is_direction: param ftag not found
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:uac:restore_from: decoded uris are: new=[sip:39600666178|^^&s*-`ty;b9r%^T^L^YG^BA^NUX ^C] old=[sip:0104491093 at plx-c5-pbx.plexia.com:5060]
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DBG:rr:run_rr_callbacks: callback id 0 entered with <vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA-->
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:dialog:dlg_onroute: Route param 'did' not found
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: parse_headers: flags=ffffffffffffffff
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: has_totag: totag found
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: has_totag: totag found
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: [PLX-OBR] Re-Invite from UA with R-UserName blank adjust ruri with To username
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:avpops:pushto_avps: 1 avps were processed
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:avpops:delete_avps: 1 avps were removed
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: has_totag: totag found
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: [PLX-OBR] This is Re-INVITE from our UA probably for a Fax
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: [PLX-OBR] Request-uri(sip:390104491084 at 82.215.163.186:1057;transport=UDP) To uri: sip:0104491084 at 82.215.163.100 destination uri 
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: [PLX-OBR] INVITE Request-uri(sip:390104491084 at 82.215.163.186:1057;transport=UDP) To uri: sip:0104491084 at 82.215.163.100 destination uri 
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: avpops:ops_dbquery_avps: query [SELECT lid,remote_party_id,maxi FROM rpid WHERE remote_party_id = '390104491084']
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: avpops:db_query_avp: rows [1]
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: avpops:db_query_avp: row [0]
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: close avp query
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: [PLX-OBR] 4 Found CLI390104491084 associated to LID[396006660084]
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:avpops:pushto_avps: 1 avps were processed
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: [PLX-OBR] check if maxi == 1
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:avpops:check_avp: check <0> against <1> as int /33554433
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:avpops:check_avp: no match
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:avpops:delete_avps: 1 avps were removed
Apr  3 10:03:55 plx-c5-pbx last message repeated 2 times
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: [PLX-OBR] Lookup location to be sure the is for our UA
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: rewrite_uri: Rewriting Request-URI with 'sip:396006660084 at 82.215.163.186:1079'
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: [PLX-OBR] Call is for our UA check the DIDflag
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: [PLX-OBR] Fix the screening CLI with the correct number
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: avpops:ops_dbquery_avps: query [SELECT lid,remote_party_id,maxi FROM rpid WHERE lid = '0104491093']
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: avpops:db_query_avp: no result after query
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: close avp query
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: [PLX-OBR] NOTHING FOUND
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:avpops:delete_avps: 0 avps were removed
Apr  3 10:03:55 plx-c5-pbx last message repeated 2 times
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: [PLX-OBR] Relay On-NET call to the UA
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: t_newtran:  T on entrance=0xffffffff
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: parse_headers: flags=ffffffffffffffff
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: parse_headers: flags=78
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: t_lookup_request: start searching: hash=5920, isACK=0
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: RFC3261 transaction matching failed
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: t_lookup_request: no transaction found
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DBG: trans=0x288d4a30, callback type 1, id 1 entered
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DBG: trans=0x288d4a30, callback type 1, id 0 entered
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: parse_headers: flags=ffffffffffffffff
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: check_via_address(82.215.163.186, 82.215.163.186, 0)
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: WARNING:vqm_resize: resize(0) called
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:tm:_reply_light: reply sent out. buf=0x8168008: SIP/2.0 1..., shmem=0x288d7068: SIP/2.0 1
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:tm:_reply_light: finished
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: mk_proxy: doing DNS lookup...
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: check_via_address(82.215.163.186, 82.215.163.186, 0)
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:tm:set_timer: relative timeout is 500000
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: add_to_tail_of_timer[4]: 0x288d4b7c (41000000)
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:tm:set_timer: relative timeout is 30
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG: add_to_tail_of_timer[0]: 0x288d4b98 (70)
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:tm:t_relay_to: new transaction fwd'ed
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:tm:UNREF_UNSAFE: after is 0
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: DEBUG:destroy_avp_list: destroying list 0x0
Apr  3 10:03:55 plx-c5-pbx /usr/local/sbin/openser[25354]: receive_msg: cleaning up
Apr  3 10:03:56 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: timer routine:4,tl=0x288d4b7c next=0x0, timeout=41000000
Apr  3 10:03:56 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : request resending (t=0x288d4a30, INVITE si ... )
Apr  3 10:03:56 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG:tm:set_timer: relative timeout is 1000000
Apr  3 10:03:56 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: add_to_tail_of_timer[5]: 0x288d4b7c (42000000)
Apr  3 10:03:56 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : done
Apr  3 10:03:57 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: timer routine:5,tl=0x288d4b7c next=0x0, timeout=42000000
Apr  3 10:03:57 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : request resending (t=0x288d4a30, INVITE si ... )
Apr  3 10:03:57 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG:tm:set_timer: relative timeout is 2000000
Apr  3 10:03:57 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: add_to_tail_of_timer[6]: 0x288d4b7c (44000000)
Apr  3 10:03:57 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : done
Apr  3 10:03:59 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: timer routine:6,tl=0x288d4b7c next=0x0, timeout=44000000
Apr  3 10:03:59 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : request resending (t=0x288d4a30, INVITE si ... )
Apr  3 10:03:59 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG:tm:set_timer: relative timeout is 4000000
Apr  3 10:03:59 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: add_to_tail_of_timer[7]: 0x288d4b7c (48000000)
Apr  3 10:03:59 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : done
Apr  3 10:04:03 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: timer routine:7,tl=0x288d4b7c next=0x0, timeout=48000000
Apr  3 10:04:03 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : request resending (t=0x288d4a30, INVITE si ... )
Apr  3 10:04:03 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG:tm:set_timer: relative timeout is 4000000
Apr  3 10:04:03 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: add_to_tail_of_timer[7]: 0x288d4b7c (52000000)
Apr  3 10:04:03 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : done
Apr  3 10:04:07 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: timer routine:7,tl=0x288d4b7c next=0x0, timeout=52000000
Apr  3 10:04:07 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : request resending (t=0x288d4a30, INVITE si ... )
Apr  3 10:04:07 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG:tm:set_timer: relative timeout is 4000000
Apr  3 10:04:07 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: add_to_tail_of_timer[7]: 0x288d4b7c (56000000)
Apr  3 10:04:07 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : done
Apr  3 10:04:11 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: timer routine:7,tl=0x288d4b7c next=0x0, timeout=56000000
Apr  3 10:04:11 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : request resending (t=0x288d4a30, INVITE si ... )
Apr  3 10:04:11 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG:tm:set_timer: relative timeout is 4000000
Apr  3 10:04:11 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: add_to_tail_of_timer[7]: 0x288d4b7c (60000000)
Apr  3 10:04:11 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : done
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: SIP Request:
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]:  method:  <OPTIONS>
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]:  uri:     <sip:82.215.163.100:5060>
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]:  version: <SIP/2.0>
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: flags=2
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: Found param type 235, <rport> = <n/a>; state=6
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: Found param type 232, <branch> = <z9hG4bKc0a8013d0000001046120a7e0000256b00000125>; state=16
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: end of header reached, state=5
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: Via found, flags=2
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: this is the first via
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: After parse_msg...
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: preparing to run routing scripts...
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: flags=100
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG: get_hdr_body : content_length=0
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: get_hdr_field: cseq <CSeq>: <145> <OPTIONS>
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG:maxfwd:is_maxfwd_present: value = 70 
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: flags=8
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG:parse_to:end of header reached, state=10
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: DBUG:parse_to: display={}, ruri={sip:82.215.163.100:5060}
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG: get_hdr_field: <To> [27]; uri=[sip:82.215.163.100:5060] 
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG: to body [<sip:82.215.163.100:5060>^M ]
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: flags=ffffffffffffffff
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: found end of header
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: check_via_address(82.215.163.186, 82.215.163.186, 0)
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG:destroy_avp_list: destroying list 0x0
Apr  3 10:04:14 plx-c5-pbx /usr/local/sbin/openser[25350]: receive_msg: cleaning up
Apr  3 10:04:15 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: timer routine:7,tl=0x288d4b7c next=0x0, timeout=60000000
Apr  3 10:04:15 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : request resending (t=0x288d4a30, INVITE si ... )
Apr  3 10:04:15 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG:tm:set_timer: relative timeout is 4000000
Apr  3 10:04:15 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: add_to_tail_of_timer[7]: 0x288d4b7c (64000000)
Apr  3 10:04:15 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : done
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: SIP Request:
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]:  method:  <REGISTER>
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]:  uri:     <sip:82.215.163.100>
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]:  version: <SIP/2.0>
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: flags=2
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: Found param type 232, <branch> = <z9hG4bK-1232967626>; state=16
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: end of header reached, state=5
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: Via found, flags=2
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: this is the first via
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: After parse_msg...
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: preparing to run routing scripts...
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: flags=100
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG:parse_to:end of header reached, state=10
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: DBUG:parse_to: display={396006660093}, ruri={sip:396006660093 at 82.215.163.100}
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG: get_hdr_field: <To> [48]; uri=[sip:396006660093 at 82.215.163.100] 
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG: to body [396006660093 <sip:396006660093 at 82.215.163.100>^M ]
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: get_hdr_field: cseq <CSeq>: <3023> <REGISTER>
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG:maxfwd:is_maxfwd_present: value = 70 
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: flags=4000
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG: get_hdr_body : content_length=0
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: found end of header
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: pre_auth(): Credentials with given realm not found
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: build_auth_hf(): 'WWW-Authenticate: Digest realm="plx-c5-pbx.plexia.com", nonce="46120baecc77ebeafac452c376b1e5ab47b51b56"^M '
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: flags=ffffffffffffffff
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: check_via_address(82.215.163.186, 82.215.163.186, 0)
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG:destroy_avp_list: destroying list 0x0
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: receive_msg: cleaning up
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: SIP Request:
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]:  method:  <REGISTER>
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]:  uri:     <sip:82.215.163.100>
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]:  version: <SIP/2.0>
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: flags=2
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: Found param type 232, <branch> = <z9hG4bK-1232967626>; state=16
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: end of header reached, state=5
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: Via found, flags=2
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: this is the first via
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: After parse_msg...
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: preparing to run routing scripts...
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: flags=100
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG:parse_to:end of header reached, state=10
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: DBUG:parse_to: display={396006660093}, ruri={sip:396006660093 at 82.215.163.100}
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG: get_hdr_field: <To> [48]; uri=[sip:396006660093 at 82.215.163.100] 
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG: to body [396006660093 <sip:396006660093 at 82.215.163.100>^M ]
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: get_hdr_field: cseq <CSeq>: <3024> <REGISTER>
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG:maxfwd:is_maxfwd_present: value = 70 
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: check_nonce(): comparing [46120baecc77ebeafac452c376b1e5ab47b51b56] and [46120baecc77ebeafac452c376b1e5ab47b51b56]
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG:auth_radius:radius_authorize_sterman: Success
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG:auth_radius:generate_avps: getting SIP AVPs from avpair 225
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: flags=ffffffffffffffff
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG: get_hdr_body : content_length=0
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: found end of header
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: flags=8000000
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: flags=ffffffffffffffff
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: build_contact(): Created Contact HF: Contact: <sip:396006660093 at 82.215.163.186:1076>;expires=60^M 
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: parse_headers: flags=ffffffffffffffff
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: check_via_address(82.215.163.186, 82.215.163.186, 0)
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: DEBUG:destroy_avp_list: destroying list 0x0
Apr  3 10:04:18 plx-c5-pbx /usr/local/sbin/openser[25350]: receive_msg: cleaning up
Apr  3 10:04:19 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: timer routine:7,tl=0x288d4b7c next=0x0, timeout=64000000
Apr  3 10:04:19 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : request resending (t=0x288d4a30, INVITE si ... )
Apr  3 10:04:19 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG:tm:set_timer: relative timeout is 4000000
Apr  3 10:04:19 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: add_to_tail_of_timer[7]: 0x288d4b7c (68000000)
Apr  3 10:04:19 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : done
Apr  3 10:04:23 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: timer routine:7,tl=0x288d4b7c next=0x0, timeout=68000000
Apr  3 10:04:23 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : request resending (t=0x288d4a30, INVITE si ... )
Apr  3 10:04:23 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG:tm:set_timer: relative timeout is 4000000
Apr  3 10:04:23 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: add_to_tail_of_timer[7]: 0x288d4b7c (72000000)
Apr  3 10:04:23 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: retransmission_handler : done
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: timer routine:0,tl=0x288d4b98 next=0x0, timeout=70
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: final_response_handler:stop retr. and send CANCEL (0x288d4a30)
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG:tm:t_should_relay_response: T_code=100, new_code=408
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG:tm:t_pick_branch: picked branch 0, code 408
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG:tm:relay_reply: branch=0, save=0, relay=0
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25357]: parse_headers: flags=ffffffffffffffff
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25357]: check_via_address(82.215.163.186, 82.215.163.186, 0)
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG:tm:set_timer: relative timeout is 500000
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: add_to_tail_of_timer[4]: 0x288d4af8 (70500000)
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG:tm:set_timer: relative timeout is 30
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: add_to_tail_of_timer[0]: 0x288d4b14 (100)
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG:tm:relay_reply: sent buf=0x8167088: SIP/2.0 4..., shmem=0x288d7068: SIP/2.0 4
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: final_response_handler : done
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: SIP Request:
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]:  method:  <ACK>
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]:  uri:     <sip:396006660084 at 82.215.163.186:1057;transport=UDP>
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]:  version: <SIP/2.0>
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=2
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: Found param type 232, <branch> = <z9hG4bK-3983741888>; state=16
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: end of header reached, state=5
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: Via found, flags=2
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: this is the first via
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: After parse_msg...
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: preparing to run routing scripts...
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG : sl_filter_ACK: to late to be a local ACK!
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=100
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: add_param: tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:parse_to:end of header reached, state=29
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DBUG:parse_to: display={"0104491084"}, ruri={sip:0104491084 at 82.215.163.100}
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: get_hdr_field: <To> [92]; uri=[sip:0104491084 at 82.215.163.100] 
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: to body ["0104491084" <sip:0104491084 at 82.215.163.100>]
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: get_hdr_field: cseq <CSeq>: <3> <ACK>
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: get_hdr_body : content_length=0
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: found end of header
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: is_maxfwd_present: max_forwards header not found!
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.186] == [82.215.163.100]
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: grep_sock_info - checking if port 5060 matches port 1057
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:check_self: host != me
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: add_param: tag=2630444615
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:parse_to:end of header reached, state=29
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DBUG:parse_to: display={"0104491093"}, ruri={sip:0104491093 at plx-c5-pbx.plexia.com:5060}
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=200
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: is_preloaded: No
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.186] == [82.215.163.100]
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: grep_sock_info - checking if port 5060 matches port 1057
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:check_self: host != me
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: after_loose: Topmost route URI: 'sip:82.215.163.100;vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA--' is me
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=200
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: found end of header
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: find_next_route: No next Route HF found
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: after_loose: No next URI found
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DBG:rr:run_rr_callbacks: callback id 1 entered with <vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA-->
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:uac:restore_from: getting 'vsf' Route param
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:uac:restore_from: Route param is 'AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA--' (len=60)
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:rr:is_direction: param ftag not found
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:uac:restore_from: decoded uris are: new=[sip:39600666178|^^&s*-`ty;b9r%^T^L^YG^BA^NUX ^C] old=[sip:0104491093 at plx-c5-pbx.plexia.com:5060]
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DBG:rr:run_rr_callbacks: callback id 0 entered with <vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA-->
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:dialog:dlg_onroute: Route param 'did' not found
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=ffffffffffffffff
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: [PLX-OBR] ACK|BYE|CANCEL Request-uri(sip:396006660084 at 82.215.163.186:1057;transport=UDP) To uri: sip:0104491084 at 82.215.163.100 destination uri 
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: avpops:ops_dbquery_avps: query [SELECT lid,remote_party_id,maxi FROM rpid WHERE remote_party_id = '390104491084']
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: avpops:db_query_avp: rows [1]
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: avpops:db_query_avp: row [0]
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: close avp query
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: [PLX-OBR] 4 Found CLI[390104491084] associated to LID[396006660084]
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:avpops:pushto_avps: 1 avps were processed
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: [PLX-OBR] check if maxi == 1
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:avpops:check_avp: check <0> against <1> as int /33554433
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:avpops:check_avp: no match
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:avpops:delete_avps: 1 avps were removed
Apr  3 10:04:25 plx-c5-pbx last message repeated 2 times
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: rewrite_uri: Rewriting Request-URI with 'sip:396006660084 at 82.215.163.186:1079'
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: avpops:ops_dbquery_avps: query [SELECT lid,remote_party_id,maxi FROM rpid WHERE lid = '0104491093']
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: avpops:db_query_avp: no result after query
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: close avp query
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: [PLX-OBR] NOTHING FOUND
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:avpops:delete_avps: 0 avps were removed
Apr  3 10:04:25 plx-c5-pbx last message repeated 2 times
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: t_newtran:  T on entrance=0xffffffff
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=ffffffffffffffff
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: parse_headers: flags=78
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: t_lookup_request: start searching: hash=5920, isACK=1
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: RFC3261 transaction matched, tid=-3983741888
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:tm:REF_UNSAFE: after is 1
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: t_lookup_request: transaction found (T=0x288d4a30)
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: cleanup_uac_timers: RETR/FR timers reset
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG: add_to_tail_of_timer[2]: 0x288d4a78 (75)
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:tm:UNREF_UNSAFE: after is 0
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: DEBUG:destroy_avp_list: destroying list 0x0
Apr  3 10:04:25 plx-c5-pbx /usr/local/sbin/openser[25355]: receive_msg: cleaning up
Apr  3 10:04:26 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: timer routine:4,tl=0x288d4af8 next=0x0, timeout=70500000
Apr  3 10:04:27 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: timer routine:7,tl=0x288d4b7c next=0x0, timeout=72000000
Apr  3 10:04:30 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: timer routine:2,tl=0x288d4a78 next=0x0, timeout=75
Apr  3 10:04:30 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: wait_handler : removing 0x288d4a30 from table 
Apr  3 10:04:30 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: delete transaction 0x288d4a30
Apr  3 10:04:30 plx-c5-pbx /usr/local/sbin/openser[25357]: DEBUG: wait_handler : done
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: SIP Request:
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]:  method:  <OPTIONS>
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]:  uri:     <sip:82.215.163.100:5060>
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]:  version: <SIP/2.0>
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: parse_headers: flags=2
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: Found param type 235, <rport> = <n/a>; state=6
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: Found param type 232, <branch> = <z9hG4bKc0a8013d0000001046120a920000176d00000127>; state=16
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: end of header reached, state=5
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: parse_headers: Via found, flags=2
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: parse_headers: this is the first via
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: After parse_msg...
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: preparing to run routing scripts...
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: parse_headers: flags=100
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG: get_hdr_body : content_length=0
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: get_hdr_field: cseq <CSeq>: <146> <OPTIONS>
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG:maxfwd:is_maxfwd_present: value = 70 
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: grep_sock_info - checking if host==us: 14==14 &&  [82.215.163.100] == [82.215.163.100]
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: grep_sock_info - checking if port 5060 matches port 5060
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: parse_headers: flags=8
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG:parse_to:end of header reached, state=10
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: DBUG:parse_to: display={}, ruri={sip:82.215.163.100:5060}
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG: get_hdr_field: <To> [27]; uri=[sip:82.215.163.100:5060] 
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG: to body [<sip:82.215.163.100:5060>^M ]
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: parse_headers: flags=ffffffffffffffff
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: found end of header
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: check_via_address(82.215.163.186, 82.215.163.186, 0)
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: DEBUG:destroy_avp_list: destroying list 0x0
Apr  3 10:04:34 plx-c5-pbx /usr/local/sbin/openser[25348]: receive_msg: cleaning up
-------------- next part --------------
interface: bge0 (82.215.163.0/255.255.255.0)
filter: (ip or ip6) and ( port 5060 )
#
U 82.215.163.186:1024 -> 82.215.163.100:5060
OPTIONS sip:82.215.163.100:5060 SIP/2.0.
Via: SIP/2.0/UDP 82.215.163.186:1038;rport;branch=z9hG4bKc0a8013d0000001046120a5600002e8d00000121.
Content-Length: 0.
Call-ID: D5371A72-8D83-41AE-B43D-6312E5C51460 at 82.215.163.151.
CSeq: 143 OPTIONS.
From: <sip:396006660099 at 82.215.163.100>;tag=298760921713.
Max-Forwards: 70.
To: <sip:82.215.163.100:5060>.
.

#
U 82.215.163.100:5060 -> 82.215.163.186:1024
SIP/2.0 200 OK.
Via: SIP/2.0/UDP 82.215.163.186:1038;rport=1024;branch=z9hG4bKc0a8013d0000001046120a5600002e8d00000121.
Call-ID: D5371A72-8D83-41AE-B43D-6312E5C51460 at 82.215.163.151.
CSeq: 143 OPTIONS.
From: <sip:396006660099 at 82.215.163.100>;tag=298760921713.
To: <sip:82.215.163.100:5060>;tag=dd62ffe5313c9a530c48a37ac354e814.6e61.
Accept: */*.
Accept-Encoding: .
Accept-Language: en.
Supported: .
Server: OpenSER (1.2.0-notls (i386/freebsd)).
Content-Length: 0.
.

#
U 82.215.163.186:1076 -> 82.215.163.100:5060
REGISTER sip:82.215.163.100 SIP/2.0.
Via: SIP/2.0/UDP 82.215.163.186:1076;branch=z9hG4bK-3670682326.
From: 396006660093 <sip:396006660093 at 82.215.163.100>;tag=1934495533.
To: 396006660093 <sip:396006660093 at 82.215.163.100>.
Call-ID: 1135760469 at 82.215.163.191.
CSeq: 3021 REGISTER.
Contact: <sip:396006660093 at 82.215.163.186:1076>.
Max-forwards: 70.
User-agent: Aethra-UserAgent/V.1.4.19.
expires: 60.
Content-Length: 0.
.

#
U 82.215.163.100:5060 -> 82.215.163.186:1076
SIP/2.0 401 Unauthorized.
Via: SIP/2.0/UDP 82.215.163.186:1076;branch=z9hG4bK-3670682326.
From: 396006660093 <sip:396006660093 at 82.215.163.100>;tag=1934495533.
To: 396006660093 <sip:396006660093 at 82.215.163.100>;tag=dd62ffe5313c9a530c48a37ac354e814.e917.
Call-ID: 1135760469 at 82.215.163.191.
CSeq: 3021 REGISTER.
WWW-Authenticate: Digest realm="plx-c5-pbx.plexia.com", nonce="46120b8442b7777ccbdfbadb16bd0ff3617a80d5".
Server: OpenSER (1.2.0-notls (i386/freebsd)).
Content-Length: 0.
.

#
U 82.215.163.186:1076 -> 82.215.163.100:5060
REGISTER sip:82.215.163.100 SIP/2.0.
Via: SIP/2.0/UDP 82.215.163.186:1076;branch=z9hG4bK-3670682326.
From: 396006660093 <sip:396006660093 at 82.215.163.100>;tag=1934495533.
To: 396006660093 <sip:396006660093 at 82.215.163.100>.
Call-ID: 1135760469 at 82.215.163.191.
CSeq: 3022 REGISTER.
Contact: <sip:396006660093 at 82.215.163.186:1076>.
Authorization: Digest username="396006660093", realm="plx-c5-pbx.plexia.com", nonce="46120b8442b7777ccbdfbadb16bd0ff3617a80d5", uri="<sip:396006660093 at 82.215.172.2>", response="14e1c43d2780eb1465f59a6c07639051".
Max-forwards: 70.
User-agent: Aethra-UserAgent/V.1.4.19.
expires: 60.
Content-Length: 0.
.

#
U 82.215.163.100:5060 -> 82.215.163.186:1076
SIP/2.0 200 OK.
Via: SIP/2.0/UDP 82.215.163.186:1076;branch=z9hG4bK-3670682326.
From: 396006660093 <sip:396006660093 at 82.215.163.100>;tag=1934495533.
To: 396006660093 <sip:396006660093 at 82.215.163.100>;tag=dd62ffe5313c9a530c48a37ac354e814.e917.
Call-ID: 1135760469 at 82.215.163.191.
CSeq: 3022 REGISTER.
Contact: <sip:396006660093 at 82.215.163.186:1076>;expires=60.
Server: OpenSER (1.2.0-notls (i386/freebsd)).
Content-Length: 0.
.

#
U 82.215.163.186:1079 -> 82.215.163.100:5060
INVITE sip:0104491093 at plx-c5-pbx.plexia.com;transport=UDP SIP/2.0.
From: "396006660084"<sip:396006660084 at plx-c5-pbx.plexia.com:5060>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
To: "0104491093"<sip:0104491093 at plx-c5-pbx.plexia.com:5060>.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 1 INVITE.
Via: SIP/2.0/UDP 82.215.163.186:1079;rport;branch=z9hG4bK-f1bc-3b04a16-7d60b58b.
Max-Forwards: 70.
Supported: replaces,100rel.
User-Agent: SpeedTouch 780.
Accept: application/dtmf-relay, x-application/dtmf-relay, application/sdp.
Allow: INVITE, ACK, BYE, REFER, NOTIFY, CANCEL, INFO, OPTIONS.
Contact: <sip:396006660084 at 82.215.163.186:1079;transport=UDP>.
Content-Type: application/sdp.
Content-Length: 179.
.
v=0.
o=780 62787 62787 IN IP4 82.215.163.199.
s=-.
c=IN IP4 82.215.163.186.
t=0 0.
m=audio 1122 RTP/AVP 18 0 8 4 97.
a=rtpmap:97 telephone-event/8000.
a=ptime:20.
a=fmtp:97 0-15.

#
U 82.215.163.100:5060 -> 82.215.163.186:1079
SIP/2.0 407 Proxy Authentication Required.
From: "396006660084"<sip:396006660084 at plx-c5-pbx.plexia.com:5060>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
To: "0104491093"<sip:0104491093 at plx-c5-pbx.plexia.com:5060>;tag=dd62ffe5313c9a530c48a37ac354e814.7362.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 1 INVITE.
Via: SIP/2.0/UDP 82.215.163.186:1079;rport=1079;branch=z9hG4bK-f1bc-3b04a16-7d60b58b.
Proxy-Authenticate: Digest realm="plx-c5-pbx.plexia.com", nonce="46120b87e8fdf68e47bb2b7be4727f00eff80c6f".
Server: OpenSER (1.2.0-notls (i386/freebsd)).
Content-Length: 0.
.

#
U 82.215.163.186:1079 -> 82.215.163.100:5060
ACK sip:0104491093 at plx-c5-pbx.plexia.com;transport=UDP SIP/2.0.
From: "396006660084"<sip:396006660084 at plx-c5-pbx.plexia.com:5060>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
To: "0104491093"<sip:0104491093 at plx-c5-pbx.plexia.com:5060>;tag=dd62ffe5313c9a530c48a37ac354e814.7362.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 1 ACK.
Via: SIP/2.0/UDP 82.215.163.186:1079;rport;branch=z9hG4bK-f1bc-3b04a16-7d60b58b.
Max-Forwards: 70.
User-Agent: SpeedTouch 780.
Accept: application/dtmf-relay, x-application/dtmf-relay, application/sdp.
Contact: <sip:396006660084 at 82.215.163.186:1079;transport=UDP>.
Content-Length: 0.
.

#
U 82.215.163.186:1079 -> 82.215.163.100:5060
INVITE sip:0104491093 at plx-c5-pbx.plexia.com;transport=UDP SIP/2.0.
From: "396006660084"<sip:396006660084 at plx-c5-pbx.plexia.com:5060>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
To: "0104491093"<sip:0104491093 at plx-c5-pbx.plexia.com:5060>.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 2 INVITE.
Via: SIP/2.0/UDP 82.215.163.186:1079;rport;branch=z9hG4bK-f1bd-3b04a70-63526190.
Max-Forwards: 70.
Supported: replaces,100rel.
User-Agent: SpeedTouch 780.
Accept: application/dtmf-relay, x-application/dtmf-relay, application/sdp.
Allow: INVITE, ACK, BYE, REFER, NOTIFY, CANCEL, INFO, OPTIONS.
Contact: <sip:396006660084 at 82.215.163.186:1079;transport=UDP>.
Proxy-Authorization: Digest username="396006660084",realm="plx-c5-pbx.plexia.com",nonce="46120b87e8fdf68e47bb2b7be4727f00eff80c6f",uri="sip:0104491093 at plx-c5-pbx.plexia.com;transport=UDP",response="fc0554717bd62d1948bc6a2178095f46",algorithm=MD5.
Content-Type: application/sdp.
Content-Length: 179.
.
v=0.
o=780 62787 62787 IN IP4 82.215.163.199.
s=-.
c=IN IP4 82.215.163.186.
t=0 0.
m=audio 1122 RTP/AVP 18 0 8 4 97.
a=rtpmap:97 telephone-event/8000.
a=ptime:20.
a=fmtp:97 0-15.

#
U 82.215.163.100:5060 -> 82.215.163.186:1079
SIP/2.0 100 Giving a try.
From: "396006660084"<sip:396006660084 at plx-c5-pbx.plexia.com:5060>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
To: "0104491093"<sip:0104491093 at plx-c5-pbx.plexia.com:5060>.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 2 INVITE.
Via: SIP/2.0/UDP 82.215.163.186:1079;rport=1079;branch=z9hG4bK-f1bd-3b04a70-63526190.
Server: OpenSER (1.2.0-notls (i386/freebsd)).
Content-Length: 0.
.

#
U 82.215.163.100:5060 -> 82.215.163.186:1076
INVITE sip:396006660093 at 82.215.163.186:1076 SIP/2.0.
Record-Route: <sip:82.215.163.100;lr=on;ftag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc;vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA-->.
From: "0104491084"<sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
To: "0104491093"<sip:0104491093 at plx-c5-pbx.plexia.com:5060>.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 2 INVITE.
Via: SIP/2.0/UDP 82.215.163.100;branch=z9hG4bKf171.07a21b96.0.
Via: SIP/2.0/UDP 82.215.163.186:1079;rport=1079;branch=z9hG4bK-f1bd-3b04a70-63526190.
Max-Forwards: 69.
Supported: replaces,100rel.
User-Agent: SpeedTouch 780.
Accept: application/dtmf-relay, x-application/dtmf-relay, application/sdp.
Allow: INVITE, ACK, BYE, REFER, NOTIFY, CANCEL, INFO, OPTIONS.
Contact: <sip:396006660084 at 82.215.163.186:1079;transport=UDP>.
Content-Type: application/sdp.
Content-Length: 179.
.
v=0.
o=780 62787 62787 IN IP4 82.215.163.199.
s=-.
c=IN IP4 82.215.163.186.
t=0 0.
m=audio 1122 RTP/AVP 18 0 8 4 97.
a=rtpmap:97 telephone-event/8000.
a=ptime:20.
a=fmtp:97 0-15.

#
U 82.215.163.186:1076 -> 82.215.163.100:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP 82.215.163.100;branch=z9hG4bKf171.07a21b96.0.
Via: SIP/2.0/UDP 82.215.163.186:1057;rport=1079;branch=z9hG4bK-f1bd-3b04a70-63526190.
From: "0104491084" <sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
To: "0104491093" <sip:0104491093 at plx-c5-pbx.plexia.com:5060>.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 2 INVITE.
Content-Length: 0.
.

#
U 82.215.163.186:1076 -> 82.215.163.100:5060
SIP/2.0 180 Ringing.
Via: SIP/2.0/UDP 82.215.163.100;branch=z9hG4bKf171.07a21b96.0.
Via: SIP/2.0/UDP 82.215.163.186:1057;rport=1079;branch=z9hG4bK-f1bd-3b04a70-63526190.
Record-Route: <sip:82.215.163.100;vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA-->.
From: "0104491084" <sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
To: "0104491093" <sip:0104491093 at plx-c5-pbx.plexia.com:5060>;tag=2630444615.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 2 INVITE.
Contact: <sip:396006660093 at 82.215.163.186:1076>.
Content-Length: 0.
.

#
U 82.215.163.100:5060 -> 82.215.163.186:1079
SIP/2.0 180 Ringing.
Via: SIP/2.0/UDP 82.215.163.186:1057;rport=1079;branch=z9hG4bK-f1bd-3b04a70-63526190.
Record-Route: <sip:82.215.163.100;vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA-->.
From: "396006660084"<sip:396006660084 at plx-c5-pbx.plexia.com:5060>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
To: "0104491093" <sip:0104491093 at plx-c5-pbx.plexia.com:5060>;tag=2630444615.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 2 INVITE.
Contact: <sip:396006660093 at 82.215.163.186:1076>.
Content-Length: 0.
.

#
U 82.215.163.186:1076 -> 82.215.163.100:5060
SIP/2.0 200 OK.
Via: SIP/2.0/UDP 82.215.163.100;branch=z9hG4bKf171.07a21b96.0.
Via: SIP/2.0/UDP 82.215.163.186:1057;rport=1079;branch=z9hG4bK-f1bd-3b04a70-63526190.
Record-Route: <sip:82.215.163.100;vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA-->.
From: "0104491084" <sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
To: "0104491093" <sip:0104491093 at plx-c5-pbx.plexia.com:5060>;tag=2630444615.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 2 INVITE.
Contact: <sip:396006660093 at 82.215.163.186:1076>.
Content-type: application/sdp.
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, INFO, PRACK, REGISTER.
Content-Length: 216  .
.
v=0.
o=aethra 1 10 IN IP4 82.215.163.191.
s=-.
c=IN IP4 82.215.163.186.
t=0 0.
m=audio 5004 RTP/AVP 18 8 97.
a=rtpmap:18 G729/8000.
a=rtpmap:8 PCMA/8000.
a=rtpmap:97 telephone-event/8000.
a=fmtp:97 0-15.
a=ptime:20.

#
U 82.215.163.100:5060 -> 82.215.163.186:1079
SIP/2.0 200 OK.
Via: SIP/2.0/UDP 82.215.163.186:1057;rport=1079;branch=z9hG4bK-f1bd-3b04a70-63526190.
Record-Route: <sip:82.215.163.100;vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA-->.
From: "396006660084"<sip:396006660084 at plx-c5-pbx.plexia.com:5060>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
To: "0104491093" <sip:0104491093 at plx-c5-pbx.plexia.com:5060>;tag=2630444615.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 2 INVITE.
Contact: <sip:396006660093 at 82.215.163.186:1076>.
Content-type: application/sdp.
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, INFO, PRACK, REGISTER.
Content-Length: 216  .
.
v=0.
o=aethra 1 10 IN IP4 82.215.163.191.
s=-.
c=IN IP4 82.215.163.186.
t=0 0.
m=audio 5004 RTP/AVP 18 8 97.
a=rtpmap:18 G729/8000.
a=rtpmap:8 PCMA/8000.
a=rtpmap:97 telephone-event/8000.
a=fmtp:97 0-15.
a=ptime:20.

#
U 82.215.163.186:1079 -> 82.215.163.100:5060
ACK sip:82.215.163.100;vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA-- SIP/2.0.
From: "396006660084"<sip:396006660084 at plx-c5-pbx.plexia.com:5060>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
To: "0104491093"<sip:0104491093 at plx-c5-pbx.plexia.com:5060>;tag=2630444615.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 2 ACK.
Via: SIP/2.0/UDP 82.215.163.186:1079;rport;branch=z9hG4bK-f1c2-3b05f6a-2af3d0d9.
Max-Forwards: 70.
User-Agent: SpeedTouch 780.
Accept: application/dtmf-relay, x-application/dtmf-relay, application/sdp.
Contact: <sip:396006660084 at 82.215.163.186:1079;transport=UDP>.
Route: <sip:396006660093 at 82.215.163.186:1063>.
Proxy-Authorization: Digest username="396006660084",realm="plx-c5-pbx.plexia.com",nonce="46120b87e8fdf68e47bb2b7be4727f00eff80c6f",uri="sip:0104491093 at plx-c5-pbx.plexia.com;transport=UDP",response="fc0554717bd62d1948bc6a2178095f46",algorithm=MD5.
Content-Length: 0.
.

#
U 82.215.163.100:5060 -> 82.215.163.186:1076
ACK sip:396006660093 at 82.215.163.186:1076 SIP/2.0.
Record-Route: <sip:82.215.163.100;lr=on;ftag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc>.
From: "396006660084"<sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
To: "0104491093"<sip:0104491093 at plx-c5-pbx.plexia.com:5060>;tag=2630444615.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 2 ACK.
Via: SIP/2.0/UDP 82.215.163.100;branch=z9hG4bKf171.07a21b96.2.
Via: SIP/2.0/UDP 82.215.163.186:1079;rport=1079;branch=z9hG4bK-f1c2-3b05f6a-2af3d0d9.
Max-Forwards: 69.
User-Agent: SpeedTouch 780.
Accept: application/dtmf-relay, x-application/dtmf-relay, application/sdp.
Contact: <sip:396006660084 at 82.215.163.186:1079;transport=UDP>.
Proxy-Authorization: Digest username="396006660084",realm="plx-c5-pbx.plexia.com",nonce="46120b87e8fdf68e47bb2b7be4727f00eff80c6f",uri="sip:0104491093 at plx-c5-pbx.plexia.com;transport=UDP",response="fc0554717bd62d1948bc6a2178095f46",algorithm=MD5.
Content-Length: 0.
P-hint: rr-enforced.
.

#
U 82.215.163.186:1024 -> 82.215.163.100:5060
OPTIONS sip:82.215.163.100:5060 SIP/2.0.
Via: SIP/2.0/UDP 82.215.163.186:1038;rport;branch=z9hG4bKc0a8013d0000001046120a6a0000295200000123.
Content-Length: 0.
Call-ID: D5371A72-8D83-41AE-B43D-6312E5C51460 at 82.215.163.151.
CSeq: 144 OPTIONS.
From: <sip:396006660099 at 82.215.163.100>;tag=30076096429.
Max-Forwards: 70.
To: <sip:82.215.163.100:5060>.
.

#
U 82.215.163.100:5060 -> 82.215.163.186:1024
SIP/2.0 200 OK.
Via: SIP/2.0/UDP 82.215.163.186:1038;rport=1024;branch=z9hG4bKc0a8013d0000001046120a6a0000295200000123.
Call-ID: D5371A72-8D83-41AE-B43D-6312E5C51460 at 82.215.163.151.
CSeq: 144 OPTIONS.
From: <sip:396006660099 at 82.215.163.100>;tag=30076096429.
To: <sip:82.215.163.100:5060>;tag=dd62ffe5313c9a530c48a37ac354e814.1497.
Accept: */*.
Accept-Encoding: .
Accept-Language: en.
Supported: .
Server: OpenSER (1.2.0-notls (i386/freebsd)).
Content-Length: 0.
.

#
U 82.215.163.186:1076 -> 82.215.163.100:5060
INVITE sip:396006660084 at 82.215.163.186:1057;transport=UDP SIP/2.0.
Via: SIP/2.0/UDP 82.215.163.186:1076;branch=z9hG4bK-3983741888.
Route: <sip:82.215.163.100;vsf=AAAAAAMIBgQEDwcGCAR4DHJeXkkYTQQbQ0xJHkBsZXhpYS5jb206NTA2MA-->.
From: "0104491093" <sip:0104491093 at plx-c5-pbx.plexia.com:5060>;tag=2630444615.
To: "0104491084" <sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 3 INVITE.
Contact: <sip:396006660093 at 82.215.163.186:1076>.
Max-forwards: 50.
Supported: 100rel.
User-agent: Aethra-UserAgent/V.1.4.19.
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, INFO, PRACK, REGISTER.
Content-Type: application/sdp.
Content-Length: 276  .
.
v=0.
o=aethra 1 11 IN IP4 82.215.163.191.
s=Normal Call 3.
c=IN IP4 82.215.163.186.
t=0 0.
m=image 5004 udptl t38.
a=T38FaxVersion:0.
a=T38MaxBitRate:14400.
a=T38FaxRateManagement:transferredTCF.
a=T38FaxMaxBuffer:524.
a=T38FaxMaxDatagram:176.
a=T38FaxUdpEC:t38UDPRedundancy.

#
U 82.215.163.100:5060 -> 82.215.163.186:1076
SIP/2.0 100 Giving a try.
Via: SIP/2.0/UDP 82.215.163.186:1076;branch=z9hG4bK-3983741888.
From: "0104491093" <sip:0104491093 at plx-c5-pbx.plexia.com:5060>;tag=2630444615.
To: "0104491084" <sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 3 INVITE.
Server: OpenSER (1.2.0-notls (i386/freebsd)).
Content-Length: 0.
.

#
U 82.215.163.100:5060 -> 82.215.163.186:1079
INVITE sip:396006660084 at 82.215.163.186:1079 SIP/2.0.
Record-Route: <sip:82.215.163.100;lr=on;ftag=2630444615>.
Via: SIP/2.0/UDP 82.215.163.100;branch=z9hG4bK0271.545595e6.0.
Via: SIP/2.0/UDP 82.215.163.186:1076;branch=z9hG4bK-3983741888.
From: "0104491093" <sip:39600666178|.&s*-`ty;b9r%...G.A.UX
..60>;tag=2630444615.
To: "0104491084" <sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 3 INVITE.
Contact: <sip:396006660093 at 82.215.163.186:1076>.
Max-forwards: 49.
Supported: 100rel.
User-agent: Aethra-UserAgent/V.1.4.19.
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, INFO, PRACK, REGISTER.
Content-Type: application/sdp.
Content-Length: 276  .
P-hint: rr-enforced.
.
v=0.
o=aethra 1 11 IN IP4 82.215.163.191.
s=Normal Call 3.
c=IN IP4 82.215.163.186.
t=0 0.
m=image 5004 udptl t38.
a=T38FaxVersion:0.
a=T38MaxBitRate:14400.
a=T38FaxRateManagement:transferredTCF.
a=T38FaxMaxBuffer:524.
a=T38FaxMaxDatagram:176.
a=T38FaxUdpEC:t38UDPRedundancy.

#
U 82.215.163.100:5060 -> 82.215.163.186:1079
INVITE sip:396006660084 at 82.215.163.186:1079 SIP/2.0.
Record-Route: <sip:82.215.163.100;lr=on;ftag=2630444615>.
Via: SIP/2.0/UDP 82.215.163.100;branch=z9hG4bK0271.545595e6.0.
Via: SIP/2.0/UDP 82.215.163.186:1076;branch=z9hG4bK-3983741888.
From: "0104491093" <sip:39600666178|.&s*-`ty;b9r%...G.A.UX
..60>;tag=2630444615.
To: "0104491084" <sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 3 INVITE.
Contact: <sip:396006660093 at 82.215.163.186:1076>.
Max-forwards: 49.
Supported: 100rel.
User-agent: Aethra-UserAgent/V.1.4.19.
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, INFO, PRACK, REGISTER.
Content-Type: application/sdp.
Content-Length: 276  .
P-hint: rr-enforced.
.
v=0.
o=aethra 1 11 IN IP4 82.215.163.191.
s=Normal Call 3.
c=IN IP4 82.215.163.186.
t=0 0.
m=image 5004 udptl t38.
a=T38FaxVersion:0.
a=T38MaxBitRate:14400.
a=T38FaxRateManagement:transferredTCF.
a=T38FaxMaxBuffer:524.
a=T38FaxMaxDatagram:176.
a=T38FaxUdpEC:t38UDPRedundancy.

#
U 82.215.163.100:5060 -> 82.215.163.186:1079
INVITE sip:396006660084 at 82.215.163.186:1079 SIP/2.0.
Record-Route: <sip:82.215.163.100;lr=on;ftag=2630444615>.
Via: SIP/2.0/UDP 82.215.163.100;branch=z9hG4bK0271.545595e6.0.
Via: SIP/2.0/UDP 82.215.163.186:1076;branch=z9hG4bK-3983741888.
From: "0104491093" <sip:39600666178|.&s*-`ty;b9r%...G.A.UX
..60>;tag=2630444615.
To: "0104491084" <sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 3 INVITE.
Contact: <sip:396006660093 at 82.215.163.186:1076>.
Max-forwards: 49.
Supported: 100rel.
User-agent: Aethra-UserAgent/V.1.4.19.
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, INFO, PRACK, REGISTER.
Content-Type: application/sdp.
Content-Length: 276  .
P-hint: rr-enforced.
.
v=0.
o=aethra 1 11 IN IP4 82.215.163.191.
s=Normal Call 3.
c=IN IP4 82.215.163.186.
t=0 0.
m=image 5004 udptl t38.
a=T38FaxVersion:0.
a=T38MaxBitRate:14400.
a=T38FaxRateManagement:transferredTCF.
a=T38FaxMaxBuffer:524.
a=T38FaxMaxDatagram:176.
a=T38FaxUdpEC:t38UDPRedundancy.

#
U 82.215.163.100:5060 -> 82.215.163.186:1079
INVITE sip:396006660084 at 82.215.163.186:1079 SIP/2.0.
Record-Route: <sip:82.215.163.100;lr=on;ftag=2630444615>.
Via: SIP/2.0/UDP 82.215.163.100;branch=z9hG4bK0271.545595e6.0.
Via: SIP/2.0/UDP 82.215.163.186:1076;branch=z9hG4bK-3983741888.
From: "0104491093" <sip:39600666178|.&s*-`ty;b9r%...G.A.UX
..60>;tag=2630444615.
To: "0104491084" <sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 3 INVITE.
Contact: <sip:396006660093 at 82.215.163.186:1076>.
Max-forwards: 49.
Supported: 100rel.
User-agent: Aethra-UserAgent/V.1.4.19.
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, INFO, PRACK, REGISTER.
Content-Type: application/sdp.
Content-Length: 276  .
P-hint: rr-enforced.
.
v=0.
o=aethra 1 11 IN IP4 82.215.163.191.
s=Normal Call 3.
c=IN IP4 82.215.163.186.
t=0 0.
m=image 5004 udptl t38.
a=T38FaxVersion:0.
a=T38MaxBitRate:14400.
a=T38FaxRateManagement:transferredTCF.
a=T38FaxMaxBuffer:524.
a=T38FaxMaxDatagram:176.
a=T38FaxUdpEC:t38UDPRedundancy.

#
U 82.215.163.100:5060 -> 82.215.163.186:1079
INVITE sip:396006660084 at 82.215.163.186:1079 SIP/2.0.
Record-Route: <sip:82.215.163.100;lr=on;ftag=2630444615>.
Via: SIP/2.0/UDP 82.215.163.100;branch=z9hG4bK0271.545595e6.0.
Via: SIP/2.0/UDP 82.215.163.186:1076;branch=z9hG4bK-3983741888.
From: "0104491093" <sip:39600666178|.&s*-`ty;b9r%...G.A.UX
..60>;tag=2630444615.
To: "0104491084" <sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 3 INVITE.
Contact: <sip:396006660093 at 82.215.163.186:1076>.
Max-forwards: 49.
Supported: 100rel.
User-agent: Aethra-UserAgent/V.1.4.19.
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, INFO, PRACK, REGISTER.
Content-Type: application/sdp.
Content-Length: 276  .
P-hint: rr-enforced.
.
v=0.
o=aethra 1 11 IN IP4 82.215.163.191.
s=Normal Call 3.
c=IN IP4 82.215.163.186.
t=0 0.
m=image 5004 udptl t38.
a=T38FaxVersion:0.
a=T38MaxBitRate:14400.
a=T38FaxRateManagement:transferredTCF.
a=T38FaxMaxBuffer:524.
a=T38FaxMaxDatagram:176.
a=T38FaxUdpEC:t38UDPRedundancy.

#
U 82.215.163.100:5060 -> 82.215.163.186:1079
INVITE sip:396006660084 at 82.215.163.186:1079 SIP/2.0.
Record-Route: <sip:82.215.163.100;lr=on;ftag=2630444615>.
Via: SIP/2.0/UDP 82.215.163.100;branch=z9hG4bK0271.545595e6.0.
Via: SIP/2.0/UDP 82.215.163.186:1076;branch=z9hG4bK-3983741888.
From: "0104491093" <sip:39600666178|.&s*-`ty;b9r%...G.A.UX
..60>;tag=2630444615.
To: "0104491084" <sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 3 INVITE.
Contact: <sip:396006660093 at 82.215.163.186:1076>.
Max-forwards: 49.
Supported: 100rel.
User-agent: Aethra-UserAgent/V.1.4.19.
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, INFO, PRACK, REGISTER.
Content-Type: application/sdp.
Content-Length: 276  .
P-hint: rr-enforced.
.
v=0.
o=aethra 1 11 IN IP4 82.215.163.191.
s=Normal Call 3.
c=IN IP4 82.215.163.186.
t=0 0.
m=image 5004 udptl t38.
a=T38FaxVersion:0.
a=T38MaxBitRate:14400.
a=T38FaxRateManagement:transferredTCF.
a=T38FaxMaxBuffer:524.
a=T38FaxMaxDatagram:176.
a=T38FaxUdpEC:t38UDPRedundancy.

#
U 82.215.163.100:5060 -> 82.215.163.186:1079
INVITE sip:396006660084 at 82.215.163.186:1079 SIP/2.0.
Record-Route: <sip:82.215.163.100;lr=on;ftag=2630444615>.
Via: SIP/2.0/UDP 82.215.163.100;branch=z9hG4bK0271.545595e6.0.
Via: SIP/2.0/UDP 82.215.163.186:1076;branch=z9hG4bK-3983741888.
From: "0104491093" <sip:39600666178|.&s*-`ty;b9r%...G.A.UX
..60>;tag=2630444615.
To: "0104491084" <sip:0104491084 at 82.215.163.100>;tag=80b12a70-52d7a439-13c4-f1bc-1eda01ca-f1bc.
Call-ID: 80b10210-52d7a439-13c4-f1bc-2d7c531d-f1bc at plx-c5-pbx.plexia.com.
CSeq: 3 INVITE.
Contact: <sip:396006660093 at 82.215.163.186:1076>.
Max-forwards: 49.
Supported: 100rel.
User-agent: Aethra-UserAgent/V.1.4.19.
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, INFO, PRACK, REGISTER.
Content-Type: application/sdp.
Content-Length: 276  .
P-hint: rr-enforced.
.
v=0.
o=aethra 1 11 IN IP4 82.215.163.191.
s=Normal Call 3.
c=IN IP4 82.215.163.186.
t=0 0.
m=image 5004 udptl t38.
a=T38FaxVersion:0.
a=T38MaxBitRate:14400.
a=T38FaxRateManagement:transferredTCF.
a=T38FaxMaxBuffer:524.
a=T38FaxMaxDatagram:176.
a=T38FaxUdpEC:t38UDPRedundancy.

#
U 82.215.163.186:1024 -> 82.215.163.100:5060
OPTIONS sip:82.215.163.100:5060 SIP/2.0.
Via: SIP/2.0/UDP 82.215.163.186:1038;rport;branch=z9hG4bKc0a8013d0000001046120a7e0000256b00000125.
Content-Length: 0.
Call-ID: D5371A72-8D83-41AE-B43D-6312E5C51460 at 82.215.163.151.
CSeq: 145 OPTIONS.
From: <sip:396006660099 at 82.215.163.100>;tag=30276099079.
Max-Forwards: 70.
To: <sip:82.215.163.100:5060>.
.

#
U 82.215.163.100:5060 -> 82.215.163.186:1024
SIP/2.0 200 OK.
Via: SIP/2.0/UDP 82.215.163.186:1038;rport=1024;branch=z9hG4bKc0a8013d0000001046120a7e0000256b00000125.
Call-ID: D5371A72-8D83-41AE-B43D-6312E5C51460 at 82.215.163.151.
CSeq: 145 OPTIONS.
From: <sip:396006660099 at 82.215.163.100>;tag=30276099079.
To: <sip:82.215.163.100:5060>;tag=dd62ffe5313c9a530c48a37ac354e814.dcb2.
Accept: */*.
Accept-Encoding: .
Accept-Language: en.
Supported: .
Server: OpenSER (1.2.0-notls (i386/freebsd)).
Content-Length: 0.
.

#


More information about the Users mailing list