# asterisk -rvvvvv
# pjsip set logger on
# sip set debug on
CSeq: 31274 OPTIONS
Supported: replaces
User-Agent: N510 IP PRO/42.076.00.000.000
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag
Accept-Encoding: identity
Accept-Language: en
Content-Length: 0
[2016-02-01 07:21:54] VERBOSE[1991] res_pjsip_logger.c: <--- Received SIP request (1221 bytes) from UDP:192.168.120.35:2048 --->
INVITE sip:[email protected];user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.120.35:2048;branch=z9hG4bK-4crcbkfwqw7x;rport
From: "Robert Schuster" <sip:[email protected]>;tag=p3lvdf2l3c
To: <sip:[email protected];user=phone>
Call-ID: 56aef9812d30-xr7b5681y73l
CSeq: 1 INVITE
Max-Forwards: 70
Contact: <sip:[email protected]:2048;line=je0exdpn>;reg-id=1
X-Serialnumber: 000413232E1C
P-Key-Flags: resolution="31x13", keys="4"
User-Agent: snom360/8.7.3.25
Accept: application/sdp
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE
Allow-Events: talk, hold, refer, call-info
Supported: timer, 100rel, replaces, from-change
Session-Expires: 3600;refresher=uas
Min-SE: 90
Content-Type: application/sdp
Content-Length: 405
v=0
o=root 250730445 250730445 IN IP4 192.168.120.35
s=call
c=IN IP4 192.168.120.35
t=0 0
m=audio 54272 RTP/AVP 9 0 8 3 99 108 18 101
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:108 AAL2-G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendrecv
[2016-02-01 07:21:54] VERBOSE[18763] res_pjsip_logger.c: <--- Transmitting SIP response (528 bytes) to UDP:192.168.120.35:2048 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.120.35:2048;rport=2048;received=192.168.120.35;branch=z9hG4bK-4crcbkfwqw7x
Call-ID: 56aef9812d30-xr7b5681y73l
From: "Robert Schuster" <sip:[email protected]>;tag=p3lvdf2l3c
To: <sip:[email protected];user=phone>;tag=z9hG4bK-4crcbkfwqw7x
CSeq: 1 INVITE
WWW-Authenticate: Digest realm="asterisk",nonce="1454307714/52c5afb8a78bdc3297dd1ff0c2d9d3b4",opaque="131ca7261ae4820e",algorithm=md5,qop="auth"
Server: FPBX-13.0.54(13.5.0)
Content-Length: 0
[2016-02-01 07:21:54] VERBOSE[1991] res_pjsip_logger.c: <--- Received SIP request (434 bytes) from UDP:192.168.120.35:2048 --->
ACK sip:[email protected];user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.120.35:2048;branch=z9hG4bK-4crcbkfwqw7x;rport
From: "Robert Schuster" <sip:[email protected]>;tag=p3lvdf2l3c
To: <sip:[email protected];user=phone>;tag=z9hG4bK-4crcbkfwqw7x
Call-ID: 56aef9812d30-xr7b5681y73l
CSeq: 1 ACK
Max-Forwards: 70
Contact: <sip:[email protected]:2048;line=je0exdpn>;reg-id=1
Content-Length: 0
[2016-02-01 07:21:54] VERBOSE[1991] res_pjsip_logger.c: <--- Received SIP request (1505 bytes) from UDP:192.168.120.35:2048 --->
INVITE sip:[email protected];user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.120.35:2048;branch=z9hG4bK-xo0im76s8pp8;rport
From: "Robert Schuster" <sip:[email protected]>;tag=p3lvdf2l3c
To: <sip:[email protected];user=phone>
Call-ID: 56aef9812d30-xr7b5681y73l
CSeq: 2 INVITE
Max-Forwards: 70
Contact: <sip:[email protected]:2048;line=je0exdpn>;reg-id=1
X-Serialnumber: 000413232E1C
P-Key-Flags: resolution="31x13", keys="4"
User-Agent: snom360/8.7.3.25
Accept: application/sdp
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE
Allow-Events: talk, hold, refer, call-info
Supported: timer, 100rel, replaces, from-change
Session-Expires: 3600;refresher=uas
Min-SE: 90
Authorization: Digest username="200",realm="asterisk",nonce="1454307714/52c5afb8a78bdc3297dd1ff0c2d9d3b4",uri="sip:[email protected];user=phone",qop=auth,nc=00000001,cnonce="3313833c",response="f20f3a7429e4d96a6dac43186ca41b0d",opaque="131ca7261ae4820e",algorithm=MD5
Content-Type: application/sdp
Content-Length: 405
v=0
o=root 250730445 250730445 IN IP4 192.168.120.35
s=call
c=IN IP4 192.168.120.35
t=0 0
m=audio 54272 RTP/AVP 9 0 8 3 99 108 18 101
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:108 AAL2-G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendrecv
[2016-02-01 07:21:54] VERBOSE[18763] res_pjsip_logger.c: <--- Transmitting SIP response (350 bytes) to UDP:192.168.120.35:2048 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.120.35:2048;rport=2048;received=192.168.120.35;branch=z9hG4bK-xo0im76s8pp8
Call-ID: 56aef9812d30-xr7b5681y73l
From: "Robert Schuster" <sip:[email protected]>;tag=p3lvdf2l3c
To: <sip:[email protected];user=phone>
CSeq: 2 INVITE
Server: FPBX-13.0.54(13.5.0)
Content-Length: 0
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [0017516xxxx4@from-internal:1] Macro("PJSIP/200-00000039", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/200-00000039", "TOUCH_MONITOR=1454307714.105") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/200-00000039", "AMPUSER=200") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("PJSIP/200-00000039", "0?report") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("PJSIP/200-00000039", "1?Set(REALCALLERIDNUM=200)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/200-00000039", "AMPUSER=200") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("PJSIP/200-00000039", "0?limit") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/200-00000039", "AMPUSERCIDNAME=Robert (Snom 360)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("PJSIP/200-00000039", "0?report") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/200-00000039", "AMPUSERCID=200") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/200-00000039", "__DIAL_OPTIONS=Ttr") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:11] Set("PJSIP/200-00000039", "CALLERID(all)="Robert (Snom 360)" <200>") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:12] GotoIf("PJSIP/200-00000039", "0?limit") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/200-00000039", "1?Set(GROUP(concurrency_limit)=200)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/200-00000039", "0?Set(CHANNEL(language)=)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:15] GotoIf("PJSIP/200-00000039", "1?continue") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Goto (macro-user-callerid,s,29)
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:29] Set("PJSIP/200-00000039", "CALLERID(number)=200") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:30] Set("PJSIP/200-00000039", "CALLERID(name)=Robert (Snom 360)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:31] Set("PJSIP/200-00000039", "CDR(cnum)=200") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:32] Set("PJSIP/200-00000039", "CDR(cnam)=Robert (Snom 360)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-user-callerid:33] Set("PJSIP/200-00000039", "CHANNEL(language)=en") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [0017516xxxx4@from-internal:2] Gosub("PJSIP/200-00000039", "sub-record-check,s,1(out,0017516xxxx4,force)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/200-00000039", "0?initialized") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/200-00000039", "__REC_STATUS=INITIALIZED") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/200-00000039", "NOW=1454307714") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/200-00000039", "__DAY=01") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/200-00000039", "__MONTH=02") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/200-00000039", "__YEAR=2016") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/200-00000039", "__TIMESTR=20160201-072154") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/200-00000039", "__FROMEXTEN=200") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/200-00000039", "__MON_FMT=wav") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/200-00000039", "Recordings initialized") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/200-00000039", "0?Set(ARG3=dontcare)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/200-00000039", "REC_POLICY_MODE_SAVE=") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/200-00000039", "0?Set(REC_STATUS=NO)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/200-00000039", "3?checkaction") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Goto (sub-record-check,s,17)
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/200-00000039", "1?sub-record-check,out,1") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Goto (sub-record-check,out,1)
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [out@sub-record-check:1] NoOp("PJSIP/200-00000039", "Outbound Recording Check from 200 to 0017516xxxx4") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [out@sub-record-check:2] Set("PJSIP/200-00000039", "RECMODE=dontcare") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [out@sub-record-check:3] ExecIf("PJSIP/200-00000039", "1?Goto(routewins)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Goto (sub-record-check,out,7)
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [out@sub-record-check:7] Gosub("PJSIP/200-00000039", "recordcheck,1(force,out,0017516xxxx4)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/200-00000039", "Starting recording check against force") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/200-00000039", "force") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Goto (sub-record-check,recordcheck,5)
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:5] Set("PJSIP/200-00000039", "__REC_POLICY_MODE=FORCE") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:6] GotoIf("PJSIP/200-00000039", "1?startrec") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Goto (sub-record-check,recordcheck,16)
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp("PJSIP/200-00000039", "Starting recording: out, 0017516xxxx4") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:17] Set("PJSIP/200-00000039", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:18] Set("PJSIP/200-00000039", "__CALLFILENAME=out-0017516xxxx4-200-20160201-072154-1454307714.105") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:19] MixMonitor("PJSIP/200-00000039", "2016/02/01/out-0017516xxxx4-200-20160201-072154-1454307714.105.wav,ai(LOCAL_MIXMON_ID),") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:20] Set("PJSIP/200-00000039", "__MIXMON_ID=0x7f80a00e36e0") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:21] Set("PJSIP/200-00000039", "__RECORD_ID=PJSIP/200-00000039") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:22] Set("PJSIP/200-00000039", "__REC_STATUS=RECORDING") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:23] Set("PJSIP/200-00000039", "CDR(recordingfile)=out-0017516xxxx4-200-20160201-072154-1454307714.105.wav") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:24] Return("PJSIP/200-00000039", "") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [out@sub-record-check:8] Return("PJSIP/200-00000039", "") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [0017516xxxx4@from-internal:3] ExecIf("PJSIP/200-00000039", "0 ?Set(CDR(accountcode)=)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [0017516xxxx4@from-internal:4] Set("PJSIP/200-00000039", "MOHCLASS=default") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [0017516xxxx4@from-internal:5] Set("PJSIP/200-00000039", "_NODEST=") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [0017516xxxx4@from-internal:6] Macro("PJSIP/200-00000039", "dialout-trunk,1,017516xxxx4,,off") in new stack
[2016-02-01 07:21:54] VERBOSE[32231][C-00000030] app_mixmonitor.c: Begin MixMonitor Recording PJSIP/200-00000039
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:1] Set("PJSIP/200-00000039", "DIAL_TRUNK=1") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf("PJSIP/200-00000039", "0?sub-pincheck,s,1()") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:3] GotoIf("PJSIP/200-00000039", "0?disabletrunk,1") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:4] Set("PJSIP/200-00000039", "DIAL_NUMBER=017516xxxx4") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:5] Set("PJSIP/200-00000039", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:6] Set("PJSIP/200-00000039", "OUTBOUND_GROUP=OUT_1") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf("PJSIP/200-00000039", "0?nomax") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:8] GotoIf("PJSIP/200-00000039", "0?chanfull") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf("PJSIP/200-00000039", "0?skipoutcid") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:10] Set("PJSIP/200-00000039", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:11] Macro("PJSIP/200-00000039", "outbound-callerid,1") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-outbound-callerid:1] ExecIf("PJSIP/200-00000039", "0?Set(CALLERPRES(name-pres)=)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-outbound-callerid:2] ExecIf("PJSIP/200-00000039", "0?Set(CALLERPRES(num-pres)=)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-outbound-callerid:3] ExecIf("PJSIP/200-00000039", "0?Set(REALCALLERIDNUM=200)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-outbound-callerid:4] GotoIf("PJSIP/200-00000039", "1?normcid") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Goto (macro-outbound-callerid,s,7)
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-outbound-callerid:7] Set("PJSIP/200-00000039", "USEROUTCID=Robert Schuster <4922897xxx10>") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-outbound-callerid:8] Set("PJSIP/200-00000039", "EMERGENCYCID=") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-outbound-callerid:9] Set("PJSIP/200-00000039", "TRUNKOUTCID=") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-outbound-callerid:10] GotoIf("PJSIP/200-00000039", "1?trunkcid") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Goto (macro-outbound-callerid,s,15)
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("PJSIP/200-00000039", "0?Set(CALLERID(all)=)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-outbound-callerid:16] ExecIf("PJSIP/200-00000039", "1?Set(CALLERID(all)=Robert Schuster <4922897xxx10>)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-outbound-callerid:17] ExecIf("PJSIP/200-00000039", "0?Set(CALLERID(all)=)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-outbound-callerid:18] ExecIf("PJSIP/200-00000039", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-outbound-callerid:19] ExecIf("PJSIP/200-00000039", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-outbound-callerid:20] Set("PJSIP/200-00000039", "CDR(outbound_cnum)=4922897xxx10") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-outbound-callerid:21] Set("PJSIP/200-00000039", "CDR(outbound_cnam)=Robert Schuster") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:12] GosubIf("PJSIP/200-00000039", "0?sub-flp-1,s,1()") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:13] Set("PJSIP/200-00000039", "OUTNUM=017516xxxx4") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:14] Set("PJSIP/200-00000039", "custom=SIP/sipgate") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:15] ExecIf("PJSIP/200-00000039", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf("PJSIP/200-00000039", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:17] Macro("PJSIP/200-00000039", "dialout-trunk-predial-hook,") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("PJSIP/200-00000039", "") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:18] GotoIf("PJSIP/200-00000039", "0?bypass,1") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:19] ExecIf("PJSIP/200-00000039", "1?Set(CONNECTEDLINE(num,i)=017516xxxx4)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf("PJSIP/200-00000039", "1?Set(CONNECTEDLINE(name,i)=CID:4922897xxx10)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("PJSIP/200-00000039", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)4922897xxx10)") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:22] GotoIf("PJSIP/200-00000039", "0?customtrunk") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-dialout-trunk:23] Dial("PJSIP/200-00000039", "SIP/sipgate/017516xxxx4,300,Tt") in new stack
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] netsock2.c: Using SIP RTP TOS bits 184
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] netsock2.c: Using SIP RTP CoS mark 5
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] chan_sip.c: Audio is at 14542
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] chan_sip.c: Adding codec ulaw to SDP
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] chan_sip.c: Adding codec alaw to SDP
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] chan_sip.c: Reliably Transmitting (NAT) to 217.10.68.150:5060:
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 212.79.184.96:5061;branch=z9hG4bK28055b0c;rport
Max-Forwards: 70
From: "Robert Schuster" <sip:[email protected]:5061>;tag=as7662db02
To: <sip:[email protected]:5060>
Contact: <sip:[email protected]:5061>
Call-ID: [email protected]
CSeq: 102 INVITE
User-Agent: FPBX-13.0.54(13.5.0)
Date: Mon, 01 Feb 2016 06:21:54 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 277
v=0
o=root 1657529415 1657529415 IN IP4 212.79.184.96
s=Asterisk PBX 13.5.0
c=IN IP4 212.79.184.96
t=0 0
m=audio 14542 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv
---
[2016-02-01 07:21:54] VERBOSE[32230][C-00000030] app_dial.c: Called SIP/sipgate/017516xxxx4
[2016-02-01 07:21:54] VERBOSE[2005] chan_sip.c:
<--- SIP read from UDP:217.10.68.150:5060 --->
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 212.79.184.96:5061;received=212.79.184.96;branch=z9hG4bK28055b0c;rport=5061
From: "Robert Schuster" <sip:[email protected]:5061>;tag=as7662db02
To: <sip:[email protected]:5060>;tag=bd5993ef28b67e3a282c182de47698f4.76a5
Call-ID: [email protected]
CSeq: 102 INVITE
Proxy-Authenticate: Digest realm="sipconnect.sipgate.de", nonce="Vq76rlau+YIbQB250S+BOwgFiCnEzckD"
Content-Length: 0
<------------->
[2016-02-01 07:21:54] VERBOSE[2005] chan_sip.c: --- (8 headers 0 lines) ---
[2016-02-01 07:21:54] VERBOSE[2005][C-00000030] chan_sip.c: Transmitting (NAT) to 217.10.68.150:5060:
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 212.79.184.96:5061;branch=z9hG4bK28055b0c;rport
Max-Forwards: 70
From: "Robert Schuster" <sip:[email protected]:5061>;tag=as7662db02
To: <sip:[email protected]:5060>;tag=bd5993ef28b67e3a282c182de47698f4.76a5
Contact: <sip:[email protected]:5061>
Call-ID: [email protected]
CSeq: 102 ACK
User-Agent: FPBX-13.0.54(13.5.0)
Content-Length: 0
---
[2016-02-01 07:21:54] VERBOSE[2005][C-00000030] chan_sip.c: Audio is at 14542
[2016-02-01 07:21:54] VERBOSE[2005][C-00000030] chan_sip.c: Adding codec ulaw to SDP
[2016-02-01 07:21:54] VERBOSE[2005][C-00000030] chan_sip.c: Adding codec alaw to SDP
[2016-02-01 07:21:54] VERBOSE[2005][C-00000030] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[2016-02-01 07:21:54] VERBOSE[2005][C-00000030] chan_sip.c: Reliably Transmitting (NAT) to 217.10.68.150:5060:
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 212.79.184.96:5061;branch=z9hG4bK7706c9c1;rport
Max-Forwards: 70
From: "Robert Schuster" <sip:[email protected]:5061>;tag=as7662db02
To: <sip:[email protected]:5060>
Contact: <sip:[email protected]:5061>
Call-ID: [email protected]
CSeq: 103 INVITE
User-Agent: FPBX-13.0.54(13.5.0)
Proxy-Authorization: Digest username="xxxxxxxxx", realm="sipconnect.sipgate.de", algorithm=MD5, uri="sip:[email protected]:5060", nonce="Vq76rlau+YIbQB250S+BOwgFiCnEzckD", response="39ad3affd3d369c0e1508ec0def29785"
Date: Mon, 01 Feb 2016 06:21:54 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 277
v=0
o=root 1657529415 1657529416 IN IP4 212.79.184.96
s=Asterisk PBX 13.5.0
c=IN IP4 212.79.184.96
t=0 0
m=audio 14542 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv
---
[2016-02-01 07:21:54] VERBOSE[2005] chan_sip.c:
<--- SIP read from UDP:217.10.68.150:5060 --->
SIP/2.0 100 trying -- your call is important to us
Via: SIP/2.0/UDP 212.79.184.96:5061;received=212.79.184.96;branch=z9hG4bK7706c9c1;rport=5061
From: "Robert Schuster" <sip:[email protected]:5061>;tag=as7662db02
To: <sip:[email protected]:5060>
Call-ID: [email protected]
CSeq: 103 INVITE
Content-Length: 0
<------------->
[2016-02-01 07:21:54] VERBOSE[2005] chan_sip.c: --- (7 headers 0 lines) ---
[2016-02-01 07:21:59] VERBOSE[2005] chan_sip.c:
<--- SIP read from UDP:217.10.68.150:5060 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 212.79.184.96:5061;received=212.79.184.96;branch=z9hG4bK7706c9c1;rport=5061
Record-Route: <sip:172.20.40.5;lr>
Record-Route: <sip:217.10.68.150;lr;ftag=as7662db02>
From: "Robert Schuster" <sip:[email protected]:5061>;tag=as7662db02
To: <sip:[email protected]:5060>;tag=as216fcbb4
Call-ID: [email protected]
CSeq: 103 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces
Contact: <sip:[email protected]:5060>
Content-Length: 0
<------------->
[2016-02-01 07:21:59] VERBOSE[2005] chan_sip.c: --- (12 headers 0 lines) ---
[2016-02-01 07:21:59] VERBOSE[2005][C-00000030] sip/route.c: sip_route_dump: route/path hop: <sip:217.10.68.150;lr;ftag=as7662db02>
[2016-02-01 07:21:59] VERBOSE[2005][C-00000030] sip/route.c: sip_route_dump: route/path hop: <sip:172.20.40.5;lr>
[2016-02-01 07:21:59] VERBOSE[32230][C-00000030] app_dial.c: SIP/sipgate-00000030 is ringing
[2016-02-01 07:21:59] VERBOSE[3225] res_pjsip_logger.c: <--- Transmitting SIP response (534 bytes) to UDP:192.168.120.35:2048 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.120.35:2048;rport=2048;received=192.168.120.35;branch=z9hG4bK-xo0im76s8pp8
Call-ID: 56aef9812d30-xr7b5681y73l
From: "Robert Schuster" <sip:[email protected]>;tag=p3lvdf2l3c
To: <sip:[email protected];user=phone>;tag=oG99649DzB-UEI2b-INGnhhZwR93RIBw
CSeq: 2 INVITE
Server: FPBX-13.0.54(13.5.0)
Contact: <sip:212.79.184.96:5060>
Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REGISTER, REFER
Content-Length: 0
[2016-02-01 07:21:59] VERBOSE[2005] chan_sip.c:
<--- SIP read from UDP:217.10.68.150:5060 --->
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 212.79.184.96:5061;received=212.79.184.96;branch=z9hG4bK7706c9c1;rport=5061
Record-Route: <sip:172.20.40.5;lr>
Record-Route: <sip:217.10.68.150;lr;ftag=as7662db02>
From: "Robert Schuster" <sip:[email protected]:5061>;tag=as7662db02
To: <sip:[email protected]:5060>;tag=as216fcbb4
Call-ID: [email protected]
CSeq: 103 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces
Contact: <sip:[email protected]:5060>
Content-Type: application/sdp
Content-Length: 282
v=0
o=root 1115461770 1115461770 IN IP4 217.10.67.11
s=sipgate VoIP GW
c=IN IP4 217.10.67.11
t=0 0
m=audio 26488 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
<------------->
[2016-02-01 07:21:59] VERBOSE[2005] chan_sip.c: --- (13 headers 13 lines) ---
[2016-02-01 07:21:59] VERBOSE[2005][C-00000030] sip/route.c: sip_route_dump: route/path hop: <sip:217.10.68.150;lr;ftag=as7662db02>
[2016-02-01 07:21:59] VERBOSE[2005][C-00000030] sip/route.c: sip_route_dump: route/path hop: <sip:172.20.40.5;lr>
[2016-02-01 07:21:59] VERBOSE[2005][C-00000030] chan_sip.c: Found RTP audio format 8
[2016-02-01 07:21:59] VERBOSE[2005][C-00000030] chan_sip.c: Found RTP audio format 0
[2016-02-01 07:21:59] VERBOSE[2005][C-00000030] chan_sip.c: Found RTP audio format 101
[2016-02-01 07:21:59] VERBOSE[2005][C-00000030] chan_sip.c: Found audio description format PCMA for ID 8
[2016-02-01 07:21:59] VERBOSE[2005][C-00000030] chan_sip.c: Found audio description format PCMU for ID 0
[2016-02-01 07:21:59] VERBOSE[2005][C-00000030] chan_sip.c: Found audio description format telephone-event for ID 101
[2016-02-01 07:21:59] VERBOSE[2005][C-00000030] chan_sip.c: Capabilities: us - (alaw|ulaw), peer - audio=(ulaw|alaw)/video=(nothing)/text=(nothing), combined - (alaw|ulaw)
[2016-02-01 07:21:59] VERBOSE[2005][C-00000030] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
[2016-02-01 07:21:59] VERBOSE[2005][C-00000030] chan_sip.c: Peer audio RTP is at port 217.10.67.11:26488
[2016-02-01 07:21:59] VERBOSE[32230][C-00000030] app_dial.c: SIP/sipgate-00000030 is making progress passing it to PJSIP/200-00000039
[2016-02-01 07:21:59] VERBOSE[18763] res_pjsip_logger.c: <--- Transmitting SIP response (890 bytes) to UDP:192.168.120.35:2048 --->
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 192.168.120.35:2048;rport=2048;received=192.168.120.35;branch=z9hG4bK-xo0im76s8pp8
Call-ID: 56aef9812d30-xr7b5681y73l
From: "Robert Schuster" <sip:[email protected]>;tag=p3lvdf2l3c
To: <sip:[email protected];user=phone>;tag=oG99649DzB-UEI2b-INGnhhZwR93RIBw
CSeq: 2 INVITE
Server: FPBX-13.0.54(13.5.0)
Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REGISTER, REFER
Contact: <sip:212.79.184.96:5060>
Content-Type: application/sdp
Content-Length: 313
v=0
o=- 250730445 250730447 IN IP4 212.79.184.96
s=Asterisk
c=IN IP4 212.79.184.96
t=0 0
m=audio 14166 RTP/AVP 0 8 3 99 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv
[2016-02-01 07:22:02] VERBOSE[3225] res_pjsip_logger.c: <--- Transmitting SIP request (478 bytes) to UDP:192.168.120.25:5060 --->
OPTIONS sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 212.79.184.96:5060;rport;branch=z9hG4bKPjLO1mLJgXWahhbq1h-0FkGF8OKQ5YllnM
From: <sip:[email protected]>;tag=c.6pHdUYJkIQxB5M96IJN7QF-4rtC3Uf
To: <sip:[email protected]>
Contact: <sip:[email protected]:5060>
Call-ID: i1MqlLlEBqP1Yibh0RMRJ9Mn-H.7qeaF
CSeq: 57813 OPTIONS
Max-Forwards: 70
User-Agent: FPBX-13.0.54(13.5.0)
Content-Length: 0
[2016-02-01 07:22:02] VERBOSE[1991] res_pjsip_logger.c: <--- Received SIP response (651 bytes) from UDP:192.168.120.25:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 212.79.184.96:5060;rport=5060;branch=z9hG4bKPjLO1mLJgXWahhbq1h-0FkGF8OKQ5YllnM
From: <sip:[email protected]>;tag=c.6pHdUYJkIQxB5M96IJN7QF-4rtC3Uf
To: <sip:[email protected]>;tag=b.7qIeTXKjHPyC4L87HKO6PG-5suB2Tg
Call-ID: i1MqlLlEBqP1Yibh0RMRJ9Mn-H.7qeaF
CSeq: 57813 OPTIONS
Supported: replaces
User-Agent: N510 IP PRO/42.076.00.000.000
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag
Accept-Encoding: identity
Accept-Language: en
Content-Length: 0
[2016-02-01 07:22:02] VERBOSE[1991] res_pjsip_logger.c: <--- Received SIP request (409 bytes) from UDP:192.168.120.35:2048 --->
CANCEL sip:[email protected];user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.120.35:2048;branch=z9hG4bK-xo0im76s8pp8;rport
From: "Robert Schuster" <sip:[email protected]>;tag=p3lvdf2l3c
To: <sip:[email protected];user=phone>
Call-ID: 56aef9812d30-xr7b5681y73l
CSeq: 2 CANCEL
Max-Forwards: 70
Reason: SIP;cause=487;text="Request terminated by user"
Content-Length: 0
[2016-02-01 07:22:02] VERBOSE[3225] res_pjsip_logger.c: <--- Transmitting SIP response (383 bytes) to UDP:192.168.120.35:2048 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.120.35:2048;rport=2048;received=192.168.120.35;branch=z9hG4bK-xo0im76s8pp8
Call-ID: 56aef9812d30-xr7b5681y73l
From: "Robert Schuster" <sip:[email protected]>;tag=p3lvdf2l3c
To: <sip:[email protected];user=phone>;tag=oG99649DzB-UEI2b-INGnhhZwR93RIBw
CSeq: 2 CANCEL
Server: FPBX-13.0.54(13.5.0)
Content-Length: 0
[2016-02-01 07:22:02] VERBOSE[3225] res_pjsip_logger.c: <--- Transmitting SIP response (510 bytes) to UDP:192.168.120.35:2048 --->
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 192.168.120.35:2048;rport=2048;received=192.168.120.35;branch=z9hG4bK-xo0im76s8pp8
Call-ID: 56aef9812d30-xr7b5681y73l
From: "Robert Schuster" <sip:[email protected]>;tag=p3lvdf2l3c
To: <sip:[email protected];user=phone>;tag=oG99649DzB-UEI2b-INGnhhZwR93RIBw
CSeq: 2 INVITE
Server: FPBX-13.0.54(13.5.0)
Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REGISTER, REFER
Content-Length: 0
[2016-02-01 07:22:02] VERBOSE[32230][C-00000030] chan_sip.c: Scheduling destruction of SIP dialog '[email protected]' in 32000 ms (Method: INVITE)
[2016-02-01 07:22:02] VERBOSE[32230][C-00000030] chan_sip.c: Reliably Transmitting (NAT) to 217.10.68.150:5060:
CANCEL sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 212.79.184.96:5061;branch=z9hG4bK7706c9c1;rport
Max-Forwards: 70
From: "Robert Schuster" <sip:[email protected]:5061>;tag=as7662db02
To: <sip:[email protected]:5060>
Call-ID: [email protected]
CSeq: 103 CANCEL
User-Agent: FPBX-13.0.54(13.5.0)
Content-Length: 0
---
[2016-02-01 07:22:02] VERBOSE[32230][C-00000030] chan_sip.c: Scheduling destruction of SIP dialog '[email protected]' in 32000 ms (Method: INVITE)
[2016-02-01 07:22:02] VERBOSE[32230][C-00000030] app_macro.c: Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'PJSIP/200-00000039' in macro 'dialout-trunk'
[2016-02-01 07:22:02] VERBOSE[32230][C-00000030] pbx.c: Spawn extension (from-internal, 0017516xxxx4, 6) exited non-zero on 'PJSIP/200-00000039'
[2016-02-01 07:22:02] VERBOSE[32230][C-00000030] pbx.c: Executing [h@from-internal:1] Macro("PJSIP/200-00000039", "hangupcall") in new stack
[2016-02-01 07:22:02] VERBOSE[2005] chan_sip.c:
<--- SIP read from UDP:217.10.68.150:5060 --->
SIP/2.0 200 canceling
Via: SIP/2.0/UDP 212.79.184.96:5061;received=212.79.184.96;branch=z9hG4bK7706c9c1;rport=5061
From: "Robert Schuster" <sip:[email protected]:5061>;tag=as7662db02
To: <sip:[email protected]:5060>;tag=b023696d0e38355785338c7ec56341c9-98c6
Call-ID: [email protected]
CSeq: 103 CANCEL
Content-Length: 0
<------------->
[2016-02-01 07:22:02] VERBOSE[2005] chan_sip.c: --- (7 headers 0 lines) ---
[2016-02-01 07:22:02] VERBOSE[2005] chan_sip.c:
<--- SIP read from UDP:217.10.68.150:5060 --->
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 212.79.184.96:5061;received=212.79.184.96;branch=z9hG4bK7706c9c1;rport=5061
From: "Robert Schuster" <sip:[email protected]:5061>;tag=as7662db02
To: <sip:[email protected]:5060>;tag=as216fcbb4
Call-ID: [email protected]
CSeq: 103 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces
Content-Length: 0
<------------->
[2016-02-01 07:22:02] VERBOSE[2005] chan_sip.c: --- (9 headers 0 lines) ---
[2016-02-01 07:22:02] VERBOSE[2005][C-00000030] chan_sip.c: Transmitting (NAT) to 217.10.68.150:5060:
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 212.79.184.96:5061;branch=z9hG4bK7706c9c1;rport
Max-Forwards: 70
From: "Robert Schuster" <sip:[email protected]:5061>;tag=as7662db02
To: <sip:[email protected]:5060>;tag=as216fcbb4
Contact: <sip:[email protected]:5061>
Call-ID: [email protected]
CSeq: 103 ACK
User-Agent: FPBX-13.0.54(13.5.0)
Content-Length: 0
---
[2016-02-01 07:22:02] VERBOSE[2005][C-00000030] chan_sip.c: Scheduling destruction of SIP dialog '[email protected]' in 32000 ms (Method: INVITE)
[2016-02-01 07:22:02] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/200-00000039", "1?theend") in new stack
[2016-02-01 07:22:02] VERBOSE[32230][C-00000030] pbx.c: Goto (macro-hangupcall,s,3)
[2016-02-01 07:22:02] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/200-00000039", "0?Set(CDR(recordingfile)=)") in new stack
[2016-02-01 07:22:02] VERBOSE[32230][C-00000030] pbx.c: Executing [s@macro-hangupcall:4] Hangup("PJSIP/200-00000039", "") in new stack
[2016-02-01 07:22:02] VERBOSE[32230][C-00000030] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'PJSIP/200-00000039' in macro 'hangupcall'
[2016-02-01 07:22:02] VERBOSE[32230][C-00000030] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/200-00000039'
[2016-02-01 07:22:02] VERBOSE[32231][C-00000030] app_mixmonitor.c: MixMonitor close filestream (mixed)
[2016-02-01 07:22:02] VERBOSE[32231][C-00000030] app_mixmonitor.c: End MixMonitor Recording PJSIP/200-00000039
[2016-02-01 07:22:02] VERBOSE[1991] res_pjsip_logger.c: <--- Received SIP request (446 bytes) from UDP:192.168.120.35:2048 --->
ACK sip:[email protected];user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.120.35:2048;branch=z9hG4bK-xo0im76s8pp8;rport
From: "Robert Schuster" <sip:[email protected]>;tag=p3lvdf2l3c
To: <sip:[email protected];user=phone>;tag=oG99649DzB-UEI2b-INGnhhZwR93RIBw
Call-ID: 56aef9812d30-xr7b5681y73l
CSeq: 2 ACK
Max-Forwards: 70
Contact: <sip:[email protected]:2048;line=je0exdpn>;reg-id=1
Content-Length: 0
[2016-02-01 07:22:03] VERBOSE[2005] chan_sip.c:
<--- SIP read from UDP:217.10.68.150:5060 --->
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 212.79.184.96:5061;received=212.79.184.96;branch=z9hG4bK7706c9c1;rport=5061
From: "Robert Schuster" <sip:[email protected]:5061>;tag=as7662db02
To: <sip:[email protected]:5060>;tag=as216fcbb4
Call-ID: [email protected]
CSeq: 103 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces
Content-Length: 0
<------------->
[2016-02-01 07:22:03] VERBOSE[2005] chan_sip.c: --- (9 headers 0 lines) ---
[2016-02-01 07:22:03] VERBOSE[2005][C-00000030] chan_sip.c: Transmitting (NAT) to 217.10.68.150:5060:
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 212.79.184.96:5061;branch=z9hG4bK7706c9c1;rport
Route: <sip:217.10.68.150;lr;ftag=as7662db02>,<sip:172.20.40.5;lr>
Max-Forwards: 70
From: "Robert Schuster" <sip:[email protected]:5061>;tag=as7662db02
To: <sip:[email protected]:5060>;tag=as216fcbb4
Contact: <sip:[email protected]:5061>
Call-ID: [email protected]
CSeq: 103 ACK
User-Agent: FPBX-13.0.54(13.5.0)
Content-Length: 0
---