[May  4 14:20:58] VERBOSE[30265] logger.c: Asterisk Event Logger restarted

[May  4 14:20:58] VERBOSE[30265] logger.c: Asterisk Queue Logger restarted

[May  4 14:20:59] VERBOSE[30265] logger.c:     -- Remote UNIX connection disconnected

[May  4 14:21:01] VERBOSE[11106] logger.c:     -- Remote UNIX connection

[May  4 14:21:02] VERBOSE[11312] logger.c: Reliably Transmitting (NAT) to 2xx.xxx.xxx.x11:5060:



<------------->

[May  4 14:21:02] VERBOSE[11312] logger.c: --- (9 headers 0 lines) ---

[May  4 14:21:02] VERBOSE[11312] logger.c: Really destroying SIP dialog '65aeb47e4762c41772cbdcab5f409702@openser2.mydomain.org' Method: OPTIONS

[May  4 14:21:06] VERBOSE[11312] logger.c: 

<--- SIP read from 2xx.xxx.xxx.x11:5060 --->

INVITE sip:902@asterisk2.mydomain.org SIP/2.0

Record-Route: <sip:2xx.xxx.xxx.x11;lr=on;ftag=2640869251>

Via: SIP/2.0/UDP 2xx.xxx.xxx.x11;branch=z9hG4bK18a5.a60e82.0

Via: SIP/2.0/UDP 10.1.99.83:5779;received=81.223.28.196;branch=z9hG4bKa9e95fe1f9bb906cd20a1218e5222f55;rport=5779

From: 804 <sip:john3@openser2.mydomain.org>;tag=2640869251

To: <sip:902@openser2.mydomain.org>

Call-ID: 46231787@10_1_99_83

CSeq: 696928839 INVITE

Contact: "John Doe the 3rd" <sip:john3@81.223.28.196:5779>

Max-Forwards: 69

User-Agent: C450 IP010610000000

Supported: 

Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO

Content-Type: application/sdp

Content-Length: 387



v=0

o=john3 8732 32 IN IP4 10.1.99.83

s=-

c=IN IP4 2xx.xxx.xxx.x11

t=0 0

m=audio 35050 RTP/AVP 18 96 97 2 8 0 101

a=rtpmap:18 G729/8000

a=rtpmap:96 G726-32/8000

a=rtpmap:97 AAL2-G726-32/8000

a=rtpmap:2 G726-32/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=direction:active

a=direction:active

a=nortpproxy:yes



<------------->

[May  4 14:21:06] VERBOSE[11312] logger.c: --- (15 headers 17 lines) ---

[May  4 14:21:06] VERBOSE[11312] logger.c: Sending to 2xx.xxx.xxx.x11 : 5060 (no NAT)

[May  4 14:21:06] VERBOSE[11312] logger.c: Using INVITE request as basis request - 46231787@10_1_99_83

[May  4 14:21:06] VERBOSE[11312] logger.c: Found peer '2xx.xxx.xxx.x11'

[May  4 14:21:06] VERBOSE[11312] logger.c: Found RTP audio format 18

[May  4 14:21:06] VERBOSE[11312] logger.c: Found RTP audio format 96

[May  4 14:21:06] VERBOSE[11312] logger.c: Found RTP audio format 97

[May  4 14:21:06] VERBOSE[11312] logger.c: Found RTP audio format 2

[May  4 14:21:06] VERBOSE[11312] logger.c: Found RTP audio format 8

[May  4 14:21:06] VERBOSE[11312] logger.c: Found RTP audio format 0

[May  4 14:21:06] VERBOSE[11312] logger.c: Found RTP audio format 101

[May  4 14:21:06] VERBOSE[11312] logger.c: Peer audio RTP is at port 2xx.xxx.xxx.x11:35050

[May  4 14:21:06] VERBOSE[11312] logger.c: Found description format G729 for ID 18

[May  4 14:21:06] VERBOSE[11312] logger.c: Found description format G726-32 for ID 96

[May  4 14:21:06] VERBOSE[11312] logger.c: Found description format AAL2-G726-32 for ID 97

[May  4 14:21:06] VERBOSE[11312] logger.c: Found description format G726-32 for ID 2

[May  4 14:21:06] VERBOSE[11312] logger.c: Found description format PCMA for ID 8

