[Jul  5 17:01:22] Asterisk SVN-trunk-r393757 built by root @ rnewton-office-lab on a i686 running Linux on 2013-07-05 20:38:29 UTC
[Jul  5 17:01:22] VERBOSE[30065] config.c:   == Parsing '/etc/asterisk/logger.conf': Found
[Jul  5 17:01:22] VERBOSE[30065] logger.c:  Asterisk Queue Logger restarted
[Jul  5 17:01:27] VERBOSE[30039] res_sip_logger.c: <--- Received SIP request (1070 bytes) from UDP:10.24.18.138:5060 --->
INVITE sip:6001@10.24.17.228 SIP/2.0
Via: SIP/2.0/UDP 10.24.18.138:5060;rport;branch=z9hG4bKPjwH5Clk3JKEpaajiBRGs8S4f21y6MwyqN
Max-Forwards: 70
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=29l9hOt.-zvAnW5haM04RS3LK2RMc0xw
To: <sip:6001@10.24.17.228>
Contact: "RustyTWO" <sip:6002@10.24.18.138:5060;ob>
Call-ID: tb.TMo0qrO1WDGrlZrDEX90PMWgAkgpr
CSeq: 5818 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
User-Agent: Digium D40 1_3_2_0_54993
Content-Type: application/sdp
Content-Length:   431

v=0
o=- 68432978 68432978 IN IP4 10.24.18.138
s=digphn
c=IN IP4 10.24.18.138
t=0 0
a=X-nat:0
m=audio 4050 RTP/AVP 0 8 9 111 18 58 118 58 96
a=rtcp:4051 IN IP4 10.24.18.138
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:58 L16/16000
a=rtpmap:118 L16/8000
a=rtpmap:58 L16-256/16000
a=sendrecv
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-15

[Jul  5 17:01:27] VERBOSE[30042] res_sip_logger.c: <--- Transmitting SIP response (524 bytes) to UDP:10.24.18.138:5060 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.24.18.138:5060;rport;received=10.24.18.138;branch=z9hG4bKPjwH5Clk3JKEpaajiBRGs8S4f21y6MwyqN
Call-ID: tb.TMo0qrO1WDGrlZrDEX90PMWgAkgpr
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=29l9hOt.-zvAnW5haM04RS3LK2RMc0xw
To: <sip:6001@10.24.17.228>;tag=z9hG4bKPjwH5Clk3JKEpaajiBRGs8S4f21y6MwyqN
CSeq: 5818 INVITE
WWW-Authenticate: Digest  realm="asterisk",nonce="1373061687/c2aaf074540d5b47f7c6e3998db62639",opaque="2de0df1007c697fa",algorithm=md5,qop="auth"
Content-Length:  0


[Jul  5 17:01:27] VERBOSE[30039] res_sip_logger.c: <--- Received SIP request (379 bytes) from UDP:10.24.18.138:5060 --->
ACK sip:6001@10.24.17.228 SIP/2.0
Via: SIP/2.0/UDP 10.24.18.138:5060;rport;branch=z9hG4bKPjwH5Clk3JKEpaajiBRGs8S4f21y6MwyqN
Max-Forwards: 70
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=29l9hOt.-zvAnW5haM04RS3LK2RMc0xw
To: <sip:6001@10.24.17.228>;tag=z9hG4bKPjwH5Clk3JKEpaajiBRGs8S4f21y6MwyqN
Call-ID: tb.TMo0qrO1WDGrlZrDEX90PMWgAkgpr
CSeq: 5818 ACK
Content-Length:  0


