[2016-03-25 13:48:21] VERBOSE[12097][C-000000da] app_macro.c: Spawn extension (macro-exten-vm, s, 16) exited non-zero on 'SIP/1240-00000183' in macro 'exten-vm'
[2016-03-25 13:48:21] VERBOSE[12098][C-000000da] bridge_channel.c: Channel SIP/0666-00000184 left 'simple_bridge' basic-bridge <7376f3c7-3051-44cd-b686-85318280884d>
[2016-03-25 13:48:21] VERBOSE[12098][C-000000da] chan_sip.c: Scheduling destruction of SIP dialog '0d598b9f7efbdff54be246ff3984fddc@4.95.0.2:5060' in 6400 ms (Method: INVITE)
[2016-03-25 13:48:21] VERBOSE[12098][C-000000da] chan_sip.c: set_destination: Parsing <sip:0666@4.95.1.16;transport=udp> for address/port to send to
[2016-03-25 13:48:21] VERBOSE[12098][C-000000da] chan_sip.c: set_destination: set destination to 4.95.1.16:5060
[2016-03-25 13:48:21] VERBOSE[12098][C-000000da] chan_sip.c: Reliably Transmitting (no NAT) to 4.95.1.16:5060:
BYE sip:0666@4.95.1.16;transport=udp SIP/2.0
Via: SIP/2.0/UDP 4.95.0.2:5060;branch=z9hG4bK326302fc
Max-Forwards: 70
From: "xxx" <sip:1240@4.95.0.2>;tag=as45255f11
To: <sip:0666@4.95.1.16;avaya-sc-enabled;transport=udp>;tag=1d5c144856f54f8b5702ab3c_T06664.95.1.16
Call-ID: 0d598b9f7efbdff54be246ff3984fddc@4.95.0.2:5060
CSeq: 103 BYE
User-Agent: FPBX-13.0.83(13.7.2)
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0
---
[2016-03-25 13:48:21] VERBOSE[12097][C-000000da] pbx.c: Spawn extension (ext-local, 0666, 2) exited non-zero on 'SIP/1240-00000183'
[2016-03-25 13:48:21] VERBOSE[12097][C-000000da] pbx.c: Executing [h@ext-local:1] Macro("SIP/1240-00000183", "hangupcall,") in new stack
[2016-03-25 13:48:21] VERBOSE[12097][C-000000da] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/1240-00000183", "1?theend") in new stack
[2016-03-25 13:48:21] VERBOSE[12097][C-000000da] pbx.c: Goto (macro-hangupcall,s,3)
[2016-03-25 13:48:21] VERBOSE[12097][C-000000da] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/1240-00000183", "0?Set(CDR(recordingfile)=)") in new stack
[2016-03-25 13:48:21] VERBOSE[12097][C-000000da] pbx.c: Executing [s@macro-hangupcall:4] Hangup("SIP/1240-00000183", "") in new stack
[2016-03-25 13:48:21] VERBOSE[12097][C-000000da] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/1240-00000183' in macro 'hangupcall'
[2016-03-25 13:48:21] VERBOSE[12097][C-000000da] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/1240-00000183'
[2016-03-25 13:48:21] VERBOSE[1957][C-000000da] chan_sip.c: Scheduling destruction of SIP dialog '3_f9377df4c39a56f648ba_I@4.95.1.21' in 6400 ms (Method: BYE)
[2016-03-25 13:48:21] VERBOSE[1957][C-000000da] chan_sip.c:
<--- Transmitting (no NAT) to 4.95.1.21:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 4.95.1.21;branch=z9hG4bK-7e2a70f0_10971-4bf0491656f65bee_B1240;received=4.95.1.21
From: sip:1240@4.95.0.2;tag=aebec4156f54f8256f64bbb_F12404.95.1.21
To: sip:0666@4.95.0.2;tag=as34c51c2f
Call-ID: 3_f9377df4c39a56f648ba_I@4.95.1.21
CSeq: 4 BYE
Server: FPBX-13.0.83(13.7.2)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0
<------------>
[2016-03-25 13:48:21] VERBOSE[1957] chan_sip.c: Really destroying SIP dialog '3_f9377df4c39a56f648ba_I@4.95.1.21' Method: BYE
[2016-03-25 13:48:21] VERBOSE[1957] chan_sip.c:
<--- SIP read from UDP:4.95.1.21:1030 --->
SIP/2.0 180 Ringing
From: "zzz" <sip:1234@4.95.0.2>;tag=as24c99ead
To: <sip:1240@4.95.1.21;avaya-sc-enabled;transport=udp>;tag=19b9532f56f54fb556f659f0_T12404.95.1.21
Call-ID: 47e1f03446a8a9124fb19675761454c6@4.95.0.2:5060
CSeq: 102 INVITE
Via: SIP/2.0/UDP 4.95.0.2:5060;branch=z9hG4bK2e7bb7d8
Contact: <sip:1240@4.95.1.21;transport=udp>
Allow: INVITE,CANCEL,BYE,ACK,SUBSCRIBE,NOTIFY,MESSAGE,INFO,PUBLISH,REFER,UPDATE,PRACK
Accept-Language: ru
User-Agent: Avaya one-X Deskphone
Content-Length: 0
<------------->
[2016-03-25 13:48:21] VERBOSE[1957] chan_sip.c: --- (11 headers 0 lines) ---
[2016-03-25 13:48:21] VERBOSE[1957][C-000000dd] sip/route.c: sip_route_dump: route/path hop: <sip:1240@4.95.1.21;transport=udp>
[2016-03-25 13:48:21] VERBOSE[1957] chan_sip.c:
<--- SIP read from UDP:4.95.1.16:1030 --->
SIP/2.0 200 OK
From: "xxx" <sip:1240@4.95.0.2>;tag=as45255f11
To: <sip:0666@4.95.1.16;avaya-sc-enabled;transport=udp>;tag=1d5c144856f54f8b5702ab3c_T06664.95.1.16
Call-ID: 0d598b9f7efbdff54be246ff3984fddc@4.95.0.2:5060
CSeq: 103 BYE
Via: SIP/2.0/UDP 4.95.0.2:5060;branch=z9hG4bK326302fc
User-Agent: Avaya one-X Deskphone
Content-Length: 0
<------------->
[2016-03-25 13:48:21] VERBOSE[12123][C-000000dd] app_dial.c: SIP/1240-00000188 is ringing
[2016-03-25 13:48:21] VERBOSE[1957] chan_sip.c: --- (8 headers 0 lines) ---
[2016-03-25 13:48:21] VERBOSE[1957] chan_sip.c: Really destroying SIP dialog '0d598b9f7efbdff54be246ff3984fddc@4.95.0.2:5060' Method: INVITE
[2016-03-25 13:48:21] VERBOSE[1957] chan_sip.c:
<--- SIP read from UDP:4.95.1.21:1030 --->
SIP/2.0 180 Ringing
From: "zzz" <sip:1234@4.95.0.2>;tag=as24c99ead
To: <sip:1240@4.95.1.21;avaya-sc-enabled;transport=udp>;tag=19b9532f56f54fb556f659f0_T12404.95.1.21
Call-ID: 47e1f03446a8a9124fb19675761454c6@4.95.0.2:5060
CSeq: 102 INVITE
Via: SIP/2.0/UDP 4.95.0.2:5060;branch=z9hG4bK2e7bb7d8
Contact: <sip:1240@4.95.1.21;transport=udp>
Allow: INVITE,CANCEL,BYE,ACK,SUBSCRIBE,NOTIFY,MESSAGE,INFO,PUBLISH,REFER,UPDATE,PRACK
Accept-Language: ru
User-Agent: Avaya one-X Deskphone
Content-Length: 0
<------------->
<------------->
[2016-03-25 13:48:22] VERBOSE[1957] chan_sip.c: --- (8 headers 0 lines) ---
[2016-03-25 13:48:22] VERBOSE[1957] chan_sip.c: Really destroying SIP dialog '086bd6cd4f62467d7caf520b57128b33@4.95.0.2:5060' Method: OPTIONS
[2016-03-25 13:48:23] VERBOSE[1957] chan_sip.c:
<--- SIP read from UDP:4.95.1.23:1030 --->
CANCEL sip:1240@4.95.0.2 SIP/2.0
From: sip:1234@4.95.0.2;tag=-74fb4f2156f54fb456fb4000_F12344.95.1.23
To: sip:1240@4.95.0.2
Call-ID: 5_5db36-7d2b484f56fb2aff_I@4.95.1.23
CSeq: 5 CANCEL
Via: SIP/2.0/UDP 4.95.1.23;branch=z9hG4bK5_5db36-da1a62b56fb2bf2_I1234
Content-Length: 0
Max-Forwards: 70
User-Agent: Avaya one-X Deskphone
Supported: eventlist, 100rel
<------------->
[2016-03-25 13:48:23] VERBOSE[1957] chan_sip.c: --- (10 headers 0 lines) ---
[2016-03-25 13:48:23] VERBOSE[1957][C-000000dd] chan_sip.c: Sending to 4.95.1.23:5060 (no NAT)
[2016-03-25 13:48:23] VERBOSE[1957][C-000000dd] chan_sip.c:
<--- Reliably Transmitting (no NAT) to 4.95.1.23:5060 --->
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 4.95.1.23;branch=z9hG4bK5_5db36-da1a62b56fb2bf2_I1234;received=4.95.1.23
From: sip:1234@4.95.0.2;tag=-74fb4f2156f54fb456fb4000_F12344.95.1.23
To: sip:1240@4.95.0.2;tag=as575cfdf0
Call-ID: 5_5db36-7d2b484f56fb2aff_I@4.95.1.23
CSeq: 5 INVITE
Server: FPBX-13.0.83(13.7.2)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0
<------------>
[2016-03-25 13:48:23] VERBOSE[1957][C-000000dd] chan_sip.c:
<--- Transmitting (no NAT) to 4.95.1.23:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 4.95.1.23;branch=z9hG4bK5_5db36-da1a62b56fb2bf2_I1234;received=4.95.1.23
From: sip:1234@4.95.0.2;tag=-74fb4f2156f54fb456fb4000_F12344.95.1.23
To: sip:1240@4.95.0.2;tag=as575cfdf0
Call-ID: 5_5db36-7d2b484f56fb2aff_I@4.95.1.23
CSeq: 5 CANCEL
Server: FPBX-13.0.83(13.7.2)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0
<------------>
[2016-03-25 13:48:23] VERBOSE[12123][C-000000dd] res_musiconhold.c: Stopped music on hold on SIP/1234-00000187
[2016-03-25 13:48:23] VERBOSE[12123][C-000000dd] chan_sip.c: Scheduling destruction of SIP dialog '47e1f03446a8a9124fb19675761454c6@4.95.0.2:5060' in 6400 ms (Method: INVITE)
[2016-03-25 13:48:23] VERBOSE[12123][C-000000dd] chan_sip.c: Reliably Transmitting (no NAT) to 4.95.1.21:5060:
CANCEL sip:1240@4.95.1.21;avaya-sc-enabled;transport=udp SIP/2.0
Via: SIP/2.0/UDP 4.95.0.2:5060;branch=z9hG4bK2e7bb7d8
Max-Forwards: 70
From: "zzz" <sip:1234@4.95.0.2>;tag=as24c99ead
To: <sip:1240@4.95.1.21;avaya-sc-enabled;transport=udp>
Call-ID: 47e1f03446a8a9124fb19675761454c6@4.95.0.2:5060
CSeq: 102 CANCEL
User-Agent: FPBX-13.0.83(13.7.2)
Content-Length: 0
---
[2016-03-25 13:48:23] VERBOSE[12123][C-000000dd] chan_sip.c: Scheduling destruction of SIP dialog '47e1f03446a8a9124fb19675761454c6@4.95.0.2:5060' in 6400 ms (Method: INVITE)
[2016-03-25 13:48:23] VERBOSE[12123][C-000000dd] app_macro.c: Spawn extension (macro-dial-one, s, 47) exited non-zero on 'SIP/1234-00000187' in macro 'dial-one'
[2016-03-25 13:48:23] VERBOSE[12123][C-000000dd] app_macro.c: Spawn extension (macro-exten-vm, s, 16) exited non-zero on 'SIP/1234-00000187' in macro 'exten-vm'
[2016-03-25 13:48:23] VERBOSE[1957] chan_sip.c:
<--- SIP read from UDP:4.95.1.21:1030 --->
SIP/2.0 200 OK
From: "zzz" <sip:1234@4.95.0.2>;tag=as24c99ead
To: <sip:1240@4.95.1.21;avaya-sc-enabled;transport=udp>
Call-ID: 47e1f03446a8a9124fb19675761454c6@4.95.0.2:5060
CSeq: 102 CANCEL
Via: SIP/2.0/UDP 4.95.0.2:5060;branch=z9hG4bK2e7bb7d8
User-Agent: Avaya one-X Deskphone
Content-Length: 0
<------------->
[2016-03-25 13:48:23] VERBOSE[1957] chan_sip.c: --- (8 headers 0 lines) ---
[2016-03-25 13:48:23] VERBOSE[12123][C-000000dd] pbx.c: Spawn extension (ext-local, 1240, 2) exited non-zero on 'SIP/1234-00000187'
[2016-03-25 13:48:23] VERBOSE[12123][C-000000dd] pbx.c: Executing [h@ext-local:1] Macro("SIP/1234-00000187", "hangupcall,") in new stack
[2016-03-25 13:48:23] VERBOSE[1957] chan_sip.c:
<--- SIP read from UDP:4.95.1.21:1030 --->
SIP/2.0 487 Request Terminated
From: "evv" <sip:1234@4.95.0.2>;tag=as24c99ead
To: <sip:1240@4.95.1.21;avaya-sc-enabled;transport=udp>;tag=19b9532f56f54fb556f659f0_T12404.95.1.21
Call-ID: 47e1f03446a8a9124fb19675761454c6@4.95.0.2:5060
CSeq: 102 INVITE
Via: SIP/2.0/UDP 4.95.0.2:5060;branch=z9hG4bK2e7bb7d8
User-Agent: Avaya one-X Deskphone
Content-Length: 0
<------------->
[2016-03-25 13:48:23] VERBOSE[1957] chan_sip.c: --- (8 headers 0 lines) ---
[2016-03-25 13:48:23] VERBOSE[1957][C-000000dd] chan_sip.c: Transmitting (no NAT) to 4.95.1.21:5060:
ACK sip:1240@4.95.1.21;transport=udp SIP/2.0
Via: SIP/2.0/UDP 4.95.0.2:5060;branch=z9hG4bK2e7bb7d8
Max-Forwards: 70
From: "zzz" <sip:1234@4.95.0.2>;tag=as24c99ead
To: <sip:1240@4.95.1.21;avaya-sc-enabled;transport=udp>;tag=19b9532f56f54fb556f659f0_T12404.95.1.21
Contact: <sip:1234@4.95.0.2:5060>
Call-ID: 47e1f03446a8a9124fb19675761454c6@4.95.0.2:5060
CSeq: 102 ACK
User-Agent: FPBX-13.0.83(13.7.2)
Content-Length: 0
---
[2016-03-25 13:48:23] VERBOSE[1957][C-000000dd] chan_sip.c: Scheduling destruction of SIP dialog '47e1f03446a8a9124fb19675761454c6@4.95.0.2:5060' in 6400 ms (Method: INVITE)
[2016-03-25 13:48:23] VERBOSE[12123][C-000000dd] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/1234-00000187", "1?theend") in new stack
[2016-03-25 13:48:23] VERBOSE[1957] chan_sip.c:
<--- SIP read from UDP:4.95.1.23:1030 --->
ACK sip:1240@4.95.0.2 SIP/2.0
From: sip:1234@4.95.0.2;tag=-74fb4f2156f54fb456fb4000_F12344.95.1.23
To: sip:1240@4.95.0.2;tag=as575cfdf0
Call-ID: 5_5db36-7d2b484f56fb2aff_I@4.95.1.23
CSeq: 5 ACK
Via: SIP/2.0/UDP 4.95.1.23;branch=z9hG4bK5_5db36-da1a62b56fb2bf2_I1234
Content-Length: 0
Max-Forwards: 70
User-Agent: Avaya one-X Deskphone
Supported: eventlist, 100rel
<------------->
<------------->
[2016-03-25 13:48:42] VERBOSE[1957] chan_sip.c: --- (8 headers 0 lines) ---
[2016-03-25 13:48:42] VERBOSE[1957] chan_sip.c: Really destroying SIP dialog '54272c417504f9c745fd11a2099fd42a@4.95.0.2:5060' Method: OPTIONS
[2016-03-25 13:48:45] VERBOSE[1957] chan_sip.c: Reliably Transmitting (no NAT) to 4.95.1.16:5060:
OPTIONS sip:0666@4.95.1.16;avaya-sc-enabled;transport=udp SIP/2.0
Via: SIP/2.0/UDP 4.95.0.2:5060;branch=z9hG4bK05e55abb
Max-Forwards: 70
From: "Unknown" <sip:Unknown@4.95.0.2>;tag=as3a9388bd
To: <sip:0666@4.95.1.16;avaya-sc-enabled;transport=udp>
Contact: <sip:Unknown@4.95.0.2:5060>
Call-ID: 6c8505cf456681b4390c286b62246d8c@4.95.0.2:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-13.0.83(13.7.2)
Date: Fri, 25 Mar 2016 10:48:45 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0
---
[2016-03-25 13:48:45] VERBOSE[1957] chan_sip.c:
<--- SIP read from UDP:4.95.1.16:1030 --->
SIP/2.0 200 OK
From: "Unknown" <sip:Unknown@4.95.0.2>;tag=as3a9388bd
To: <sip:0666@4.95.1.16;avaya-sc-enabled;transport=udp>
Call-ID: 6c8505cf456681b4390c286b62246d8c@4.95.0.2:5060
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 4.95.0.2:5060;branch=z9hG4bK05e55abb
User-Agent: Avaya one-X Deskphone
Content-Length: 0