[May  4 14:21:06] VERBOSE[11312] logger.c: Found description format PCMU for ID 0

[May  4 14:21:06] VERBOSE[11312] logger.c: Found description format telephone-event for ID 101

[May  4 14:21:06] VERBOSE[11312] logger.c: Capabilities: us - 0x10c (ulaw|alaw|g729), peer - audio=0x91c (ulaw|alaw|g726|g729|g726aal2)/video=0x0 (nothing), combined - 0x10c (ulaw|alaw|g729)

[May  4 14:21:06] VERBOSE[11312] logger.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event)

[May  4 14:21:06] VERBOSE[11312] logger.c: Peer audio RTP is at port 2xx.xxx.xxx.x11:35050

[May  4 14:21:06] VERBOSE[11312] logger.c: Looking for 902 in from-internal (domain asterisk2.mydomain.org)

[May  4 14:21:06] VERBOSE[11312] logger.c: list_route: hop: <sip:2xx.xxx.xxx.x11;lr=on;ftag=2640869251>

[May  4 14:21:06] VERBOSE[11312] logger.c: 

<--- Transmitting (NAT) to 2xx.xxx.xxx.x11:5060 --->

SIP/2.0 100 Trying

Via: SIP/2.0/UDP 2xx.xxx.xxx.x11;branch=z9hG4bK18a5.a60e82.0;received=2xx.xxx.xxx.x11

Via: SIP/2.0/UDP 10.1.99.83:5779;received=81.223.28.196;branch=z9hG4bKa9e95fe1f9bb906cd20a1218e5222f55;rport=5779

From: 804 <sip:john3@openser2.mydomain.org>;tag=2640869251

To: <sip:902@openser2.mydomain.org>

Call-ID: 46231787@10_1_99_83

CSeq: 696928839 INVITE

User-Agent: mydomain asterisk

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Supported: replaces

Contact: <sip:902@2xx.xxx.xxx.x12>

Content-Length: 0





<------------>

[May  4 14:21:06] VERBOSE[30277] logger.c:     -- Executing [902@asteriskfunctions:1] Answer("SIP/openser2.mydomain.org-b357bb60", "") in new stack

[May  4 14:21:06] VERBOSE[30277] logger.c: Audio is at 2xx.xxx.xxx.x12 port 10122

[May  4 14:21:06] VERBOSE[30277] logger.c: Adding codec 0x8 (alaw) to SDP

[May  4 14:21:06] VERBOSE[30277] logger.c: Adding codec 0x4 (ulaw) to SDP

[May  4 14:21:06] VERBOSE[30277] logger.c: Adding codec 0x100 (g729) to SDP

[May  4 14:21:06] VERBOSE[30277] logger.c: Adding non-codec 0x1 (telephone-event) to SDP

[May  4 14:21:06] VERBOSE[30277] logger.c: 

<--- Reliably Transmitting (NAT) to 2xx.xxx.xxx.x11:5060 --->

SIP/2.0 200 OK

Via: SIP/2.0/UDP 2xx.xxx.xxx.x11;branch=z9hG4bK18a5.a60e82.0;received=2xx.xxx.xxx.x11

Via: SIP/2.0/UDP 10.1.99.83:5779;received=81.223.28.196;branch=z9hG4bKa9e95fe1f9bb906cd20a1218e5222f55;rport=5779

Record-Route: <sip:2xx.xxx.xxx.x11;lr=on;ftag=2640869251>

From: 804 <sip:john3@openser2.mydomain.org>;tag=2640869251

To: <sip:902@openser2.mydomain.org>;tag=as0e7ba72f

Call-ID: 46231787@10_1_99_83

CSeq: 696928839 INVITE

User-Agent: mydomain asterisk

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Supported: replaces

Contact: <sip:902@2xx.xxx.xxx.x12>

Content-Type: application/sdp

Content-Length: 311



v=0

o=root 11103 11103 IN IP4 2xx.xxx.xxx.x12

s=session

c=IN IP4 2xx.xxx.xxx.x12

t=0 0

m=audio 10122 RTP/AVP 8 0 18 101

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

a=sendrecv



<------------>

