Oct 27 13:55:38 VERBOSE[4217] logger.c:     -- Executing Wait("SIP/102-09090248", "1") in new stack
Oct 27 13:55:38 VERBOSE[3218] logger.c: 
<-- SIP read from 192.168.1.130:36778: 
ACK sip:1234@192.168.1.7 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.130:36778;branch=z9hG4bK-d87543-8f2cda2c182c7748-1--d87543-;rport
Max-Forwards: 70
Contact: <sip:102@192.168.1.130:36778>
To: "1234"<sip:1234@192.168.1.7>;tag=as0020fa0d
From: "102"<sip:102@192.168.1.7>;tag=b1649d1b
Call-ID: OWFlMjAyYjJiNTYwZGZhYWQ4ZDZmYzNlODE1YTJjOWM.
CSeq: 2 ACK
Proxy-Authorization: Digest username="102",realm="asterisk",nonce="00a47b57",uri="sip:1234@192.168.1.7",response="c202ab033ac19b56e99c0b4d5f3143ab",algorithm=MD5
User-Agent: X-Lite release 1011s stamp 41150
Content-Length: 0


Oct 27 13:55:38 VERBOSE[3218] logger.c: --- (11 headers 0 lines) ---
Oct 27 13:55:38 DEBUG[3218] chan_sip.c: Stopping retransmission on 'OWFlMjAyYjJiNTYwZGZhYWQ4ZDZmYzNlODE1YTJjOWM.' of Response 2: Match Found
Oct 27 13:55:39 VERBOSE[4217] logger.c:     -- Executing Playback("SIP/102-09090248", "work") in new stack
Oct 27 13:55:39 DEBUG[4217] channel.c: Scheduling timer at 160 sample intervals
Oct 27 13:55:39 VERBOSE[4217] logger.c:     -- Playing 'work' (language 'en')
Oct 27 13:55:49 VERBOSE[3218] logger.c: 
<-- SIP read from 192.168.1.130:36778: 



Oct 27 13:55:49 VERBOSE[3218] logger.c: --- (0 headers 1 lines) ---
Oct 27 13:56:18 VERBOSE[3218] logger.c: 12 headers, 0 lines
Oct 27 13:56:18 VERBOSE[3218] logger.c: Reliably Transmitting (NAT) to 192.168.1.130:36778:
OPTIONS sip:102@192.168.1.130:36778;rinstance=eb46b1948a0d8d6d SIP/2.0
Via: SIP/2.0/UDP 192.168.1.7:5060;branch=z9hG4bK2b65febc;rport
From: "Unknown" <sip:Unknown@192.168.1.7>;tag=as416e26f5
To: <sip:102@192.168.1.130:36778;rinstance=eb46b1948a0d8d6d>
Contact: <sip:Unknown@192.168.1.7>
Call-ID: 6b7094a476d5a0c85969330a7b777b27@192.168.1.7
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX
Max-Forwards: 70
Date: Sat, 27 Oct 2007 18:56:18 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
Oct 27 13:56:18 VERBOSE[3218] logger.c: 
<-- SIP read from 192.168.1.130:36778: 
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.7:5060;branch=z9hG4bK2b65febc;rport=5060
Contact: <sip:192.168.1.130:36778>
To: <sip:102@192.168.1.130:36778;rinstance=eb46b1948a0d8d6d>;tag=e7253520
From: "Unknown"<sip:Unknown@192.168.1.7>;tag=as416e26f5
Call-ID: 6b7094a476d5a0c85969330a7b777b27@192.168.1.7
CSeq: 102 OPTIONS
Accept: application/sdp
Accept-Language: en
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: X-Lite release 1011s stamp 41150
Content-Length: 0


Oct 27 13:56:18 VERBOSE[3218] logger.c: --- (12 headers 0 lines) ---
Oct 27 13:56:18 DEBUG[3218] chan_sip.c: Stopping retransmission on '6b7094a476d5a0c85969330a7b777b27@192.168.1.7' of Request 102: Match Found
Oct 27 13:56:18 VERBOSE[3218] logger.c: Destroying call '6b7094a476d5a0c85969330a7b777b27@192.168.1.7'
Oct 27 13:56:19 VERBOSE[3218] logger.c: 
<-- SIP read from 192.168.1.130:36778: 



