[2014-11-05 15:50:52] Asterisk 13.0.0 built by root @ localhost.localdomain on a x86_64 running Linux on 2014-11-05 11:40:23 UTC [2014-11-05 15:50:52] VERBOSE[2997] config.c: Parsing '/etc/asterisk/logger.conf': Found [2014-11-05 15:50:52] VERBOSE[2997] config.c: Parsing '/etc/asterisk/logger_general_additional.conf': Found [2014-11-05 15:50:52] VERBOSE[2997] config.c: Parsing '/etc/asterisk/logger_general_custom.conf': Found [2014-11-05 15:50:52] VERBOSE[2997] config.c: Parsing '/etc/asterisk/logger_logfiles_additional.conf': Found [2014-11-05 15:50:52] VERBOSE[2997] config.c: Parsing '/etc/asterisk/logger_logfiles_custom.conf': Found [2014-11-05 15:50:52] VERBOSE[2997] logger.c: Asterisk Queue Logger restarted [2014-11-05 15:50:56] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP request (872 bytes) from UDP:192.168.1.4:5065 ---> INVITE sip:601@192.168.1.8:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.4:5065;branch=z9hG4bK1006547255 From: "602" ;tag=361930255 To: Call-ID: 1504683635@192.168.1.4 CSeq: 1 INVITE Contact: Content-Type: application/sdp Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE Max-Forwards: 70 User-Agent: Yealink SIP-T46G 28.71.0.170 Supported: replaces Allow-Events: talk,hold,conference,refer,check-sync Content-Length: 304 v=0 o=- 20261 20261 IN IP4 192.168.1.4 s=SDP data c=IN IP4 192.168.1.4 t=0 0 m=audio 11798 RTP/AVP 0 8 18 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:9 G722/8000 a=fmtp:101 0-15 a=rtpmap:101 telephone-event/8000 a=ptime:20 a=sendrecv [2014-11-05 15:50:56] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP response (463 bytes) to UDP:192.168.1.4:5065 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.4:5065;rport=5065;received=192.168.1.4;branch=z9hG4bK1006547255 Call-ID: 1504683635@192.168.1.4 From: "602" ;tag=361930255 To: ;tag=z9hG4bK1006547255 CSeq: 1 INVITE WWW-Authenticate: Digest realm="asterisk",nonce="1415199056/ec2db37bfe752a52d78a6dff25218c01",opaque="2fa882ee2acef9e9",algorithm=md5,qop="auth" Server: FPBX-12.0.6(13.0.0) Content-Length: 0 [2014-11-05 15:50:56] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP request (263 bytes) from UDP:192.168.1.4:5065 ---> ACK sip:601@192.168.1.8:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.4:5065;branch=z9hG4bK1006547255 From: "602" ;tag=361930255 To: ;tag=z9hG4bK1006547255 Call-ID: 1504683635@192.168.1.4 CSeq: 1 ACK Content-Length: 0 [2014-11-05 15:50:56] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP request (1142 bytes) from UDP:192.168.1.4:5065 ---> INVITE sip:601@192.168.1.8:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.4:5065;branch=z9hG4bK2094505297 From: "602" ;tag=361930255 To: Call-ID: 1504683635@192.168.1.4 CSeq: 2 INVITE Contact: Authorization: Digest username="602", realm="asterisk", nonce="1415199056/ec2db37bfe752a52d78a6dff25218c01", uri="sip:601@192.168.1.8:5060", response="6e018097f2f338661b65d99e81bd52c5", algorithm=MD5, cnonce="0a4f113b", opaque="2fa882ee2acef9e9", qop=auth, nc=00000001 Content-Type: application/sdp Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE Max-Forwards: 70 User-Agent: Yealink SIP-T46G 28.71.0.170 Supported: replaces Allow-Events: talk,hold,conference,refer,check-sync Content-Length: 304 v=0 o=- 20261 20261 IN IP4 192.168.1.4 s=SDP data c=IN IP4 192.168.1.4 t=0 0 m=audio 11798 RTP/AVP 0 8 18 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:9 G722/8000 a=fmtp:101 0-15 a=rtpmap:101 telephone-event/8000 a=ptime:20 a=sendrecv [2014-11-05 15:50:56] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP response (288 bytes) to UDP:192.168.1.4:5065 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.4:5065;rport=5065;received=192.168.1.4;branch=z9hG4bK2094505297 Call-ID: 1504683635@192.168.1.4 From: "602" ;tag=361930255 To: CSeq: 2 INVITE Server: FPBX-12.0.6(13.0.0) Content-Length: 0 [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [601@from-internal:1] GotoIf("PJSIP/602-00000003", "1?ext-local,601,1") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Goto (ext-local,601,1) [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [601@ext-local:1] Set("PJSIP/602-00000003", "__RINGTIMER=15") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [601@ext-local:2] Macro("PJSIP/602-00000003", "exten-vm,novm,601,0,0,0") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-exten-vm:1] Macro("PJSIP/602-00000003", "user-callerid,") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/602-00000003", "TOUCH_MONITOR=1415199056.21") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/602-00000003", "AMPUSER=602") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("PJSIP/602-00000003", "0?report") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("PJSIP/602-00000003", "1?Set(REALCALLERIDNUM=602)") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/602-00000003", "AMPUSER=602") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("PJSIP/602-00000003", "0?limit") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/602-00000003", "AMPUSERCIDNAME=Int - 602") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("PJSIP/602-00000003", "0?report") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/602-00000003", "AMPUSERCID=602") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/602-00000003", "__DIAL_OPTIONS=Ttr") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:11] Set("PJSIP/602-00000003", "CALLERID(all)="Int - 602" <602>") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:12] GotoIf("PJSIP/602-00000003", "0?limit") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/602-00000003", "0?Set(GROUP(concurrency_limit)=602)") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:14] GosubIf("PJSIP/602-00000003", "7?sub-ccss,s,1(macro-exten-vm,)") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-ccss:1] ExecIf("PJSIP/602-00000003", "0?Return()") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-ccss:2] Set("PJSIP/602-00000003", "CCSS_SETUP=TRUE") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-ccss:3] GosubIf("PJSIP/602-00000003", "0?monitor_config,1(macro-exten-vm,):monitor_default,1(macro-exten-vm,)") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [monitor_default@sub-ccss:1] GotoIf("PJSIP/602-00000003", "0?is_exten") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [monitor_default@sub-ccss:2] StackPop("PJSIP/602-00000003", "") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [monitor_default@sub-ccss:3] Return("PJSIP/602-00000003", "FALSE") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:15] ExecIf("PJSIP/602-00000003", "1?Set(CHANNEL(language)=it)") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("PJSIP/602-00000003", "0?continue") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:17] ExecIf("PJSIP/602-00000003", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:18] Set("PJSIP/602-00000003", "__TTL=64") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("PJSIP/602-00000003", "1?continue") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Goto (macro-user-callerid,s,30) [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:30] Set("PJSIP/602-00000003", "CALLERID(number)=602") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:31] Set("PJSIP/602-00000003", "CALLERID(name)=Int - 602") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:32] Set("PJSIP/602-00000003", "CDR(cnum)=602") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:33] Set("PJSIP/602-00000003", "CDR(cnam)=Int - 602") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-user-callerid:34] Set("PJSIP/602-00000003", "CHANNEL(language)=it") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-exten-vm:2] Set("PJSIP/602-00000003", "RingGroupMethod=none") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-exten-vm:3] Set("PJSIP/602-00000003", "__EXTTOCALL=601") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-exten-vm:4] Set("PJSIP/602-00000003", "__PICKUPMARK=601") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-exten-vm:5] Set("PJSIP/602-00000003", "RT=") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-exten-vm:6] Gosub("PJSIP/602-00000003", "sub-record-check,s,1(exten,601,)") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-record-check:1] Set("PJSIP/602-00000003", "REC_POLICY_MODE_SAVE=") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-record-check:2] GotoIf("PJSIP/602-00000003", "1?check") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Goto (sub-record-check,s,7) [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/602-00000003", "__MON_FMT=wav") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-record-check:8] GotoIf("PJSIP/602-00000003", "1?next") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Goto (sub-record-check,s,11) [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/602-00000003", "0?Return()") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-record-check:12] ExecIf("PJSIP/602-00000003", "0?Set(__REC_POLICY_MODE=)") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-record-check:13] GotoIf("PJSIP/602-00000003", "0?exten,1") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-record-check:14] Set("PJSIP/602-00000003", "__REC_STATUS=INITIALIZED") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-record-check:15] Set("PJSIP/602-00000003", "NOW=1415199056") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-record-check:16] Set("PJSIP/602-00000003", "__DAY=05") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-record-check:17] Set("PJSIP/602-00000003", "__MONTH=11") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-record-check:18] Set("PJSIP/602-00000003", "__YEAR=2014") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-record-check:19] Set("PJSIP/602-00000003", "__TIMESTR=20141105-155056") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-record-check:20] Set("PJSIP/602-00000003", "__FROMEXTEN=602") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-record-check:21] Set("PJSIP/602-00000003", "__CALLFILENAME=exten-601-602-20141105-155056-1415199056.21") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-record-check:22] Goto("PJSIP/602-00000003", "exten,1") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Goto (sub-record-check,exten,1) [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [exten@sub-record-check:1] GotoIf("PJSIP/602-00000003", "0?callee") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [exten@sub-record-check:2] Set("PJSIP/602-00000003", "__REC_POLICY_MODE=dontcare") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [exten@sub-record-check:3] GotoIf("PJSIP/602-00000003", "1?caller") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Goto (sub-record-check,exten,10) [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [exten@sub-record-check:10] Set("PJSIP/602-00000003", "__REC_POLICY_MODE=dontcare") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [exten@sub-record-check:11] GosubIf("PJSIP/602-00000003", "0?record,1(exten,601,602)") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [exten@sub-record-check:12] Return("PJSIP/602-00000003", "") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-exten-vm:7] GotoIf("PJSIP/602-00000003", "1?macrodial") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Goto (macro-exten-vm,s,13) [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-exten-vm:13] GosubIf("PJSIP/602-00000003", "0?clrheader,1()") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-exten-vm:14] Macro("PJSIP/602-00000003", "dial-one,,Ttr,601") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:1] Set("PJSIP/602-00000003", "DEXTEN=601") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:2] Set("PJSIP/602-00000003", "DIALSTATUS_CW=") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:3] GosubIf("PJSIP/602-00000003", "0?screen,1()") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:4] GosubIf("PJSIP/602-00000003", "0?cf,1()") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:5] GotoIf("PJSIP/602-00000003", "1?skip1") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Goto (macro-dial-one,s,8) [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:8] GotoIf("PJSIP/602-00000003", "0?nodial") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:9] GotoIf("PJSIP/602-00000003", "0?continue") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:10] Set("PJSIP/602-00000003", "EXTHASCW=ENABLED") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:11] GotoIf("PJSIP/602-00000003", "0?next1:cwinusebusy") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Goto (macro-dial-one,s,23) [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:23] GotoIf("PJSIP/602-00000003", "1?next3:continue") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Goto (macro-dial-one,s,24) [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:24] ExecIf("PJSIP/602-00000003", "0?Set(DIALSTATUS_CW=BUSY)") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:25] GotoIf("PJSIP/602-00000003", "0?nodial") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:26] GosubIf("PJSIP/602-00000003", "1?dstring,1():dlocal,1()") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [dstring@macro-dial-one:1] Set("PJSIP/602-00000003", "DSTRING=") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [dstring@macro-dial-one:2] Set("PJSIP/602-00000003", "DEVICES=601") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("PJSIP/602-00000003", "0?Return()") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("PJSIP/602-00000003", "0?Set(DEVICES=01)") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [dstring@macro-dial-one:5] Set("PJSIP/602-00000003", "LOOPCNT=1") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [dstring@macro-dial-one:6] Set("PJSIP/602-00000003", "ITER=1") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [dstring@macro-dial-one:7] Set("PJSIP/602-00000003", "THISDIAL=PJSIP/601") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("PJSIP/602-00000003", "1?zap2dahdi,1()") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("PJSIP/602-00000003", "0?Return()") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("PJSIP/602-00000003", "NEWDIAL=") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("PJSIP/602-00000003", "LOOPCNT2=1") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("PJSIP/602-00000003", "ITER2=1") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("PJSIP/602-00000003", "THISPART2=PJSIP/601") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("PJSIP/602-00000003", "0?Set(THISPART2=DAHDIIP/601)") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("PJSIP/602-00000003", "NEWDIAL=PJSIP/601&") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("PJSIP/602-00000003", "ITER2=2") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("PJSIP/602-00000003", "0?begin2") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("PJSIP/602-00000003", "THISDIAL=PJSIP/601") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("PJSIP/602-00000003", "") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("PJSIP/602-00000003", "0?doset") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [dstring@macro-dial-one:10] NoOp("PJSIP/602-00000003", "Debug: Found PJSIP Destination PJSIP/601, updaing with PJSIP_DIAL_CONTACTS") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [dstring@macro-dial-one:11] Set("PJSIP/602-00000003", "THISDIAL=PJSIP/601/sip:601@192.168.1.11:32768;line=eczln7ba") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [dstring@macro-dial-one:12] Set("PJSIP/602-00000003", "DSTRING=PJSIP/601/sip:601@192.168.1.11:32768;line=eczln7ba&") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [dstring@macro-dial-one:13] Set("PJSIP/602-00000003", "ITER=2") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf("PJSIP/602-00000003", "0?begin") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [dstring@macro-dial-one:15] Set("PJSIP/602-00000003", "DSTRING=PJSIP/601/sip:601@192.168.1.11:32768;line=eczln7ba") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [dstring@macro-dial-one:16] Return("PJSIP/602-00000003", "") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:27] GotoIf("PJSIP/602-00000003", "0?nodial") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:28] GotoIf("PJSIP/602-00000003", "0?skiptrace") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:29] GosubIf("PJSIP/602-00000003", "1?ctset,1():ctclear,1()") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [ctset@macro-dial-one:1] Set("PJSIP/602-00000003", "DB(CALLTRACE/601)=602") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [ctset@macro-dial-one:2] Return("PJSIP/602-00000003", "") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:30] Set("PJSIP/602-00000003", "D_OPTIONS=Ttr") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:31] ExecIf("PJSIP/602-00000003", "0?SIPAddHeader(Alert-Info: )") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:32] ExecIf("PJSIP/602-00000003", "0?SIPAddHeader()") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:33] ExecIf("PJSIP/602-00000003", "0?Set(CHANNEL(musicclass)=)") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:34] GosubIf("PJSIP/602-00000003", "0?qwait,1()") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:35] Set("PJSIP/602-00000003", "__CWIGNORE=") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:36] Set("PJSIP/602-00000003", "__KEEPCID=TRUE") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:37] GotoIf("PJSIP/602-00000003", "0?usegoto,1") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:38] GotoIf("PJSIP/602-00000003", "0?godial") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:39] Gosub("PJSIP/602-00000003", "sub-presencestate-display,s,1(601)") in new stack [2014-11-05 15:50:56] WARNING[3061][C-00000002] func_presencestate.c: PRESENCE_STATE unknown [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@sub-presencestate-display:1] Goto("PJSIP/602-00000003", "state-,1") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Goto (sub-presencestate-display,state-,1) [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [state-@sub-presencestate-display:1] Set("PJSIP/602-00000003", "PRESENCESTATE_DISPLAY=") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [state-@sub-presencestate-display:2] Return("PJSIP/602-00000003", "") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:40] Set("PJSIP/602-00000003", "CONNECTEDLINE(name,i)=Int - 601") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:41] Set("PJSIP/602-00000003", "CONNECTEDLINE(num)=601") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:42] Set("PJSIP/602-00000003", "D_OPTIONS=TtrI") in new stack [2014-11-05 15:50:56] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP response (530 bytes) to UDP:192.168.1.4:5065 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.1.4:5065;rport=5065;received=192.168.1.4;branch=z9hG4bK2094505297 Call-ID: 1504683635@192.168.1.4 From: "602" ;tag=361930255 To: ;tag=e67a4ec5-14e6-4fe9-9c30-bc3492046c46 CSeq: 2 INVITE Server: FPBX-12.0.6(13.0.0) Contact: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REFER, REGISTER, MESSAGE P-Asserted-Identity: "Int - 601" Content-Length: 0 [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] pbx.c: Executing [s@macro-dial-one:43] Dial("PJSIP/602-00000003", "PJSIP/601/sip:601@192.168.1.11:32768;line=eczln7ba,,TtrI") in new stack [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] app_dial.c: Called PJSIP/601/sip:601@192.168.1.11:32768;line=eczln7ba [2014-11-05 15:50:56] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP request (1004 bytes) to UDP:192.168.1.11:32768 ---> INVITE sip:601@192.168.1.11:32768;line=eczln7ba SIP/2.0 Via: SIP/2.0/UDP 192.168.1.8:5060;rport;branch=z9hG4bKPj1e7eb5c3-943e-4284-8e99-04ccd85e16c9 From: "Int - 602" ;tag=555bc7dc-3d7d-4761-80b4-46bd6a83cbf1 To: Contact: Call-ID: 77e2f006-d680-4e4a-b708-cb2fd494e6eb CSeq: 28913 INVITE Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REFER, REGISTER, MESSAGE Supported: 100rel, timer, replaces, norefersub Session-Expires: 1800 Min-SE: 90 P-Asserted-Identity: "Int - 602" Max-Forwards: 70 User-Agent: FPBX-12.0.6(13.0.0) Content-Type: application/sdp Content-Length: 235 v=0 o=- 1828120170 1828120170 IN IP4 192.168.1.8 s=Asterisk c=IN IP4 192.168.1.8 t=0 0 m=audio 15594 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [2014-11-05 15:50:56] VERBOSE[3025] res_pjsip_logger.c: <--- Transmitting SIP response (530 bytes) to UDP:192.168.1.4:5065 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.1.4:5065;rport=5065;received=192.168.1.4;branch=z9hG4bK2094505297 Call-ID: 1504683635@192.168.1.4 From: "602" ;tag=361930255 To: ;tag=e67a4ec5-14e6-4fe9-9c30-bc3492046c46 CSeq: 2 INVITE Server: FPBX-12.0.6(13.0.0) Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REFER, REGISTER, MESSAGE P-Asserted-Identity: "Int - 601" Contact: Content-Length: 0 [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] app_dial.c: Connected line update to PJSIP/602-00000003 prevented. [2014-11-05 15:50:56] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP response (408 bytes) from UDP:192.168.1.11:32768 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.8:5060;rport=5060;branch=z9hG4bKPj1e7eb5c3-943e-4284-8e99-04ccd85e16c9 From: "Int - 602" ;tag=555bc7dc-3d7d-4761-80b4-46bd6a83cbf1 To: ;tag=yy3jfqbm9t Call-ID: 77e2f006-d680-4e4a-b708-cb2fd494e6eb CSeq: 28913 INVITE Contact: ;reg-id=1 Content-Length: 0 [2014-11-05 15:50:56] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP response (577 bytes) from UDP:192.168.1.11:32768 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.1.8:5060;rport=5060;branch=z9hG4bKPj1e7eb5c3-943e-4284-8e99-04ccd85e16c9 From: "Int - 602" ;tag=555bc7dc-3d7d-4761-80b4-46bd6a83cbf1 To: ;tag=yy3jfqbm9t Call-ID: 77e2f006-d680-4e4a-b708-cb2fd494e6eb CSeq: 28913 INVITE Contact: ;reg-id=1 Require: 100rel RSeq: 1 Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE Allow-Events: talk, hold, refer, call-info Content-Length: 0 [2014-11-05 15:50:56] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP request (450 bytes) to UDP:192.168.1.11:32768 ---> PRACK sip:601@192.168.1.11:32768;line=eczln7ba SIP/2.0 Via: SIP/2.0/UDP 192.168.1.8:5060;rport;branch=z9hG4bKPjcd1b15c6-ed77-4610-95fc-ad032e872bd8 From: "Int - 602" ;tag=555bc7dc-3d7d-4761-80b4-46bd6a83cbf1 To: ;tag=yy3jfqbm9t Call-ID: 77e2f006-d680-4e4a-b708-cb2fd494e6eb CSeq: 28914 PRACK RAck: 1 28913 INVITE Max-Forwards: 70 User-Agent: FPBX-12.0.6(13.0.0) Content-Length: 0 [2014-11-05 15:50:56] VERBOSE[3061][C-00000002] app_dial.c: PJSIP/601-00000004 is ringing [2014-11-05 15:50:56] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP response (530 bytes) to UDP:192.168.1.4:5065 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.1.4:5065;rport=5065;received=192.168.1.4;branch=z9hG4bK2094505297 Call-ID: 1504683635@192.168.1.4 From: "602" ;tag=361930255 To: ;tag=e67a4ec5-14e6-4fe9-9c30-bc3492046c46 CSeq: 2 INVITE Server: FPBX-12.0.6(13.0.0) Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REFER, REGISTER, MESSAGE P-Asserted-Identity: "Int - 601" Contact: Content-Length: 0 [2014-11-05 15:50:56] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP response (403 bytes) from UDP:192.168.1.11:32768 ---> SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.1.8:5060;rport=5060;branch=z9hG4bKPjcd1b15c6-ed77-4610-95fc-ad032e872bd8 From: "Int - 602" ;tag=555bc7dc-3d7d-4761-80b4-46bd6a83cbf1 To: ;tag=yy3jfqbm9t Call-ID: 77e2f006-d680-4e4a-b708-cb2fd494e6eb CSeq: 28914 PRACK Contact: ;reg-id=1 Content-Length: 0 [2014-11-05 15:50:58] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP response (933 bytes) from UDP:192.168.1.11:32768 ---> SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.1.8:5060;rport=5060;branch=z9hG4bKPj1e7eb5c3-943e-4284-8e99-04ccd85e16c9 From: "Int - 602" ;tag=555bc7dc-3d7d-4761-80b4-46bd6a83cbf1 To: ;tag=yy3jfqbm9t Call-ID: 77e2f006-d680-4e4a-b708-cb2fd494e6eb CSeq: 28913 INVITE Contact: ;reg-id=1 Require: timer Session-Expires: 1800;refresher=uac User-Agent: snom710/8.7.3.25.5 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 Content-Type: application/sdp Content-Length: 220 v=0 o=root 1871200900 1871200901 IN IP4 192.168.1.11 s=call c=IN IP4 192.168.1.11 t=0 0 m=audio 57452 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv [2014-11-05 15:50:58] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP request (424 bytes) to UDP:192.168.1.11:32768 ---> ACK sip:601@192.168.1.11:32768;line=eczln7ba SIP/2.0 Via: SIP/2.0/UDP 192.168.1.8:5060;rport;branch=z9hG4bKPj3216e445-0ff7-47e0-b3e2-65e0b781b0c7 From: "Int - 602" ;tag=555bc7dc-3d7d-4761-80b4-46bd6a83cbf1 To: ;tag=yy3jfqbm9t Call-ID: 77e2f006-d680-4e4a-b708-cb2fd494e6eb CSeq: 28913 ACK Max-Forwards: 70 User-Agent: FPBX-12.0.6(13.0.0) Content-Length: 0 [2014-11-05 15:50:58] VERBOSE[3061][C-00000002] app_dial.c: PJSIP/601-00000004 answered PJSIP/602-00000003 [2014-11-05 15:50:58] VERBOSE[3061][C-00000002] bridge_channel.c: Channel PJSIP/602-00000003 joined 'simple_bridge' basic-bridge <474a04f8-cea5-48c4-befc-f052daacbf45> [2014-11-05 15:50:58] VERBOSE[3062][C-00000002] bridge_channel.c: Channel PJSIP/601-00000004 joined 'simple_bridge' basic-bridge <474a04f8-cea5-48c4-befc-f052daacbf45> [2014-11-05 15:50:58] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP response (832 bytes) to UDP:192.168.1.4:5065 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.4:5065;rport=5065;received=192.168.1.4;branch=z9hG4bK2094505297 Call-ID: 1504683635@192.168.1.4 From: "602" ;tag=361930255 To: ;tag=e67a4ec5-14e6-4fe9-9c30-bc3492046c46 CSeq: 2 INVITE Server: FPBX-12.0.6(13.0.0) Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REFER, REGISTER, MESSAGE P-Asserted-Identity: "Int - 601" Contact: Supported: 100rel, timer, replaces, norefersub Content-Type: application/sdp Content-Length: 225 v=0 o=- 20261 20263 IN IP4 192.168.1.8 s=Asterisk c=IN IP4 192.168.1.8 t=0 0 m=audio 15884 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [2014-11-05 15:50:58] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP request (375 bytes) from UDP:192.168.1.4:5065 ---> ACK sip:192.168.1.8:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.4:5065;branch=z9hG4bK1531760558 From: "602" ;tag=361930255 To: ;tag=e67a4ec5-14e6-4fe9-9c30-bc3492046c46 Call-ID: 1504683635@192.168.1.4 CSeq: 2 ACK Contact: Max-Forwards: 70 User-Agent: Yealink SIP-T46G 28.71.0.170 Content-Length: 0 [2014-11-05 15:51:02] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP request (1354 bytes) from UDP:192.168.1.11:32768 ---> INVITE sip:6dd11ee3-3424-4dcc-b547-b843bb503df9@192.168.1.8:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.11:32768;branch=z9hG4bK-oy3slkgr279s;rport From: ;tag=yy3jfqbm9t To: "Int - 602" ;tag=555bc7dc-3d7d-4761-80b4-46bd6a83cbf1 Call-ID: 77e2f006-d680-4e4a-b708-cb2fd494e6eb CSeq: 1 INVITE Max-Forwards: 70 Contact: ;reg-id=1 X-Serialnumber: 00041376E0BF P-Key-Flags: resolution="31x13", keys="4" User-Agent: snom710/8.7.3.25.5 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 Require: timer Session-Expires: 1800;refresher=uas Min-SE: 90 Content-Type: application/sdp Content-Length: 487 v=0 o=root 1871200900 1871200902 IN IP4 192.168.1.11 s=call c=IN IP4 192.168.1.11 t=0 0 m=audio 57452 RTP/AVP 9 0 8 3 99 108 18 101 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:7948j4kDocovNgVcSfsrA8rWrqNa8KUVXDg095q5 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=sendonly [2014-11-05 15:51:02] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP response (903 bytes) to UDP:192.168.1.11:32768 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.11:32768;rport=32768;received=192.168.1.11;branch=z9hG4bK-oy3slkgr279s Call-ID: 77e2f006-d680-4e4a-b708-cb2fd494e6eb From: ;tag=yy3jfqbm9t To: "Int - 602" ;tag=555bc7dc-3d7d-4761-80b4-46bd6a83cbf1 CSeq: 1 INVITE Session-Expires: 1800;refresher=uas Contact: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REFER, REGISTER, MESSAGE Supported: 100rel, timer, replaces, norefersub Server: FPBX-12.0.6(13.0.0) Content-Type: application/sdp Content-Length: 235 v=0 o=- 1828120170 1828120171 IN IP4 192.168.1.8 s=Asterisk c=IN IP4 192.168.1.8 t=0 0 m=audio 15594 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=recvonly [2014-11-05 15:51:02] VERBOSE[3061][C-00000002] res_musiconhold.c: Started music on hold, class 'default', on channel 'PJSIP/602-00000003' [2014-11-05 15:51:02] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP request (442 bytes) from UDP:192.168.1.11:32768 ---> ACK sip:6dd11ee3-3424-4dcc-b547-b843bb503df9@192.168.1.8:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.11:32768;branch=z9hG4bK-v2naalkgcvsp;rport From: ;tag=yy3jfqbm9t To: "Int - 602" ;tag=555bc7dc-3d7d-4761-80b4-46bd6a83cbf1 Call-ID: 77e2f006-d680-4e4a-b708-cb2fd494e6eb CSeq: 1 ACK Max-Forwards: 70 Contact: ;reg-id=1 Content-Length: 0 [2014-11-05 15:51:05] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP request (1253 bytes) from UDP:192.168.1.11:32768 ---> INVITE sip:603@192.168.1.8;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.11:32768;branch=z9hG4bK-h70u8gddstx2;rport From: "Int - 601" ;tag=pjnf1x6cbg To: Call-ID: 545a395816e1-vdqexaq8p5rz CSeq: 1 INVITE Max-Forwards: 70 Contact: ;reg-id=1 X-Serialnumber: 00041376E0BF P-Key-Flags: resolution="31x13", keys="4" User-Agent: snom710/8.7.3.25.5 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: 485 v=0 o=root 726372745 726372745 IN IP4 192.168.1.11 s=call c=IN IP4 192.168.1.11 t=0 0 m=audio 53932 RTP/AVP 9 0 8 3 99 108 18 101 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:1aDuWmEfux+ZVBSQHdm8Ivi0rIyaBk5XrDHbdW4T 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 [2014-11-05 15:51:05] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP response (494 bytes) to UDP:192.168.1.11:32768 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.11:32768;rport=32768;received=192.168.1.11;branch=z9hG4bK-h70u8gddstx2 Call-ID: 545a395816e1-vdqexaq8p5rz From: "Int - 601" ;tag=pjnf1x6cbg To: ;tag=z9hG4bK-h70u8gddstx2 CSeq: 1 INVITE WWW-Authenticate: Digest realm="asterisk",nonce="1415199065/73f855feb732c0862140c2cdd11fb344",opaque="25fc8705137c3c22",algorithm=md5,qop="auth" Server: FPBX-12.0.6(13.0.0) Content-Length: 0 [2014-11-05 15:51:06] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP request (384 bytes) from UDP:192.168.1.11:32768 ---> ACK sip:603@192.168.1.8;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.11:32768;branch=z9hG4bK-h70u8gddstx2;rport From: "Int - 601" ;tag=pjnf1x6cbg To: ;tag=z9hG4bK-h70u8gddstx2 Call-ID: 545a395816e1-vdqexaq8p5rz CSeq: 1 ACK Max-Forwards: 70 Contact: ;reg-id=1 Content-Length: 0 [2014-11-05 15:51:06] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP request (1520 bytes) from UDP:192.168.1.11:32768 ---> INVITE sip:603@192.168.1.8;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.11:32768;branch=z9hG4bK-v93kpceglmn8;rport From: "Int - 601" ;tag=pjnf1x6cbg To: Call-ID: 545a395816e1-vdqexaq8p5rz CSeq: 2 INVITE Max-Forwards: 70 Contact: ;reg-id=1 X-Serialnumber: 00041376E0BF P-Key-Flags: resolution="31x13", keys="4" User-Agent: snom710/8.7.3.25.5 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="601",realm="asterisk",nonce="1415199065/73f855feb732c0862140c2cdd11fb344",uri="sip:603@192.168.1.8;user=phone",qop=auth,nc=00000001,cnonce="0255189f",response="60eb9bfe286bd3716b21ada73f9e4bd6",opaque="25fc8705137c3c22",algorithm=MD5 Content-Type: application/sdp Content-Length: 485 v=0 o=root 726372745 726372745 IN IP4 192.168.1.11 s=call c=IN IP4 192.168.1.11 t=0 0 m=audio 53932 RTP/AVP 9 0 8 3 99 108 18 101 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:1aDuWmEfux+ZVBSQHdm8Ivi0rIyaBk5XrDHbdW4T 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 [2014-11-05 15:51:06] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP response (316 bytes) to UDP:192.168.1.11:32768 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.11:32768;rport=32768;received=192.168.1.11;branch=z9hG4bK-v93kpceglmn8 Call-ID: 545a395816e1-vdqexaq8p5rz From: "Int - 601" ;tag=pjnf1x6cbg To: CSeq: 2 INVITE Server: FPBX-12.0.6(13.0.0) Content-Length: 0 [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [603@from-internal:1] GotoIf("PJSIP/601-00000005", "1?ext-local,603,1") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Goto (ext-local,603,1) [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [603@ext-local:1] Set("PJSIP/601-00000005", "__RINGTIMER=15") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [603@ext-local:2] Macro("PJSIP/601-00000005", "exten-vm,novm,603,0,0,0") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-exten-vm:1] Macro("PJSIP/601-00000005", "user-callerid,") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/601-00000005", "TOUCH_MONITOR=1415199066.29") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/601-00000005", "AMPUSER=601") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("PJSIP/601-00000005", "0?report") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("PJSIP/601-00000005", "1?Set(REALCALLERIDNUM=601)") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/601-00000005", "AMPUSER=601") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("PJSIP/601-00000005", "0?limit") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/601-00000005", "AMPUSERCIDNAME=Int - 601") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("PJSIP/601-00000005", "0?report") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/601-00000005", "AMPUSERCID=601") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/601-00000005", "__DIAL_OPTIONS=Ttr") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:11] Set("PJSIP/601-00000005", "CALLERID(all)="Int - 601" <601>") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:12] GotoIf("PJSIP/601-00000005", "0?limit") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/601-00000005", "0?Set(GROUP(concurrency_limit)=601)") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:14] GosubIf("PJSIP/601-00000005", "7?sub-ccss,s,1(macro-exten-vm,)") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-ccss:1] ExecIf("PJSIP/601-00000005", "0?Return()") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-ccss:2] Set("PJSIP/601-00000005", "CCSS_SETUP=TRUE") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-ccss:3] GosubIf("PJSIP/601-00000005", "0?monitor_config,1(macro-exten-vm,):monitor_default,1(macro-exten-vm,)") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [monitor_default@sub-ccss:1] GotoIf("PJSIP/601-00000005", "0?is_exten") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [monitor_default@sub-ccss:2] StackPop("PJSIP/601-00000005", "") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [monitor_default@sub-ccss:3] Return("PJSIP/601-00000005", "FALSE") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:15] ExecIf("PJSIP/601-00000005", "1?Set(CHANNEL(language)=it)") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("PJSIP/601-00000005", "0?continue") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:17] ExecIf("PJSIP/601-00000005", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:18] Set("PJSIP/601-00000005", "__TTL=64") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("PJSIP/601-00000005", "1?continue") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Goto (macro-user-callerid,s,30) [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:30] Set("PJSIP/601-00000005", "CALLERID(number)=601") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:31] Set("PJSIP/601-00000005", "CALLERID(name)=Int - 601") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:32] Set("PJSIP/601-00000005", "CDR(cnum)=601") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:33] Set("PJSIP/601-00000005", "CDR(cnam)=Int - 601") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-user-callerid:34] Set("PJSIP/601-00000005", "CHANNEL(language)=it") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-exten-vm:2] Set("PJSIP/601-00000005", "RingGroupMethod=none") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-exten-vm:3] Set("PJSIP/601-00000005", "__EXTTOCALL=603") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-exten-vm:4] Set("PJSIP/601-00000005", "__PICKUPMARK=603") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-exten-vm:5] Set("PJSIP/601-00000005", "RT=") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-exten-vm:6] Gosub("PJSIP/601-00000005", "sub-record-check,s,1(exten,603,)") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-record-check:1] Set("PJSIP/601-00000005", "REC_POLICY_MODE_SAVE=") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-record-check:2] GotoIf("PJSIP/601-00000005", "1?check") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Goto (sub-record-check,s,7) [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/601-00000005", "__MON_FMT=wav") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-record-check:8] GotoIf("PJSIP/601-00000005", "1?next") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Goto (sub-record-check,s,11) [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/601-00000005", "0?Return()") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-record-check:12] ExecIf("PJSIP/601-00000005", "0?Set(__REC_POLICY_MODE=)") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-record-check:13] GotoIf("PJSIP/601-00000005", "0?exten,1") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-record-check:14] Set("PJSIP/601-00000005", "__REC_STATUS=INITIALIZED") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-record-check:15] Set("PJSIP/601-00000005", "NOW=1415199066") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-record-check:16] Set("PJSIP/601-00000005", "__DAY=05") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-record-check:17] Set("PJSIP/601-00000005", "__MONTH=11") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-record-check:18] Set("PJSIP/601-00000005", "__YEAR=2014") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-record-check:19] Set("PJSIP/601-00000005", "__TIMESTR=20141105-155106") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-record-check:20] Set("PJSIP/601-00000005", "__FROMEXTEN=601") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-record-check:21] Set("PJSIP/601-00000005", "__CALLFILENAME=exten-603-601-20141105-155106-1415199066.29") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-record-check:22] Goto("PJSIP/601-00000005", "exten,1") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Goto (sub-record-check,exten,1) [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [exten@sub-record-check:1] GotoIf("PJSIP/601-00000005", "0?callee") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [exten@sub-record-check:2] Set("PJSIP/601-00000005", "__REC_POLICY_MODE=dontcare") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [exten@sub-record-check:3] GotoIf("PJSIP/601-00000005", "1?caller") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Goto (sub-record-check,exten,10) [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [exten@sub-record-check:10] Set("PJSIP/601-00000005", "__REC_POLICY_MODE=dontcare") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [exten@sub-record-check:11] GosubIf("PJSIP/601-00000005", "0?record,1(exten,603,601)") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [exten@sub-record-check:12] Return("PJSIP/601-00000005", "") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-exten-vm:7] GotoIf("PJSIP/601-00000005", "1?macrodial") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Goto (macro-exten-vm,s,13) [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-exten-vm:13] GosubIf("PJSIP/601-00000005", "0?clrheader,1()") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-exten-vm:14] Macro("PJSIP/601-00000005", "dial-one,,Ttr,603") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:1] Set("PJSIP/601-00000005", "DEXTEN=603") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:2] Set("PJSIP/601-00000005", "DIALSTATUS_CW=") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:3] GosubIf("PJSIP/601-00000005", "0?screen,1()") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:4] GosubIf("PJSIP/601-00000005", "0?cf,1()") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:5] GotoIf("PJSIP/601-00000005", "1?skip1") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Goto (macro-dial-one,s,8) [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:8] GotoIf("PJSIP/601-00000005", "0?nodial") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:9] GotoIf("PJSIP/601-00000005", "0?continue") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:10] Set("PJSIP/601-00000005", "EXTHASCW=ENABLED") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:11] GotoIf("PJSIP/601-00000005", "0?next1:cwinusebusy") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Goto (macro-dial-one,s,23) [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:23] GotoIf("PJSIP/601-00000005", "1?next3:continue") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Goto (macro-dial-one,s,24) [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:24] ExecIf("PJSIP/601-00000005", "0?Set(DIALSTATUS_CW=BUSY)") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:25] GotoIf("PJSIP/601-00000005", "0?nodial") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:26] GosubIf("PJSIP/601-00000005", "1?dstring,1():dlocal,1()") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [dstring@macro-dial-one:1] Set("PJSIP/601-00000005", "DSTRING=") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [dstring@macro-dial-one:2] Set("PJSIP/601-00000005", "DEVICES=603") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("PJSIP/601-00000005", "0?Return()") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("PJSIP/601-00000005", "0?Set(DEVICES=03)") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [dstring@macro-dial-one:5] Set("PJSIP/601-00000005", "LOOPCNT=1") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [dstring@macro-dial-one:6] Set("PJSIP/601-00000005", "ITER=1") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [dstring@macro-dial-one:7] Set("PJSIP/601-00000005", "THISDIAL=PJSIP/603") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("PJSIP/601-00000005", "1?zap2dahdi,1()") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("PJSIP/601-00000005", "0?Return()") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("PJSIP/601-00000005", "NEWDIAL=") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("PJSIP/601-00000005", "LOOPCNT2=1") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("PJSIP/601-00000005", "ITER2=1") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("PJSIP/601-00000005", "THISPART2=PJSIP/603") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("PJSIP/601-00000005", "0?Set(THISPART2=DAHDIIP/603)") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("PJSIP/601-00000005", "NEWDIAL=PJSIP/603&") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("PJSIP/601-00000005", "ITER2=2") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("PJSIP/601-00000005", "0?begin2") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("PJSIP/601-00000005", "THISDIAL=PJSIP/603") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("PJSIP/601-00000005", "") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("PJSIP/601-00000005", "0?doset") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [dstring@macro-dial-one:10] NoOp("PJSIP/601-00000005", "Debug: Found PJSIP Destination PJSIP/603, updaing with PJSIP_DIAL_CONTACTS") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [dstring@macro-dial-one:11] Set("PJSIP/601-00000005", "THISDIAL=PJSIP/603/sip:603@192.168.1.9:5060;registering_acc=192_168_1_8") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [dstring@macro-dial-one:12] Set("PJSIP/601-00000005", "DSTRING=PJSIP/603/sip:603@192.168.1.9:5060;registering_acc=192_168_1_8&") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [dstring@macro-dial-one:13] Set("PJSIP/601-00000005", "ITER=2") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf("PJSIP/601-00000005", "0?begin") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [dstring@macro-dial-one:15] Set("PJSIP/601-00000005", "DSTRING=PJSIP/603/sip:603@192.168.1.9:5060;registering_acc=192_168_1_8") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [dstring@macro-dial-one:16] Return("PJSIP/601-00000005", "") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:27] GotoIf("PJSIP/601-00000005", "0?nodial") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:28] GotoIf("PJSIP/601-00000005", "0?skiptrace") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:29] GosubIf("PJSIP/601-00000005", "1?ctset,1():ctclear,1()") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [ctset@macro-dial-one:1] Set("PJSIP/601-00000005", "DB(CALLTRACE/603)=601") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [ctset@macro-dial-one:2] Return("PJSIP/601-00000005", "") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:30] Set("PJSIP/601-00000005", "D_OPTIONS=Ttr") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:31] ExecIf("PJSIP/601-00000005", "0?SIPAddHeader(Alert-Info: )") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:32] ExecIf("PJSIP/601-00000005", "0?SIPAddHeader()") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:33] ExecIf("PJSIP/601-00000005", "0?Set(CHANNEL(musicclass)=)") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:34] GosubIf("PJSIP/601-00000005", "0?qwait,1()") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:35] Set("PJSIP/601-00000005", "__CWIGNORE=") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:36] Set("PJSIP/601-00000005", "__KEEPCID=TRUE") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:37] GotoIf("PJSIP/601-00000005", "0?usegoto,1") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:38] GotoIf("PJSIP/601-00000005", "0?godial") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:39] Gosub("PJSIP/601-00000005", "sub-presencestate-display,s,1(603)") in new stack [2014-11-05 15:51:06] WARNING[3090][C-00000003] func_presencestate.c: PRESENCE_STATE unknown [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@sub-presencestate-display:1] Goto("PJSIP/601-00000005", "state-,1") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Goto (sub-presencestate-display,state-,1) [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [state-@sub-presencestate-display:1] Set("PJSIP/601-00000005", "PRESENCESTATE_DISPLAY=") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [state-@sub-presencestate-display:2] Return("PJSIP/601-00000005", "") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:40] Set("PJSIP/601-00000005", "CONNECTEDLINE(name,i)=Int - 603") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:41] Set("PJSIP/601-00000005", "CONNECTEDLINE(num)=603") in new stack [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:42] Set("PJSIP/601-00000005", "D_OPTIONS=TtrI") in new stack [2014-11-05 15:51:06] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP response (569 bytes) to UDP:192.168.1.11:32768 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.1.11:32768;rport=32768;received=192.168.1.11;branch=z9hG4bK-v93kpceglmn8 Call-ID: 545a395816e1-vdqexaq8p5rz From: "Int - 601" ;tag=pjnf1x6cbg To: ;tag=85275f21-9f1a-437a-8e4f-7a97577abd26 CSeq: 2 INVITE Server: FPBX-12.0.6(13.0.0) Contact: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REFER, REGISTER, MESSAGE P-Asserted-Identity: "Int - 603" Content-Length: 0 [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] pbx.c: Executing [s@macro-dial-one:43] Dial("PJSIP/601-00000005", "PJSIP/603/sip:603@192.168.1.9:5060;registering_acc=192_168_1_8,,TtrI") in new stack [2014-11-05 15:51:06] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP request (1028 bytes) to UDP:192.168.1.9:5060 ---> INVITE sip:603@192.168.1.9:5060;registering_acc=192_168_1_8 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.8:5060;rport;branch=z9hG4bKPj9793936a-5799-4b23-8fed-e590462f2158 From: "Int - 601" ;tag=592116fd-1173-488b-9d00-09028f845a3b To: Contact: Call-ID: 7714dfad-92ae-4cf4-8d5d-6825d2888ad7 CSeq: 1075 INVITE Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REFER, REGISTER, MESSAGE Supported: 100rel, timer, replaces, norefersub Session-Expires: 1800 Min-SE: 90 P-Asserted-Identity: "Int - 601" Max-Forwards: 70 User-Agent: FPBX-12.0.6(13.0.0) Content-Type: application/sdp Content-Length: 235 v=0 o=- 1688766411 1688766411 IN IP4 192.168.1.8 s=Asterisk c=IN IP4 192.168.1.8 t=0 0 m=audio 10656 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] app_dial.c: Called PJSIP/603/sip:603@192.168.1.9:5060;registering_acc=192_168_1_8 [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] app_dial.c: Connected line update to PJSIP/601-00000005 prevented. [2014-11-05 15:51:06] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP response (569 bytes) to UDP:192.168.1.11:32768 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.1.11:32768;rport=32768;received=192.168.1.11;branch=z9hG4bK-v93kpceglmn8 Call-ID: 545a395816e1-vdqexaq8p5rz From: "Int - 601" ;tag=pjnf1x6cbg To: ;tag=85275f21-9f1a-437a-8e4f-7a97577abd26 CSeq: 2 INVITE Server: FPBX-12.0.6(13.0.0) Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REFER, REGISTER, MESSAGE P-Asserted-Identity: "Int - 603" Contact: Content-Length: 0 [2014-11-05 15:51:06] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP response (364 bytes) from UDP:192.168.1.9:5060 ---> SIP/2.0 100 Trying To: Via: SIP/2.0/UDP 192.168.1.8:5060;rport=5060;branch=z9hG4bKPj9793936a-5799-4b23-8fed-e590462f2158;received=192.168.1.8 CSeq: 1075 INVITE Call-ID: 7714dfad-92ae-4cf4-8d5d-6825d2888ad7 From: "Int - 601" ;tag=592116fd-1173-488b-9d00-09028f845a3b Content-Length: 0 [2014-11-05 15:51:06] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP response (495 bytes) from UDP:192.168.1.9:5060 ---> SIP/2.0 180 Ringing To: ;tag=ec785588 Via: SIP/2.0/UDP 192.168.1.8:5060;rport=5060;branch=z9hG4bKPj9793936a-5799-4b23-8fed-e590462f2158;received=192.168.1.8 CSeq: 1075 INVITE Call-ID: 7714dfad-92ae-4cf4-8d5d-6825d2888ad7 From: "Int - 601" ;tag=592116fd-1173-488b-9d00-09028f845a3b Contact: "603" User-Agent: Jitsi2.5.5065Linux Content-Length: 0 [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] app_dial.c: PJSIP/603-00000006 is ringing [2014-11-05 15:51:06] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP response (569 bytes) to UDP:192.168.1.11:32768 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.1.11:32768;rport=32768;received=192.168.1.11;branch=z9hG4bK-v93kpceglmn8 Call-ID: 545a395816e1-vdqexaq8p5rz From: "Int - 601" ;tag=pjnf1x6cbg To: ;tag=85275f21-9f1a-437a-8e4f-7a97577abd26 CSeq: 2 INVITE Server: FPBX-12.0.6(13.0.0) Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REFER, REGISTER, MESSAGE P-Asserted-Identity: "Int - 603" Contact: Content-Length: 0 [2014-11-05 15:51:06] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP response (495 bytes) from UDP:192.168.1.9:5060 ---> SIP/2.0 180 Ringing To: ;tag=ec785588 Via: SIP/2.0/UDP 192.168.1.8:5060;rport=5060;branch=z9hG4bKPj9793936a-5799-4b23-8fed-e590462f2158;received=192.168.1.8 CSeq: 1075 INVITE Call-ID: 7714dfad-92ae-4cf4-8d5d-6825d2888ad7 From: "Int - 601" ;tag=592116fd-1173-488b-9d00-09028f845a3b Contact: "603" User-Agent: Jitsi2.5.5065Linux Content-Length: 0 [2014-11-05 15:51:06] VERBOSE[3090][C-00000003] app_dial.c: PJSIP/603-00000006 is ringing [2014-11-05 15:51:07] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP response (495 bytes) from UDP:192.168.1.9:5060 ---> SIP/2.0 180 Ringing To: ;tag=ec785588 Via: SIP/2.0/UDP 192.168.1.8:5060;rport=5060;branch=z9hG4bKPj9793936a-5799-4b23-8fed-e590462f2158;received=192.168.1.8 CSeq: 1075 INVITE Call-ID: 7714dfad-92ae-4cf4-8d5d-6825d2888ad7 From: "Int - 601" ;tag=592116fd-1173-488b-9d00-09028f845a3b Contact: "603" User-Agent: Jitsi2.5.5065Linux Content-Length: 0 [2014-11-05 15:51:07] VERBOSE[3090][C-00000003] app_dial.c: PJSIP/603-00000006 is ringing [2014-11-05 15:51:08] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP request (538 bytes) from UDP:192.168.1.9:5060 ---> OPTIONS sip:192.168.1.8 SIP/2.0 Call-ID: 2ada6b52d702f886721c0c8714c19973@0:0:0:0:0:0:0:0 CSeq: 2 OPTIONS From: "603" ;tag=9605775 To: "603" Via: SIP/2.0/UDP 192.168.1.9:5060;branch=z9hG4bK-363736-cef50f3ff62f40243b536c4291de54f5 Max-Forwards: 70 Contact: "603" User-Agent: Jitsi2.5.5065Linux Allow: INFO,OPTIONS,MESSAGE,BYE,REFER,SUBSCRIBE,ACK,CANCEL,PUBLISH,NOTIFY,INVITE Allow-Events: refer Content-Length: 0 [2014-11-05 15:51:08] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP response (554 bytes) to UDP:192.168.1.9:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.9:5060;rport=5060;received=192.168.1.9;branch=z9hG4bK-363736-cef50f3ff62f40243b536c4291de54f5 Call-ID: 2ada6b52d702f886721c0c8714c19973@0:0:0:0:0:0:0:0 From: "603" ;tag=9605775 To: "603" ;tag=z9hG4bK-363736-cef50f3ff62f40243b536c4291de54f5 CSeq: 2 OPTIONS WWW-Authenticate: Digest realm="asterisk",nonce="1415199068/44cab937d7ec0c976c9886e9604bec55",opaque="5363aa075b214549",algorithm=md5,qop="auth" Server: FPBX-12.0.6(13.0.0) Content-Length: 0 [2014-11-05 15:51:09] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP response (495 bytes) from UDP:192.168.1.9:5060 ---> SIP/2.0 180 Ringing To: ;tag=ec785588 Via: SIP/2.0/UDP 192.168.1.8:5060;rport=5060;branch=z9hG4bKPj9793936a-5799-4b23-8fed-e590462f2158;received=192.168.1.8 CSeq: 1075 INVITE Call-ID: 7714dfad-92ae-4cf4-8d5d-6825d2888ad7 From: "Int - 601" ;tag=592116fd-1173-488b-9d00-09028f845a3b Contact: "603" User-Agent: Jitsi2.5.5065Linux Content-Length: 0 [2014-11-05 15:51:09] VERBOSE[3090][C-00000003] app_dial.c: PJSIP/603-00000006 is ringing [2014-11-05 15:51:10] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP response (677 bytes) from UDP:192.168.1.9:5060 ---> SIP/2.0 200 OK To: ;tag=ec785588 Via: SIP/2.0/UDP 192.168.1.8:5060;rport=5060;branch=z9hG4bKPj9793936a-5799-4b23-8fed-e590462f2158;received=192.168.1.8 CSeq: 1075 INVITE Call-ID: 7714dfad-92ae-4cf4-8d5d-6825d2888ad7 From: "Int - 601" ;tag=592116fd-1173-488b-9d00-09028f845a3b Contact: "603" User-Agent: Jitsi2.5.5065Linux Content-Type: application/sdp Content-Length: 154 v=0 o=603 0 0 IN IP4 192.168.1.9 s=- c=IN IP4 192.168.1.9 t=0 0 m=audio 5000 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 [2014-11-05 15:51:10] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP request (446 bytes) to UDP:192.168.1.9:5060 ---> ACK sip:603@192.168.1.9:5060;registering_acc=192_168_1_8 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.8:5060;rport;branch=z9hG4bKPj1b4fb210-c197-4d96-a4ac-0fd725d8054b From: "Int - 601" ;tag=592116fd-1173-488b-9d00-09028f845a3b To: ;tag=ec785588 Call-ID: 7714dfad-92ae-4cf4-8d5d-6825d2888ad7 CSeq: 1075 ACK Max-Forwards: 70 User-Agent: FPBX-12.0.6(13.0.0) Content-Length: 0 [2014-11-05 15:51:10] VERBOSE[3090][C-00000003] app_dial.c: PJSIP/603-00000006 answered PJSIP/601-00000005 [2014-11-05 15:51:10] VERBOSE[3090][C-00000003] bridge_channel.c: Channel PJSIP/601-00000005 joined 'simple_bridge' basic-bridge [2014-11-05 15:51:10] VERBOSE[3091][C-00000003] bridge_channel.c: Channel PJSIP/603-00000006 joined 'simple_bridge' basic-bridge [2014-11-05 15:51:10] VERBOSE[2648] res_pjsip_logger.c: <--- Transmitting SIP response (916 bytes) to UDP:192.168.1.11:32768 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.11:32768;rport=32768;received=192.168.1.11;branch=z9hG4bK-v93kpceglmn8 Call-ID: 545a395816e1-vdqexaq8p5rz From: "Int - 601" ;tag=pjnf1x6cbg To: ;tag=85275f21-9f1a-437a-8e4f-7a97577abd26 CSeq: 2 INVITE Server: FPBX-12.0.6(13.0.0) Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REFER, REGISTER, MESSAGE P-Asserted-Identity: "Int - 603" Contact: Supported: 100rel, timer, replaces, norefersub Session-Expires: 3600;refresher=uas Content-Type: application/sdp Content-Length: 233 v=0 o=- 726372745 726372747 IN IP4 192.168.1.8 s=Asterisk c=IN IP4 192.168.1.8 t=0 0 m=audio 10818 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [2014-11-05 15:51:10] VERBOSE[2653] res_pjsip_logger.c: <--- Received SIP request (390 bytes) from UDP:192.168.1.11:32768 ---> ACK sip:192.168.1.8:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.11:32768;branch=z9hG4bK-cis8rfcgsbgx;rport From: "Int - 601" ;tag=pjnf1x6cbg To: ;tag=85275f21-9f1a-437a-8e4f-7a97577abd26 Call-ID: 545a395816e1-vdqexaq8p5rz CSeq: 2 ACK Max-Forwards: 70 Contact: ;reg-id=1 Content-Length: 0 ********************crash [2014-11-05 15:51:15] Asterisk 13.0.0 built by root @ localhost.localdomain on a x86_64 running Linux on 2014-11-05 11:40:23 UTC [2014-11-05 15:51:15] VERBOSE[3099] manager.c: Manager registered action DBGet