[May  4 14:21:06] VERBOSE[30277] logger.c:     -- Executing [902@asteriskfunctions:2] SetMusicOnHold("SIP/openser2.mydomain.org-b357bb60", "mymoh_hold") in new stack

[May  4 14:21:06] VERBOSE[30277] logger.c:     -- Executing [902@asteriskfunctions:3] Dial("SIP/openser2.mydomain.org-b357bb60", "SIP/nancy@openser2.mydomain.org|30|gm(mymoh_ringing)") in new stack

[May  4 14:21:06] VERBOSE[30277] logger.c:     -- parse_srv: SRV mapped to host openser2.mydomain.org, port 5060

[May  4 14:21:06] VERBOSE[30277] logger.c: Audio is at 2xx.xxx.xxx.x12 port 13378

[May  4 14:21:06] VERBOSE[11312] logger.c: Retransmitting #1 (NAT) to 2xx.xxx.xxx.x11:5060:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 2xx.xxx.xxx.x11;branch=z9hG4bK18a5.a60e82.0;received=2xx.xxx.xxx.x11

Via: SIP/2.0/UDP 10.1.99.83:5779;received=81.223.28.196;branch=z9hG4bKa9e95fe1f9bb906cd20a1218e5222f55;rport=5779

Record-Route: <sip:2xx.xxx.xxx.x11;lr=on;ftag=2640869251>

From: 804 <sip:john3@openser2.mydomain.org>;tag=2640869251

To: <sip:902@openser2.mydomain.org>;tag=as0e7ba72f

Call-ID: 46231787@10_1_99_83

CSeq: 696928839 INVITE

User-Agent: mydomain asterisk

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Supported: replaces

Contact: <sip:902@2xx.xxx.xxx.x12>

Content-Type: application/sdp

Content-Length: 311



v=0

o=root 11103 11103 IN IP4 2xx.xxx.xxx.x12

s=session

c=IN IP4 2xx.xxx.xxx.x12

t=0 0

m=audio 10122 RTP/AVP 8 0 18 101

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

a=sendrecv



---

[May  4 14:21:06] VERBOSE[30277] logger.c: Adding codec 0x8 (alaw) to SDP

[May  4 14:21:06] VERBOSE[30277] logger.c: Adding codec 0x4 (ulaw) to SDP

[May  4 14:21:06] VERBOSE[30277] logger.c: Adding codec 0x100 (g729) to SDP

[May  4 14:21:06] VERBOSE[30277] logger.c: Adding non-codec 0x1 (telephone-event) to SDP

[May  4 14:21:06] VERBOSE[30277] logger.c: Reliably Transmitting (no NAT) to 2xx.xxx.xxx.xx1:5060:

INVITE sip:nancy@openser2.mydomain.org SIP/2.0

Via: SIP/2.0/UDP 2xx.xxx.xxx.x12:5060;branch=z9hG4bK7e52153a;rport

From: "John Doe the 3rd" <sip:john3@openser2.mydomain.org>;tag=as278c732f

To: <sip:nancy@openser2.mydomain.org>

Contact: <sip:john3@2xx.xxx.xxx.x12>

Call-ID: 7bafdb2117254d3731770b64476c80b0@openser2.mydomain.org

CSeq: 102 INVITE

User-Agent: mydomain asterisk

Max-Forwards: 70

Date: Fri, 04 May 2007 12:21:06 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Supported: replaces

Content-Type: application/sdp

Content-Length: 311



v=0

o=root 11103 11103 IN IP4 2xx.xxx.xxx.x12

s=session

c=IN IP4 2xx.xxx.xxx.x12

t=0 0

m=audio 13378 RTP/AVP 8 0 18 101

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

a=sendrecv



---

[May  4 14:21:06] VERBOSE[30277] logger.c:     -- Called nancy@openser2.mydomain.org

[May  4 14:21:06] VERBOSE[30277] logger.c:     -- Started music on hold, class 'mymoh_ringing', on SIP/openser2.mydomain.org-b357bb60

[May  4 14:21:06] VERBOSE[11312] logger.c: 

<--- SIP read from 2xx.xxx.xxx.xx1:5060 --->

SIP/2.0 100 trying -- your call is important to us

Via: SIP/2.0/UDP 2xx.xxx.xxx.x12:5060;branch=z9hG4bK7e52153a;rport=5060