Oct 27 13:56:19 VERBOSE[3218] logger.c: --- (0 headers 1 lines) ---
Oct 27 13:56:49 VERBOSE[3218] logger.c: 
<-- SIP read from 192.168.1.130:36778: 



Oct 27 13:56:49 VERBOSE[3218] logger.c: --- (0 headers 1 lines) ---
Oct 27 13:56:56 DEBUG[4117] manager.c: Manager received command 'Command'
Oct 27 13:56:56 DEBUG[4117] manager.c: Manager received command 'Command'
Oct 27 13:56:56 DEBUG[4117] manager.c: Manager received command 'Command'
Oct 27 13:57:18 VERBOSE[3218] logger.c: 12 headers, 0 lines
Oct 27 13:57:18 VERBOSE[3218] logger.c: Reliably Transmitting (NAT) to 192.168.1.130:36778:
OPTIONS sip:102@192.168.1.130:36778;rinstance=eb46b1948a0d8d6d SIP/2.0
Via: SIP/2.0/UDP 192.168.1.7:5060;branch=z9hG4bK02318696;rport
From: "Unknown" <sip:Unknown@192.168.1.7>;tag=as0b0e0c26
To: <sip:102@192.168.1.130:36778;rinstance=eb46b1948a0d8d6d>
Contact: <sip:Unknown@192.168.1.7>
Call-ID: 5102939c53eaded464375db40de8e3de@192.168.1.7
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX
Max-Forwards: 70
Date: Sat, 27 Oct 2007 18:57:18 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
Oct 27 13:57:19 VERBOSE[3218] logger.c: 
<-- SIP read from 192.168.1.130:36778: 
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.7:5060;branch=z9hG4bK02318696;rport=5060
Contact: <sip:192.168.1.130:36778>
To: <sip:102@192.168.1.130:36778;rinstance=eb46b1948a0d8d6d>;tag=3e70477a
From: "Unknown"<sip:Unknown@192.168.1.7>;tag=as0b0e0c26
Call-ID: 5102939c53eaded464375db40de8e3de@192.168.1.7
CSeq: 102 OPTIONS
Accept: application/sdp
Accept-Language: en
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: X-Lite release 1011s stamp 41150
Content-Length: 0


Oct 27 13:57:19 VERBOSE[3218] logger.c: --- (12 headers 0 lines) ---
Oct 27 13:57:19 DEBUG[3218] chan_sip.c: Stopping retransmission on '5102939c53eaded464375db40de8e3de@192.168.1.7' of Request 102: Match Found
Oct 27 13:57:19 VERBOSE[3218] logger.c: Destroying call '5102939c53eaded464375db40de8e3de@192.168.1.7'
Oct 27 13:57:19 VERBOSE[3218] logger.c: 
<-- SIP read from 192.168.1.130:36778: 



Oct 27 13:57:19 VERBOSE[3218] logger.c: --- (0 headers 1 lines) ---
Oct 27 13:57:49 VERBOSE[3218] logger.c: 
<-- SIP read from 192.168.1.130:36778: 



Oct 27 13:57:49 VERBOSE[3218] logger.c: --- (0 headers 1 lines) ---
Oct 27 13:58:19 VERBOSE[3218] logger.c: 12 headers, 0 lines
Oct 27 13:58:19 VERBOSE[3218] logger.c: Reliably Transmitting (NAT) to 192.168.1.130:36778:
OPTIONS sip:102@192.168.1.130:36778;rinstance=eb46b1948a0d8d6d SIP/2.0
Via: SIP/2.0/UDP 192.168.1.7:5060;branch=z9hG4bK1f7db2c9;rport
From: "Unknown" <sip:Unknown@192.168.1.7>;tag=as16388602
To: <sip:102@192.168.1.130:36778;rinstance=eb46b1948a0d8d6d>
Contact: <sip:Unknown@192.168.1.7>
Call-ID: 09e0cc8477e9f1702ffc2b9a050b57ea@192.168.1.7
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX
Max-Forwards: 70
Date: Sat, 27 Oct 2007 18:58:19 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
Oct 27 13:58:19 VERBOSE[3218] logger.c: 
<-- SIP read from 192.168.1.130:36778: 
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.7:5060;branch=z9hG4bK1f7db2c9;rport=5060
Contact: <sip:192.168.1.130:36778>
To: <sip:102@192.168.1.130:36778;rinstance=eb46b1948a0d8d6d>;tag=8114a03b
From: "Unknown"<sip:Unknown@192.168.1.7>;tag=as16388602
Call-ID: 09e0cc8477e9f1702ffc2b9a050b57ea@192.168.1.7
CSeq: 102 OPTIONS
Accept: application/sdp
Accept-Language: en
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: X-Lite release 1011s stamp 41150
Content-Length: 0