[Jul  5 17:01:27] VERBOSE[30039] res_sip_logger.c: <--- Received SIP request (1362 bytes) from UDP:10.24.18.138:5060 --->
INVITE sip:6001@10.24.17.228 SIP/2.0
Via: SIP/2.0/UDP 10.24.18.138:5060;rport;branch=z9hG4bKPjC6XU6DhWUhYRzVzfNf5I2HvMWbddFl5B
Max-Forwards: 70
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=29l9hOt.-zvAnW5haM04RS3LK2RMc0xw
To: <sip:6001@10.24.17.228>
Contact: "RustyTWO" <sip:6002@10.24.18.138:5060;ob>
Call-ID: tb.TMo0qrO1WDGrlZrDEX90PMWgAkgpr
CSeq: 5819 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
User-Agent: Digium D40 1_3_2_0_54993
Authorization: Digest username="6002", realm="asterisk", nonce="1373061687/c2aaf074540d5b47f7c6e3998db62639", uri="sip:6001@10.24.17.228", response="08037fe7bcd428a163ef1a9fbbad81de", algorithm=md5, cnonce="MsYanZLb2b8IKdUFDBaPd94OLHEKPvyp", opaque="2de0df1007c697fa", qop=auth, nc=00000001
Content-Type: application/sdp
Content-Length:   431

v=0
o=- 68432978 68432978 IN IP4 10.24.18.138
s=digphn
c=IN IP4 10.24.18.138
t=0 0
a=X-nat:0
m=audio 4050 RTP/AVP 0 8 9 111 18 58 118 58 96
a=rtcp:4051 IN IP4 10.24.18.138
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:58 L16/16000
a=rtpmap:118 L16/8000
a=rtpmap:58 L16-256/16000
a=sendrecv
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-15

[Jul  5 17:01:27] VERBOSE[30042] res_sip_logger.c: <--- Transmitting SIP response (325 bytes) to UDP:10.24.18.138:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.24.18.138:5060;rport;received=10.24.18.138;branch=z9hG4bKPjC6XU6DhWUhYRzVzfNf5I2HvMWbddFl5B
Call-ID: tb.TMo0qrO1WDGrlZrDEX90PMWgAkgpr
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=29l9hOt.-zvAnW5haM04RS3LK2RMc0xw
To: <sip:6001@10.24.17.228>
CSeq: 5819 INVITE
Content-Length:  0


[Jul  5 17:01:27] VERBOSE[30076][C-00000002] pbx.c:     -- Executing [6001@otherinternal:1] Dial("Gulp/6002-00000004", "GULP/6001") in new stack
[Jul  5 17:01:27] VERBOSE[30042] res_sip_logger.c: <--- Transmitting SIP request (907 bytes) to UDP:10.24.18.16:5060 --->
INVITE sip:6001@10.24.18.16:5060 SIP/2.0
Via: SIP/2.0/UDP 10.24.17.228:5060;rport;branch=z9hG4bKPj5c88cb83-340d-402f-aabd-c3980507293e
Max-Forwards: 70
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=a9abd28a-48f3-4c4e-b332-8db5decb0ea0
To: sip:6001@10.24.18.16
Contact: <sip:926a5300-6015-42c3-b3ac-e0fe38f2e8fd@10.24.17.228:5060>
Call-ID: 71fdc0fa-22c9-4647-9961-16c6392b9194
CSeq: 28328 INVITE
Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER, REGISTER
Supported: 100rel, timer, replaces, norefersub
Session-Expires: 1800
Min-SE: 90
Content-Type: application/sdp
Content-Length:   255

v=0
o=Asterisk 575022841 575022841 IN IP4 rnewton-office-lab
s=Asterisk
c=IN IP4 10.24.17.228
t=0 0
m=audio 26078 RTP/AVP 0 101
c=IN IP4 10.24.17.228
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

[Jul  5 17:01:27] VERBOSE[30076][C-00000002] app_dial.c:     -- Called GULP/6001
[Jul  5 17:01:27] VERBOSE[30039] res_sip_logger.c: <--- Received SIP response (342 bytes) from UDP:10.24.18.16:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.24.17.228:5060;rport=5060;received=10.24.17.228;branch=z9hG4bKPj5c88cb83-340d-402f-aabd-c3980507293e
Call-ID: 71fdc0fa-22c9-4647-9961-16c6392b9194
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=a9abd28a-48f3-4c4e-b332-8db5decb0ea0
To: <sip:6001@10.24.18.16>
CSeq: 28328 INVITE
Content-Length:  0