From: "John Doe the 3rd" <sip:john3@openser2.mydomain.org>;tag=as278c732f

To: <sip:nancy@openser2.mydomain.org>

Call-ID: 7bafdb2117254d3731770b64476c80b0@openser2.mydomain.org

CSeq: 102 INVITE

Server: OpenSer (1.1.0-tls (i386/linux))

Content-Length: 0

Warning: 392 2xx.xxx.xxx.xx1:5060 "Noisy feedback tells:  pid=4081 req_src_ip=2xx.xxx.xxx.x12 req_src_port=5060 in_uri=sip:nancy@openser2.mydomain.org out_uri=sip:nancy@10.1.99.172:5060 via_cnt==1"





<------------->

[May  4 14:21:06] VERBOSE[11312] logger.c: --- (9 headers 0 lines) ---

[May  4 14:21:06] VERBOSE[11312] logger.c: Retransmitting #2 (NAT) to 2xx.xxx.xxx.x11:5060:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 2xx.xxx.xxx.x11;branch=z9hG4bK18a5.a60e82.0;received=2xx.xxx.xxx.x11

Via: SIP/2.0/UDP 10.1.99.83:5779;received=81.223.28.196;branch=z9hG4bKa9e95fe1f9bb906cd20a1218e5222f55;rport=5779

Record-Route: <sip:2xx.xxx.xxx.x11;lr=on;ftag=2640869251>

From: 804 <sip:john3@openser2.mydomain.org>;tag=2640869251

To: <sip:902@openser2.mydomain.org>;tag=as0e7ba72f

Call-ID: 46231787@10_1_99_83

CSeq: 696928839 INVITE

User-Agent: mydomain asterisk

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Supported: replaces

Contact: <sip:902@2xx.xxx.xxx.x12>

Content-Type: application/sdp

Content-Length: 311



v=0

o=root 11103 11103 IN IP4 2xx.xxx.xxx.x12

s=session

c=IN IP4 2xx.xxx.xxx.x12

t=0 0

m=audio 10122 RTP/AVP 8 0 18 101

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

a=sendrecv



---

[May  4 14:21:06] VERBOSE[11312] logger.c: Retransmitting #3 (NAT) to 2xx.xxx.xxx.x11:5060:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 2xx.xxx.xxx.x11;branch=z9hG4bK18a5.a60e82.0;received=2xx.xxx.xxx.x11

Via: SIP/2.0/UDP 10.1.99.83:5779;received=81.223.28.196;branch=z9hG4bKa9e95fe1f9bb906cd20a1218e5222f55;rport=5779

Record-Route: <sip:2xx.xxx.xxx.x11;lr=on;ftag=2640869251>

From: 804 <sip:john3@openser2.mydomain.org>;tag=2640869251

To: <sip:902@openser2.mydomain.org>;tag=as0e7ba72f

Call-ID: 46231787@10_1_99_83

CSeq: 696928839 INVITE

User-Agent: mydomain asterisk

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Supported: replaces

Contact: <sip:902@2xx.xxx.xxx.x12>

Content-Type: application/sdp

Content-Length: 311



v=0

o=root 11103 11103 IN IP4 2xx.xxx.xxx.x12

s=session

c=IN IP4 2xx.xxx.xxx.x12

t=0 0

m=audio 10122 RTP/AVP 8 0 18 101

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

a=sendrecv



---

[May  4 14:21:06] VERBOSE[11312] logger.c: Retransmitting #4 (NAT) to 2xx.xxx.xxx.x11:5060:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 2xx.xxx.xxx.x11;branch=z9hG4bK18a5.a60e82.0;received=2xx.xxx.xxx.x11

Via: SIP/2.0/UDP 10.1.99.83:5779;received=81.223.28.196;branch=z9hG4bKa9e95fe1f9bb906cd20a1218e5222f55;rport=5779

Record-Route: <sip:2xx.xxx.xxx.x11;lr=on;ftag=2640869251>

From: 804 <sip:john3@openser2.mydomain.org>;tag=2640869251

To: <sip:902@openser2.mydomain.org>;tag=as0e7ba72f

Call-ID: 46231787@10_1_99_83

CSeq: 696928839 INVITE

User-Agent: mydomain asterisk

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Supported: replaces