Oct 27 13:58:19 VERBOSE[3218] logger.c: --- (12 headers 0 lines) ---
Oct 27 13:58:19 DEBUG[3218] chan_sip.c: Stopping retransmission on '09e0cc8477e9f1702ffc2b9a050b57ea@192.168.1.7' of Request 102: Match Found
Oct 27 13:58:19 VERBOSE[3218] logger.c: Destroying call '09e0cc8477e9f1702ffc2b9a050b57ea@192.168.1.7'
Oct 27 13:58:20 VERBOSE[3218] logger.c: 
<-- SIP read from 192.168.1.130:36778: 



Oct 27 13:58:20 VERBOSE[3218] logger.c: --- (0 headers 1 lines) ---
Oct 27 13:58:50 VERBOSE[3218] logger.c: 
<-- SIP read from 192.168.1.130:36778: 



Oct 27 13:58:50 VERBOSE[3218] logger.c: --- (0 headers 1 lines) ---
Oct 27 13:58:56 DEBUG[4117] manager.c: Manager received command 'Command'
Oct 27 13:58:56 DEBUG[4117] manager.c: Manager received command 'Command'
Oct 27 13:58:56 DEBUG[4117] manager.c: Manager received command 'Command'
Oct 27 13:59:19 VERBOSE[3218] logger.c: 12 headers, 0 lines
Oct 27 13:59:19 VERBOSE[3218] logger.c: Reliably Transmitting (NAT) to 192.168.1.130:36778:
OPTIONS sip:102@192.168.1.130:36778;rinstance=eb46b1948a0d8d6d SIP/2.0
Via: SIP/2.0/UDP 192.168.1.7:5060;branch=z9hG4bK3bb36d3d;rport
From: "Unknown" <sip:Unknown@192.168.1.7>;tag=as58f29c27
To: <sip:102@192.168.1.130:36778;rinstance=eb46b1948a0d8d6d>
Contact: <sip:Unknown@192.168.1.7>
Call-ID: 704efdbd27e981ef3d23d05d0eca188a@192.168.1.7
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX
Max-Forwards: 70
Date: Sat, 27 Oct 2007 18:59:19 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
Oct 27 13:59:19 VERBOSE[3218] logger.c: 
<-- SIP read from 192.168.1.130:36778: 
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.7:5060;branch=z9hG4bK3bb36d3d;rport=5060
Contact: <sip:192.168.1.130:36778>
To: <sip:102@192.168.1.130:36778;rinstance=eb46b1948a0d8d6d>;tag=ca0a394e
From: "Unknown"<sip:Unknown@192.168.1.7>;tag=as58f29c27
Call-ID: 704efdbd27e981ef3d23d05d0eca188a@192.168.1.7
CSeq: 102 OPTIONS
Accept: application/sdp
Accept-Language: en
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: X-Lite release 1011s stamp 41150
Content-Length: 0


Oct 27 13:59:19 VERBOSE[3218] logger.c: --- (12 headers 0 lines) ---
Oct 27 13:59:19 DEBUG[3218] chan_sip.c: Stopping retransmission on '704efdbd27e981ef3d23d05d0eca188a@192.168.1.7' of Request 102: Match Found
Oct 27 13:59:19 VERBOSE[3218] logger.c: Destroying call '704efdbd27e981ef3d23d05d0eca188a@192.168.1.7'
Oct 27 13:59:20 VERBOSE[3218] logger.c: 
<-- SIP read from 192.168.1.130:36778: 