[Jul  5 17:01:27] VERBOSE[30039] res_sip_logger.c: <--- Received SIP response (559 bytes) from UDP:10.24.18.16:5060 --->
SIP/2.0 180 ringing
Via: SIP/2.0/UDP 10.24.17.228:5060;rport=5060;received=10.24.17.228;branch=z9hG4bKPj5c88cb83-340d-402f-aabd-c3980507293e
Call-ID: 71fdc0fa-22c9-4647-9961-16c6392b9194
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=a9abd28a-48f3-4c4e-b332-8db5decb0ea0
To: <sip:6001@10.24.18.16>;tag=yS8CXheWjK0JpltGonZRPVDN9cfwFrcY
CSeq: 28328 INVITE
Contact: "RustyONE" <sip:6001@10.24.18.16:5060>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
User-Agent: Digium D40 1_3_2_0_54993
Content-Length:  0


[Jul  5 17:01:27] VERBOSE[30076][C-00000002] app_dial.c:     -- Gulp/6001-00000005 is ringing
[Jul  5 17:01:27] VERBOSE[30042] res_sip_logger.c: <--- Transmitting SIP response (512 bytes) to UDP:10.24.18.138:5060 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 10.24.18.138:5060;rport;received=10.24.18.138;branch=z9hG4bKPjC6XU6DhWUhYRzVzfNf5I2HvMWbddFl5B
Call-ID: tb.TMo0qrO1WDGrlZrDEX90PMWgAkgpr
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=29l9hOt.-zvAnW5haM04RS3LK2RMc0xw
To: <sip:6001@10.24.17.228>;tag=86e8ac0c-ae7b-4a4c-84d2-59f7623f96fd
CSeq: 5819 INVITE
Contact: <sip:6001@10.24.17.228>
Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER, REGISTER
Content-Length:  0


[Jul  5 17:01:30] VERBOSE[30039] res_sip_logger.c: <--- Received SIP request (377 bytes) from UDP:10.24.18.138:5060 --->
CANCEL sip:6001@10.24.17.228 SIP/2.0
Via: SIP/2.0/UDP 10.24.18.138:5060;rport;branch=z9hG4bKPjC6XU6DhWUhYRzVzfNf5I2HvMWbddFl5B
Max-Forwards: 70
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=29l9hOt.-zvAnW5haM04RS3LK2RMc0xw
To: <sip:6001@10.24.17.228>
Call-ID: tb.TMo0qrO1WDGrlZrDEX90PMWgAkgpr
CSeq: 5819 CANCEL
User-Agent: Digium D40 1_3_2_0_54993
Content-Length:  0


[Jul  5 17:01:30] VERBOSE[30042] res_sip_logger.c: <--- Transmitting SIP response (362 bytes) to UDP:10.24.18.138:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.24.18.138:5060;rport;received=10.24.18.138;branch=z9hG4bKPjC6XU6DhWUhYRzVzfNf5I2HvMWbddFl5B
Call-ID: tb.TMo0qrO1WDGrlZrDEX90PMWgAkgpr
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=29l9hOt.-zvAnW5haM04RS3LK2RMc0xw
To: <sip:6001@10.24.17.228>;tag=86e8ac0c-ae7b-4a4c-84d2-59f7623f96fd
CSeq: 5819 CANCEL
Content-Length:  0


[Jul  5 17:01:30] VERBOSE[30042] res_sip_logger.c: <--- Transmitting SIP response (489 bytes) to UDP:10.24.18.138:5060 --->
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 10.24.18.138:5060;rport;received=10.24.18.138;branch=z9hG4bKPjC6XU6DhWUhYRzVzfNf5I2HvMWbddFl5B
Call-ID: tb.TMo0qrO1WDGrlZrDEX90PMWgAkgpr
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=29l9hOt.-zvAnW5haM04RS3LK2RMc0xw
To: <sip:6001@10.24.17.228>;tag=86e8ac0c-ae7b-4a4c-84d2-59f7623f96fd
CSeq: 5819 INVITE
Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER, REGISTER
Content-Length:  0