Contact: <sip:902@2xx.xxx.xxx.x12>

Content-Type: application/sdp

Content-Length: 311



v=0

o=root 11103 11103 IN IP4 2xx.xxx.xxx.x12

s=session

c=IN IP4 2xx.xxx.xxx.x12

t=0 0

m=audio 10122 RTP/AVP 8 0 18 101

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

a=sendrecv



---

[May  4 14:21:06] VERBOSE[11312] logger.c: Retransmitting #5 (NAT) to 2xx.xxx.xxx.x11:5060:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 2xx.xxx.xxx.x11;branch=z9hG4bK18a5.a60e82.0;received=2xx.xxx.xxx.x11

Via: SIP/2.0/UDP 10.1.99.83:5779;received=81.223.28.196;branch=z9hG4bKa9e95fe1f9bb906cd20a1218e5222f55;rport=5779

Record-Route: <sip:2xx.xxx.xxx.x11;lr=on;ftag=2640869251>

From: 804 <sip:john3@openser2.mydomain.org>;tag=2640869251

To: <sip:902@openser2.mydomain.org>;tag=as0e7ba72f

Call-ID: 46231787@10_1_99_83

CSeq: 696928839 INVITE

User-Agent: mydomain asterisk

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Supported: replaces

Contact: <sip:902@2xx.xxx.xxx.x12>

Content-Type: application/sdp

Content-Length: 311



v=0

o=root 11103 11103 IN IP4 2xx.xxx.xxx.x12

s=session

c=IN IP4 2xx.xxx.xxx.x12

t=0 0

m=audio 10122 RTP/AVP 8 0 18 101

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

a=sendrecv



---

[May  4 14:21:06] VERBOSE[11312] logger.c: Retransmitting #6 (NAT) to 2xx.xxx.xxx.x11:5060:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 2xx.xxx.xxx.x11;branch=z9hG4bK18a5.a60e82.0;received=2xx.xxx.xxx.x11

Via: SIP/2.0/UDP 10.1.99.83:5779;received=81.223.28.196;branch=z9hG4bKa9e95fe1f9bb906cd20a1218e5222f55;rport=5779

Record-Route: <sip:2xx.xxx.xxx.x11;lr=on;ftag=2640869251>

From: 804 <sip:john3@openser2.mydomain.org>;tag=2640869251

To: <sip:902@openser2.mydomain.org>;tag=as0e7ba72f

Call-ID: 46231787@10_1_99_83

CSeq: 696928839 INVITE

User-Agent: mydomain asterisk

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Supported: replaces

Contact: <sip:902@2xx.xxx.xxx.x12>

Content-Type: application/sdp

Content-Length: 311



v=0

o=root 11103 11103 IN IP4 2xx.xxx.xxx.x12

s=session

c=IN IP4 2xx.xxx.xxx.x12

t=0 0

m=audio 10122 RTP/AVP 8 0 18 101

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

a=sendrecv



---

[May  4 14:21:06] WARNING[11312] chan_sip.c: Maximum retries exceeded on transmission 46231787@10_1_99_83 for seqno 696928839 (Critical Response)

[May  4 14:21:06] WARNING[11312] chan_sip.c: Hanging up call 46231787@10_1_99_83 - no reply to our critical packet.

[May  4 14:21:06] VERBOSE[30277] logger.c:     -- Stopped music on hold on SIP/openser2.mydomain.org-b357bb60

[May  4 14:21:06] VERBOSE[30277] logger.c: Scheduling destruction of SIP dialog '7bafdb2117254d3731770b64476c80b0@openser2.mydomain.org' in 32000 ms (Method: INVITE)

[May  4 14:21:06] VERBOSE[30277] logger.c: Reliably Transmitting (no NAT) to 2xx.xxx.xxx.xx1:5060:

CANCEL sip:nancy@openser2.mydomain.org SIP/2.0

Via: SIP/2.0/UDP 2xx.xxx.xxx.x12:5060;branch=z9hG4bK7e52153a;rport

From: "John Doe the 3rd" <sip:john3@openser2.mydomain.org>;tag=as278c732f

To: <sip:nancy@openser2.mydomain.org>

Call-ID: 7bafdb2117254d3731770b64476c80b0@openser2.mydomain.org