Oct 27 13:59:20 VERBOSE[3218] logger.c: --- (0 headers 1 lines) ---
Oct 27 13:59:50 VERBOSE[3218] logger.c: 
<-- SIP read from 192.168.1.130:36778: 



Oct 27 13:59:50 VERBOSE[3218] logger.c: --- (0 headers 1 lines) ---
Oct 27 14:00:19 VERBOSE[3218] logger.c: 12 headers, 0 lines
Oct 27 14:00:19 VERBOSE[3218] logger.c: Reliably Transmitting (NAT) to 192.168.1.130:36778:
OPTIONS sip:102@192.168.1.130:36778;rinstance=eb46b1948a0d8d6d SIP/2.0
Via: SIP/2.0/UDP 192.168.1.7:5060;branch=z9hG4bK0643abd2;rport
From: "Unknown" <sip:Unknown@192.168.1.7>;tag=as4e5243d4
To: <sip:102@192.168.1.130:36778;rinstance=eb46b1948a0d8d6d>
Contact: <sip:Unknown@192.168.1.7>
Call-ID: 4bf16d557a7d1b8549a25dfe41dc01d7@192.168.1.7
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX
Max-Forwards: 70
Date: Sat, 27 Oct 2007 19:00:19 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
Oct 27 14:00:19 VERBOSE[3218] logger.c: 
<-- SIP read from 192.168.1.130:36778: 
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.7:5060;branch=z9hG4bK0643abd2;rport=5060
Contact: <sip:192.168.1.130:36778>
To: <sip:102@192.168.1.130:36778;rinstance=eb46b1948a0d8d6d>;tag=c84a5f2c
From: "Unknown"<sip:Unknown@192.168.1.7>;tag=as4e5243d4
Call-ID: 4bf16d557a7d1b8549a25dfe41dc01d7@192.168.1.7
CSeq: 102 OPTIONS
Accept: application/sdp
Accept-Language: en
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: X-Lite release 1011s stamp 41150
Content-Length: 0


Oct 27 14:00:19 VERBOSE[3218] logger.c: --- (12 headers 0 lines) ---
Oct 27 14:00:19 DEBUG[3218] chan_sip.c: Stopping retransmission on '4bf16d557a7d1b8549a25dfe41dc01d7@192.168.1.7' of Request 102: Match Found
Oct 27 14:00:19 VERBOSE[3218] logger.c: Destroying call '4bf16d557a7d1b8549a25dfe41dc01d7@192.168.1.7'
Oct 27 14:00:20 VERBOSE[3218] logger.c: 
<-- SIP read from 192.168.1.130:36778: 



Oct 27 14:00:20 VERBOSE[3218] logger.c: --- (0 headers 1 lines) ---
Oct 27 14:00:24 DEBUG[4217] channel.c: Scheduling timer at 0 sample intervals
Oct 27 14:00:24 DEBUG[4217] channel.c: Scheduling timer at 0 sample intervals
Oct 27 14:00:24 VERBOSE[4217] logger.c:     -- Executing Playback("SIP/102-09090248", "wish-to-continue") in new stack
Oct 27 14:00:24 DEBUG[4217] channel.c: Scheduling timer at 160 sample intervals
Oct 27 14:00:24 VERBOSE[4217] logger.c:     -- Playing 'wish-to-continue' (language 'en')
Oct 27 14:00:33 DEBUG[4217] channel.c: Scheduling timer at 0 sample intervals
Oct 27 14:00:33 DEBUG[4217] channel.c: Scheduling timer at 0 sample intervals
Oct 27 14:00:33 VERBOSE[4217] logger.c:     -- Executing Hangup("SIP/102-09090248", "") in new stack
Oct 27 14:00:33 VERBOSE[4217] logger.c:   == Spawn extension (from-internal, 1234, 5) exited non-zero on 'SIP/102-09090248'
Oct 27 14:00:33 VERBOSE[4217] logger.c:     -- Executing Macro("SIP/102-09090248", "hangupcall") in new stack
Oct 27 14:00:33 VERBOSE[4217] logger.c:     -- Executing ResetCDR("SIP/102-09090248", "w") in new stack
Oct 27 14:00:33 DEBUG[4217] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.