[Jul  5 16:57:36] 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 16:57:36] VERBOSE[29960] config.c:   == Parsing '/etc/asterisk/logger.conf': Found
[Jul  5 16:57:36] VERBOSE[29960] logger.c:  Asterisk Queue Logger restarted
[Jul  5 16:57:51] VERBOSE[29861] res_sip_logger.c: <--- Received SIP request (1071 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=z9hG4bKPjTFZZ7N5stR5vQ51olDP6GUCGCe-icvQH
Max-Forwards: 70
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=koll1ZZ--sGyLbxSReHOI6zD7M9L.vFg
To: <sip:6001@10.24.17.228>
Contact: "RustyTWO" <sip:6002@10.24.18.138:5060;ob>
Call-ID: eLoRkU26GZq2d9d1NPJKC1il5K9wIDDw
CSeq: 23960 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=- 68432763 68432763 IN IP4 10.24.18.138
s=digphn
c=IN IP4 10.24.18.138
t=0 0
a=X-nat:0
m=audio 4044 RTP/AVP 0 8 9 111 18 58 118 58 96
a=rtcp:4045 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 16:57:51] VERBOSE[29961] res_sip_logger.c: <--- Transmitting SIP response (525 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=z9hG4bKPjTFZZ7N5stR5vQ51olDP6GUCGCe-icvQH
Call-ID: eLoRkU26GZq2d9d1NPJKC1il5K9wIDDw
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=koll1ZZ--sGyLbxSReHOI6zD7M9L.vFg
To: <sip:6001@10.24.17.228>;tag=z9hG4bKPjTFZZ7N5stR5vQ51olDP6GUCGCe-icvQH
CSeq: 23960 INVITE
WWW-Authenticate: Digest  realm="asterisk",nonce="1373061471/509a1047f055f88cea07ec0ae7b3d59c",opaque="190c7d3b07508902",algorithm=md5,qop="auth"
Content-Length:  0


[Jul  5 16:57:52] VERBOSE[29861] res_sip_logger.c: <--- Received SIP request (380 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=z9hG4bKPjTFZZ7N5stR5vQ51olDP6GUCGCe-icvQH
Max-Forwards: 70
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=koll1ZZ--sGyLbxSReHOI6zD7M9L.vFg
To: <sip:6001@10.24.17.228>;tag=z9hG4bKPjTFZZ7N5stR5vQ51olDP6GUCGCe-icvQH
Call-ID: eLoRkU26GZq2d9d1NPJKC1il5K9wIDDw
CSeq: 23960 ACK
Content-Length:  0


[Jul  5 16:57:52] VERBOSE[29861] res_sip_logger.c: <--- Received SIP request (1363 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=z9hG4bKPj6OpZQh6Mt-x-mRnCuYd4SUcFyAOA4JWT
Max-Forwards: 70
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=koll1ZZ--sGyLbxSReHOI6zD7M9L.vFg
To: <sip:6001@10.24.17.228>
Contact: "RustyTWO" <sip:6002@10.24.18.138:5060;ob>
Call-ID: eLoRkU26GZq2d9d1NPJKC1il5K9wIDDw
CSeq: 23961 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="1373061471/509a1047f055f88cea07ec0ae7b3d59c", uri="sip:6001@10.24.17.228", response="0ad124cb7fdb9cf5f450d106ae35c53f", algorithm=md5, cnonce="MiGGZqDTnxyCrNsE2sLh8xD3abcU3rkG", opaque="190c7d3b07508902", qop=auth, nc=00000001
Content-Type: application/sdp
Content-Length:   431

v=0
o=- 68432763 68432763 IN IP4 10.24.18.138
s=digphn
c=IN IP4 10.24.18.138
t=0 0
a=X-nat:0
m=audio 4044 RTP/AVP 0 8 9 111 18 58 118 58 96
a=rtcp:4045 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 16:57:52] VERBOSE[29961] res_sip_logger.c: <--- Transmitting SIP response (326 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=z9hG4bKPj6OpZQh6Mt-x-mRnCuYd4SUcFyAOA4JWT
Call-ID: eLoRkU26GZq2d9d1NPJKC1il5K9wIDDw
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=koll1ZZ--sGyLbxSReHOI6zD7M9L.vFg
To: <sip:6001@10.24.17.228>
CSeq: 23961 INVITE
Content-Length:  0


[Jul  5 16:57:52] VERBOSE[29970][C-00000005] pbx.c:     -- Executing [6001@otherinternal:1] Dial("Gulp/6002-0000000a", "GULP/6001") in new stack
[Jul  5 16:57:52] VERBOSE[29961] res_sip_logger.c: <--- Transmitting SIP request (908 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=z9hG4bKPjdb67dfbf-689c-4205-b857-21c8bd1eecfc
Max-Forwards: 70
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=3a8e7c4a-6084-48bf-9d31-da7d8dcd24df
To: sip:6001@10.24.18.16
Contact: <sip:9b68643a-ed00-4c88-862b-f5fc9f86abb6@10.24.17.228:5060>
Call-ID: 0a911c07-a2b8-460e-a796-d552662b2e80
CSeq: 19290 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:   256

v=0
o=Asterisk 1663479326 1663479326 IN IP4 rnewton-office-lab
s=Asterisk
c=IN IP4 10.24.17.228
t=0 0
m=audio 7392 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 16:57:52] VERBOSE[29861] 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=z9hG4bKPjdb67dfbf-689c-4205-b857-21c8bd1eecfc
Call-ID: 0a911c07-a2b8-460e-a796-d552662b2e80
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=3a8e7c4a-6084-48bf-9d31-da7d8dcd24df
To: <sip:6001@10.24.18.16>
CSeq: 19290 INVITE
Content-Length:  0


[Jul  5 16:57:52] VERBOSE[29970][C-00000005] app_dial.c:     -- Called GULP/6001
[Jul  5 16:57:52] VERBOSE[29861] 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=z9hG4bKPjdb67dfbf-689c-4205-b857-21c8bd1eecfc
Call-ID: 0a911c07-a2b8-460e-a796-d552662b2e80
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=3a8e7c4a-6084-48bf-9d31-da7d8dcd24df
To: <sip:6001@10.24.18.16>;tag=q5F7p3LU5LFXYNc8-TK9us6O5ZX0heeF
CSeq: 19290 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 16:57:52] VERBOSE[29970][C-00000005] app_dial.c:     -- Gulp/6001-0000000b is ringing
[Jul  5 16:57:52] VERBOSE[29962] res_sip_logger.c: <--- Transmitting SIP response (513 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=z9hG4bKPj6OpZQh6Mt-x-mRnCuYd4SUcFyAOA4JWT
Call-ID: eLoRkU26GZq2d9d1NPJKC1il5K9wIDDw
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=koll1ZZ--sGyLbxSReHOI6zD7M9L.vFg
To: <sip:6001@10.24.17.228>;tag=362f3f3d-7110-4577-99c3-1ff199c2e0f9
CSeq: 23961 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 16:57:54] VERBOSE[29861] res_sip_logger.c: <--- Received SIP request (378 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=z9hG4bKPj6OpZQh6Mt-x-mRnCuYd4SUcFyAOA4JWT
Max-Forwards: 70
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=koll1ZZ--sGyLbxSReHOI6zD7M9L.vFg
To: <sip:6001@10.24.17.228>
Call-ID: eLoRkU26GZq2d9d1NPJKC1il5K9wIDDw
CSeq: 23961 CANCEL
User-Agent: Digium D40 1_3_2_0_54993
Content-Length:  0


[Jul  5 16:57:54] VERBOSE[29961] res_sip_logger.c: <--- Transmitting SIP response (363 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=z9hG4bKPj6OpZQh6Mt-x-mRnCuYd4SUcFyAOA4JWT
Call-ID: eLoRkU26GZq2d9d1NPJKC1il5K9wIDDw
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=koll1ZZ--sGyLbxSReHOI6zD7M9L.vFg
To: <sip:6001@10.24.17.228>;tag=362f3f3d-7110-4577-99c3-1ff199c2e0f9
CSeq: 23961 CANCEL
Content-Length:  0


[Jul  5 16:57:54] VERBOSE[29961] res_sip_logger.c: <--- Transmitting SIP response (490 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=z9hG4bKPj6OpZQh6Mt-x-mRnCuYd4SUcFyAOA4JWT
Call-ID: eLoRkU26GZq2d9d1NPJKC1il5K9wIDDw
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=koll1ZZ--sGyLbxSReHOI6zD7M9L.vFg
To: <sip:6001@10.24.17.228>;tag=362f3f3d-7110-4577-99c3-1ff199c2e0f9
CSeq: 23961 INVITE
Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER, REGISTER
Content-Length:  0


[Jul  5 16:57:54] VERBOSE[29961] 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=z9hG4bKPjdb67dfbf-689c-4205-b857-21c8bd1eecfc
Max-Forwards: 70
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=3a8e7c4a-6084-48bf-9d31-da7d8dcd24df
To: sip:6001@10.24.18.16
Call-ID: 0a911c07-a2b8-460e-a796-d552662b2e80
CSeq: 19290 CANCEL
Reason: Q.850;cause=0
Content-Length:  0


[Jul  5 16:57:54] VERBOSE[29861] 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=z9hG4bKPjdb67dfbf-689c-4205-b857-21c8bd1eecfc
Call-ID: 0a911c07-a2b8-460e-a796-d552662b2e80
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=3a8e7c4a-6084-48bf-9d31-da7d8dcd24df
To: <sip:6001@10.24.18.16>;tag=q5F7p3LU5LFXYNc8-TK9us6O5ZX0heeF
CSeq: 19290 CANCEL
Content-Length:  0


[Jul  5 16:57:54] VERBOSE[29861] 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=z9hG4bKPjdb67dfbf-689c-4205-b857-21c8bd1eecfc
Call-ID: 0a911c07-a2b8-460e-a796-d552662b2e80
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=3a8e7c4a-6084-48bf-9d31-da7d8dcd24df
To: <sip:6001@10.24.18.16>;tag=q5F7p3LU5LFXYNc8-TK9us6O5ZX0heeF
CSeq: 19290 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 16:57:54] VERBOSE[29962] 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=z9hG4bKPjdb67dfbf-689c-4205-b857-21c8bd1eecfc
Max-Forwards: 70
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=3a8e7c4a-6084-48bf-9d31-da7d8dcd24df
To: sip:6001@10.24.18.16;tag=q5F7p3LU5LFXYNc8-TK9us6O5ZX0heeF
Call-ID: 0a911c07-a2b8-460e-a796-d552662b2e80
CSeq: 19290 ACK
Content-Length:  0


[Jul  5 16:57:54] VERBOSE[29861] res_sip_logger.c: <--- Received SIP request (375 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=z9hG4bKPj6OpZQh6Mt-x-mRnCuYd4SUcFyAOA4JWT
Max-Forwards: 70
From: "RustyTWO" <sip:6002@10.24.17.228>;tag=koll1ZZ--sGyLbxSReHOI6zD7M9L.vFg
To: <sip:6001@10.24.17.228>;tag=362f3f3d-7110-4577-99c3-1ff199c2e0f9
Call-ID: eLoRkU26GZq2d9d1NPJKC1il5K9wIDDw
CSeq: 23961 ACK
Content-Length:  0


[Jul  5 16:57:54] VERBOSE[29970][C-00000005] pbx.c:   == Spawn extension (otherinternal, 6001, 1) exited non-zero on 'Gulp/6002-0000000a'