CSeq: 102 CANCEL

User-Agent: mydomain asterisk

Max-Forwards: 70

Content-Length: 0





---

[May  4 14:21:06] VERBOSE[30277] logger.c: Scheduling destruction of SIP dialog '7bafdb2117254d3731770b64476c80b0@openser2.mydomain.org' in 32000 ms (Method: INVITE)

[May  4 14:21:06] VERBOSE[30277] logger.c:   == Spawn extension (asteriskfunctions, 902, 3) exited non-zero on 'SIP/openser2.mydomain.org-b357bb60'

[May  4 14:21:06] VERBOSE[11312] logger.c: 

<--- SIP read from 2xx.xxx.xxx.xx1:5060 --->

SIP/2.0 200 canceling

Via: SIP/2.0/UDP 2xx.xxx.xxx.x12:5060;branch=z9hG4bK7e52153a;rport=5060

From: "John Doe the 3rd" <sip:john3@openser2.mydomain.org>;tag=as278c732f

To: <sip:nancy@openser2.mydomain.org>;tag=270654b62db510c3bf80508de89c4940-9a92

Call-ID: 7bafdb2117254d3731770b64476c80b0@openser2.mydomain.org

CSeq: 102 CANCEL

Server: OpenSer (1.1.0-tls (i386/linux))

Content-Length: 0

Warning: 392 2xx.xxx.xxx.xx1:5060 "Noisy feedback tells:  pid=4081 req_src_ip=2xx.xxx.xxx.x12 req_src_port=5060 in_uri=sip:nancy@openser2.mydomain.org out_uri=sip:nancy@openser2.mydomain.org via_cnt==1"





<------------->

[May  4 14:21:06] VERBOSE[11312] logger.c: --- (9 headers 0 lines) ---

[May  4 14:21:06] VERBOSE[11312] logger.c: Really destroying SIP dialog '46231787@10_1_99_83' Method: INVITE

[May  4 14:21:06] VERBOSE[11312] logger.c: 

<--- SIP read from 2xx.xxx.xxx.xx1:5060 --->

SIP/2.0 487 Request Cancelled

Via: SIP/2.0/UDP 2xx.xxx.xxx.x12:5060;branch=z9hG4bK7e52153a;rport=5060

From: "John Doe the 3rd" <sip:john3@openser2.mydomain.org>;tag=as278c732f

To: <sip:nancy@openser2.mydomain.org>;tag=75F55B09-5D3D77F2

CSeq: 102 INVITE

Call-ID: 7bafdb2117254d3731770b64476c80b0@openser2.mydomain.org

Contact: <sip:nancy@81.223.28.196:16878>

Record-Route: <sip:2xx.xxx.xxx.xx1;lr=on;ftag=as278c732f>

User-Agent: PolycomSoundPointIP-SPIP_601-UA/2.1.0.2708

Content-Length: 0





<------------->

[May  4 14:21:06] VERBOSE[11312] logger.c: --- (10 headers 0 lines) ---

[May  4 14:21:06] VERBOSE[11312] logger.c: Transmitting (no NAT) to 2xx.xxx.xxx.xx1:5060:

ACK sip:nancy@openser2.mydomain.org SIP/2.0

Via: SIP/2.0/UDP 2xx.xxx.xxx.x12:5060;branch=z9hG4bK7e52153a;rport

From: "John Doe the 3rd" <sip:john3@openser2.mydomain.org>;tag=as278c732f

To: <sip:nancy@openser2.mydomain.org>;tag=75F55B09-5D3D77F2

Contact: <sip:john3@2xx.xxx.xxx.x12>

Call-ID: 7bafdb2117254d3731770b64476c80b0@openser2.mydomain.org

CSeq: 102 ACK

User-Agent: mydomain asterisk

Max-Forwards: 70

Content-Length: 0





---

[May  4 14:21:07] VERBOSE[11312] logger.c: 

<--- SIP read from 2xx.xxx.xxx.x11:5060 --->

ACK sip:902@2xx.xxx.xxx.x12 SIP/2.0

Record-Route: <sip:2xx.xxx.xxx.x11;lr=on;ftag=2640869251>

Via: SIP/2.0/UDP 2xx.xxx.xxx.x11;branch=z9hG4bK18a5.a60e82.2