[Jul  5 17:01:30] VERBOSE[30039] res_sip_logger.c: <--- Received SIP request (374 bytes) from UDP:10.24.18.138:5060 --->
ACK sip:6001@10.24.17.228 SIP/2.0
Via: SIP/2.0/UDP 10.24.18.138:5060;rport;branch=z9hG4bKPjC6XU6DhWUhYRzVzfNf5I2HvMWbddFl5B
Max-Forwards: 70
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=29l9hOt.-zvAnW5haM04RS3LK2RMc0xw
To: <sip:6001@10.24.17.228>;tag=86e8ac0c-ae7b-4a4c-84d2-59f7623f96fd
Call-ID: tb.TMo0qrO1WDGrlZrDEX90PMWgAkgpr
CSeq: 5819 ACK
Content-Length:  0


[Jul  5 17:01:30] VERBOSE[30042] res_sip_logger.c: <--- Transmitting SIP request (376 bytes) to UDP:10.24.18.16:5060 --->
CANCEL sip:6001@10.24.18.16:5060 SIP/2.0
Via: SIP/2.0/UDP 10.24.17.228:5060;rport;branch=z9hG4bKPj5c88cb83-340d-402f-aabd-c3980507293e
Max-Forwards: 70
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=a9abd28a-48f3-4c4e-b332-8db5decb0ea0
To: sip:6001@10.24.18.16
Call-ID: 71fdc0fa-22c9-4647-9961-16c6392b9194
CSeq: 28328 CANCEL
Reason: Q.850;cause=0
Content-Length:  0


[Jul  5 17:01:30] VERBOSE[30039] res_sip_logger.c: <--- Received SIP response (375 bytes) from UDP:10.24.18.16:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.24.17.228:5060;rport=5060;received=10.24.17.228;branch=z9hG4bKPj5c88cb83-340d-402f-aabd-c3980507293e
Call-ID: 71fdc0fa-22c9-4647-9961-16c6392b9194
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=a9abd28a-48f3-4c4e-b332-8db5decb0ea0
To: <sip:6001@10.24.18.16>;tag=yS8CXheWjK0JpltGonZRPVDN9cfwFrcY
CSeq: 28328 CANCEL
Content-Length:  0


[Jul  5 17:01:30] VERBOSE[30039] res_sip_logger.c: <--- Received SIP response (521 bytes) from UDP:10.24.18.16:5060 --->
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 10.24.17.228:5060;rport=5060;received=10.24.17.228;branch=z9hG4bKPj5c88cb83-340d-402f-aabd-c3980507293e
Call-ID: 71fdc0fa-22c9-4647-9961-16c6392b9194
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=a9abd28a-48f3-4c4e-b332-8db5decb0ea0
To: <sip:6001@10.24.18.16>;tag=yS8CXheWjK0JpltGonZRPVDN9cfwFrcY
CSeq: 28328 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
User-Agent: Digium D40 1_3_2_0_54993
Content-Length:  0


[Jul  5 17:01:30] VERBOSE[30042] res_sip_logger.c: <--- Transmitting SIP request (384 bytes) to UDP:10.24.18.16:5060 --->
ACK sip:6001@10.24.18.16:5060 SIP/2.0
Via: SIP/2.0/UDP 10.24.17.228:5060;rport;branch=z9hG4bKPj5c88cb83-340d-402f-aabd-c3980507293e
Max-Forwards: 70
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=a9abd28a-48f3-4c4e-b332-8db5decb0ea0
To: sip:6001@10.24.18.16;tag=yS8CXheWjK0JpltGonZRPVDN9cfwFrcY
Call-ID: 71fdc0fa-22c9-4647-9961-16c6392b9194
CSeq: 28328 ACK
Content-Length:  0


[Jul  5 17:01:30] VERBOSE[30076][C-00000002] pbx.c:   == Spawn extension (otherinternal, 6001, 1) exited non-zero on 'Gulp/6002-00000004'