Via: SIP/2.0/UDP 10.1.99.83:5779;received=81.223.28.196;branch=z9hG4bK1d85c086f597d5fe2b6df981f86c3036;rport=5779

From: 804 <sip:john3@openser2.mydomain.org>;tag=2640869251

To: <sip:902@openser2.mydomain.org>;tag=as0e7ba72f

Call-ID: 46231787@10_1_99_83

CSeq: 696928839 ACK

Contact: "John Doe the 3rd" <sip:john3@10.1.99.83:5779>

Proxy-Authorization: Digest username="john3", realm="openser2.mydomain.org", uri="sip:902@openser2.mydomain.org", nonce="463b03098043d4aae15477007c64cc976dd2e8b1", response="a68bd400ef1c0bef847426544061db69"

Max-Forwards: 69

User-Agent: C450 IP010610000000

Content-Length: 0





<------------->

[May  4 14:21:07] VERBOSE[11312] logger.c: --- (13 headers 0 lines) ---

[May  4 14:21:08] VERBOSE[11312] logger.c: 

<--- SIP read from 2xx.xxx.xxx.x11:5060 --->

ACK sip:902@2xx.xxx.xxx.x12 SIP/2.0

Record-Route: <sip:2xx.xxx.xxx.x11;lr=on;ftag=2640869251>

Via: SIP/2.0/UDP 2xx.xxx.xxx.x11;branch=z9hG4bK18a5.a60e82.2

Via: SIP/2.0/UDP 10.1.99.83:5779;received=81.223.28.196;branch=z9hG4bKfe3d728bc7e23c7ff6dddfcd83d741a1;rport=5779

From: 804 <sip:john3@openser2.mydomain.org>;tag=2640869251

To: <sip:902@openser2.mydomain.org>;tag=as0e7ba72f

Call-ID: 46231787@10_1_99_83

CSeq: 696928839 ACK

Contact: "John Doe the 3rd" <sip:john3@10.1.99.83:5779>

Proxy-Authorization: Digest username="john3", realm="openser2.mydomain.org", uri="sip:902@openser2.mydomain.org", nonce="463b03098043d4aae15477007c64cc976dd2e8b1", response="a68bd400ef1c0bef847426544061db69"

Max-Forwards: 69

User-Agent: C450 IP010610000000

Content-Length: 0





<------------->

[May  4 14:21:08] VERBOSE[11312] logger.c: --- (13 headers 0 lines) ---

[May  4 14:21:08] VERBOSE[11312] logger.c: 

<--- SIP read from 2xx.xxx.xxx.x11:5060 --->

ACK sip:902@2xx.xxx.xxx.x12 SIP/2.0

Record-Route: <sip:2xx.xxx.xxx.x11;lr=on;ftag=2640869251>

Via: SIP/2.0/UDP 2xx.xxx.xxx.x11;branch=z9hG4bK18a5.a60e82.2

Via: SIP/2.0/UDP 10.1.99.83:5779;received=81.223.28.196;branch=z9hG4bK306c77f0f6d01ead5f72fa3a66c41706;rport=5779

From: 804 <sip:john3@openser2.mydomain.org>;tag=2640869251

To: <sip:902@openser2.mydomain.org>;tag=as0e7ba72f

Call-ID: 46231787@10_1_99_83

CSeq: 696928839 ACK

Contact: "John Doe the 3rd" <sip:john3@10.1.99.83:5779>

Proxy-Authorization: Digest username="john3", realm="openser2.mydomain.org", uri="sip:902@openser2.mydomain.org", nonce="463b03098043d4aae15477007c64cc976dd2e8b1", response="a68bd400ef1c0bef847426544061db69"

Max-Forwards: 69

User-Agent: C450 IP010610000000

Content-Length: 0





<------------->

[May  4 14:21:08] VERBOSE[11312] logger.c: --- (13 headers 0 lines) ---

[May  4 14:21:08] VERBOSE[11312] logger.c: 

<--- SIP read from 2xx.xxx.xxx.x11:5060 --->

ACK sip:902@2xx.xxx.xxx.x12 SIP/2.0

Record-Route: <sip:2xx.xxx.xxx.x11;lr=on;ftag=2640869251>

Via: SIP/2.0/UDP 2xx.xxx.xxx.x11;branch=z9hG4bK18a5.a60e82.2

Via: SIP/2.0/UDP 10.1.99.83:5779;received=81.223.28.196;branch=z9hG4bKd6bc1446346fc8be4c16ef7c84d103;rport=5779

From: 804 <sip:john3@openser2.mydomain.org>;tag=2640869251

To: <sip:902@openser2.mydomain.org>;tag=as0e7ba72f

Call-ID: 46231787@10_1_99_83

CSeq: 696928839 ACK

Contact: "John Doe the 3rd" <sip:john3@10.1.99.83:5779>

Proxy-Authorization: Digest username="john3", realm="openser2.mydomain.org", uri="sip:902@openser2.mydomain.org", nonce="463b03098043d4aae15477007c64cc976dd2e8b1", response="a68bd400ef1c0bef847426544061db69"

Max-Forwards: 69

User-Agent: C450 IP010610000000

Content-Length: 0





<------------->

[May  4 14:21:08] VERBOSE[11312] logger.c: --- (13 headers 0 lines) ---



<------------->

[May  4 14:21:11] VERBOSE[11312] logger.c: --- (13 headers 0 lines) ---

[May  4 14:21:11] VERBOSE[11312] logger.c: Looking for s in default (domain 2xx.xxx.xxx.x12)



<------------>

[May  4 14:21:11] VERBOSE[11312] logger.c: Scheduling destruction of SIP dialog '4900af4064915e7e0624ead929bafddd@openser2.mydomain.org' in 32000 ms (Method: OPTIONS)

[May  4 14:21:12] VERBOSE[30276] logger.c:     -- Remote UNIX connection disconnected

[May  4 14:21:12] VERBOSE[11312] logger.c: 

<--- SIP read from 2xx.xxx.xxx.x11:5060 --->

BYE sip:902@2xx.xxx.xxx.x12 SIP/2.0

Record-Route: <sip:2xx.xxx.xxx.x11;lr=on;ftag=2640869251>

Via: SIP/2.0/UDP 2xx.xxx.xxx.x11;branch=z9hG4bK67a5.14490da7.0

Via: SIP/2.0/UDP 10.1.99.83:5779;received=81.223.28.196;branch=z9hG4bK4d3c145f90016c343c3383058eeeafbc;rport=5779

From: 804 <sip:john3@openser2.mydomain.org>;tag=2640869251

To: <sip:902@openser2.mydomain.org>;tag=as0e7ba72f

Call-ID: 46231787@10_1_99_83

CSeq: 696928840 BYE

Contact: "John Doe the 3rd" <sip:john3@10.1.99.83:5779>

Proxy-Authorization: Digest username="john3", realm="openser2.mydomain.org", uri="sip:902@2xx.xxx.xxx.x12", nonce="463b03098043d4aae15477007c64cc976dd2e8b1", response="3faae288704b3eff8f3b998ac929b157"

Max-Forwards: 69

User-Agent: C450 IP010610000000

Content-Length: 0





<------------->

[May  4 14:21:12] VERBOSE[11312] logger.c: --- (13 headers 0 lines) ---

[May  4 14:21:12] VERBOSE[11312] logger.c: 

<--- Transmitting (no NAT) to 2xx.xxx.xxx.x11:5060 --->

SIP/2.0 481 Call leg/transaction does not exist

Via: SIP/2.0/UDP 2xx.xxx.xxx.x11;branch=z9hG4bK67a5.14490da7.0;received=2xx.xxx.xxx.x11

Via: SIP/2.0/UDP 10.1.99.83:5779;received=81.223.28.196;branch=z9hG4bK4d3c145f90016c343c3383058eeeafbc;rport=5779

From: 804 <sip:john3@openser2.mydomain.org>;tag=2640869251

To: <sip:902@openser2.mydomain.org>;tag=as0e7ba72f

Call-ID: 46231787@10_1_99_83

CSeq: 696928840 BYE

User-Agent: mydomain asterisk

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Supported: replaces

Content-Length: 0





<------------>

[May  4 14:21:38] VERBOSE[11312] logger.c: Really destroying SIP dialog '7bafdb2117254d3731770b64476c80b0@openser2.mydomain.org' Method: INVITE


