Here I'm attaching a SIP debug for peer 106, which is configured to use iLBC, calling extension 101 which is on DAHDI card. 101 can hear 106, but 106 can't hear 101 Connected to Asterisk 13.23.1 currently running on freepbx (pid = 2546) freepbx*CLI> sip set debug peer 106 SIP Debugging Enabled for IP: 192.168.10.106 <--- SIP read from UDP:192.168.10.106:5060 ---> INVITE sip:101@192.168.10.4 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1839075671;rport From: ;tag=1674326932 To: Call-ID: 2000295813-5060-336@BJC.BGI.BA.BAG CSeq: 1080 INVITE Contact: X-Grandstream-PBX: true Max-Forwards: 70 User-Agent: Grandstream GXP2110 1.0.8.6 Privacy: none P-Preferred-Identity: Supported: replaces, path, timer Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Type: application/sdp Accept: application/sdp, application/dtmf-relay Content-Length: 405 v=0 o=106 8000 8000 IN IP4 192.168.10.106 s=SIP Call c=IN IP4 192.168.10.106 t=0 0 m=audio 5004 RTP/AVP 97 8 4 18 9 0 2 101 a=sendrecv a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=30 a=ptime:60 a=rtpmap:8 PCMA/8000 a=rtpmap:4 G723/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 <-------------> — (17 headers 19 lines) — Sending to 192.168.10.106:5060 (no NAT) Sending to 192.168.10.106:5060 (no NAT) Using INVITE request as basis request - 2000295813-5060-336@BJC.BGI.BA.BAG Found peer '106' for '106' from 192.168.10.106:5060 <--- Reliably Transmitting (no NAT) to 192.168.10.106:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1839075671;received=192.168.10.106;rport=5060 From: ;tag=1674326932 To: ;tag=as606c6233 Call-ID: 2000295813-5060-336@BJC.BGI.BA.BAG CSeq: 1080 INVITE Server: FPBX-13.0.195.19(13.23.1) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="4af1d250" Content-Length: 0 <------------> Scheduling destruction of SIP dialog '2000295813-5060-336@BJC.BGI.BA.BAG' in 6400 ms (Method: INVITE) <--- SIP read from UDP:192.168.10.106:5060 ---> ACK sip:101@192.168.10.4 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1839075671;rport From: ;tag=1674326932 To: ;tag=as606c6233 Call-ID: 2000295813-5060-336@BJC.BGI.BA.BAG CSeq: 1080 ACK Content-Length: 0 <-------------> — (7 headers 0 lines) — <--- SIP read from UDP:192.168.10.106:5060 ---> INVITE sip:101@192.168.10.4 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1868569856;rport From: ;tag=1674326932 To: Call-ID: 2000295813-5060-336@BJC.BGI.BA.BAG CSeq: 1081 INVITE Contact: Authorization: Digest username="106", realm="asterisk", nonce="4af1d250", uri="sip:101@192.168.10.4", response="516bd7777e0f8d2eef24d09b1d1a74b9", algorithm=MD5 X-Grandstream-PBX: true Max-Forwards: 70 User-Agent: Grandstream GXP2110 1.0.8.6 Privacy: none P-Preferred-Identity: Supported: replaces, path, timer Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Type: application/sdp Accept: application/sdp, application/dtmf-relay Content-Length: 405 v=0 o=106 8000 8000 IN IP4 192.168.10.106 s=SIP Call c=IN IP4 192.168.10.106 t=0 0 m=audio 5004 RTP/AVP 97 8 4 18 9 0 2 101 a=sendrecv a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=30 a=ptime:60 a=rtpmap:8 PCMA/8000 a=rtpmap:4 G723/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 <-------------> — (18 headers 19 lines) — Sending to 192.168.10.106:5060 (no NAT) Using INVITE request as basis request - 2000295813-5060-336@BJC.BGI.BA.BAG Found peer '106' for '106' from 192.168.10.106:5060 == Using SIP RTP TOS bits 184 == Using SIP RTP CoS mark 5 Found RTP audio format 97 Found RTP audio format 8 Found RTP audio format 4 Found RTP audio format 18 Found RTP audio format 9 Found RTP audio format 0 Found RTP audio format 2 Found RTP audio format 101 Found audio description format iLBC for ID 97 Found audio description format PCMA for ID 8 Found audio description format G723 for ID 4 Found audio description format G729 for ID 18 Found audio description format G722 for ID 9 Found audio description format PCMU for ID 0 Found audio description format G726-32 for ID 2 Found audio description format telephone-event for ID 101 Capabilities: us - (ilbc|g729|ulaw|alaw|g722), peer - audio=(ulaw|g726|g723|alaw|g722|g729|ilbc)/video=(nothing)/text=(nothing), combined - (ilbc|g729|ulaw|alaw|g722) Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) > 0xb7452bd0 – Strict RTP learning after remote address set to: 192.168.10.106:5004 Peer audio RTP is at port 192.168.10.106:5004 Looking for 101 in from-internal (domain 192.168.10.4) == Extension Changed 106[ext-local] new state InUse for Notify User 103 == Extension Changed 106[ext-local] new state InUse for Notify User 100 == Extension Changed 106[ext-local] new state InUse for Notify User 104 == Extension Changed 106[ext-local] new state InUse for Notify User 105 sip_route_dump: route/path hop: <--- Transmitting (no NAT) to 192.168.10.106:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1868569856;received=192.168.10.106;rport=5060 From: ;tag=1674326932 To: Call-ID: 2000295813-5060-336@BJC.BGI.BA.BAG CSeq: 1081 INVITE Server: FPBX-13.0.195.19(13.23.1) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> – Executing [101@from-internal:1] GotoIf("SIP/106-000000b3", "1?ext-local,101,1:followme-check,101,1") in new stack – Goto (ext-local,101,1) – Executing [101@ext-local:1] Set("SIP/106-000000b3", "__RINGTIMER=15") in new stack – Executing [101@ext-local:2] Macro("SIP/106-000000b3", "exten-vm,novm,101,0,0,0") in new stack – Executing [s@macro-exten-vm:1] Macro("SIP/106-000000b3", "user-callerid,") in new stack – Executing [s@macro-user-callerid:1] Set("SIP/106-000000b3", "TOUCH_MONITOR=1544205572.1516") in new stack – Executing [s@macro-user-callerid:2] Set("SIP/106-000000b3", "AMPUSER=106") in new stack – Executing [s@macro-user-callerid:3] GotoIf("SIP/106-000000b3", "0?report") in new stack – Executing [s@macro-user-callerid:4] ExecIf("SIP/106-000000b3", "1?Set(REALCALLERIDNUM=106)") in new stack – Executing [s@macro-user-callerid:5] Set("SIP/106-000000b3", "AMPUSER=106") in new stack – Executing [s@macro-user-callerid:6] GotoIf("SIP/106-000000b3", "0?limit") in new stack – Executing [s@macro-user-callerid:7] Set("SIP/106-000000b3", "AMPUSERCIDNAME=Marianela Lopez") in new stack – Executing [s@macro-user-callerid:8] ExecIf("SIP/106-000000b3", "0?Set(__CIDMASQUERADING=TRUE)") in new stack – Executing [s@macro-user-callerid:9] GotoIf("SIP/106-000000b3", "0?report") in new stack – Executing [s@macro-user-callerid:10] Set("SIP/106-000000b3", "AMPUSERCID=106") in new stack – Executing [s@macro-user-callerid:11] Set("SIP/106-000000b3", "__DIAL_OPTIONS=Ttr") in new stack – Executing [s@macro-user-callerid:12] Set("SIP/106-000000b3", "CALLERID(all)="Marianela Lopez" <106>") in new stack – Executing [s@macro-user-callerid:13] GotoIf("SIP/106-000000b3", "0?limit") in new stack – Executing [s@macro-user-callerid:14] ExecIf("SIP/106-000000b3", "0?Set(GROUP(concurrency_limit)=106)") in new stack – Executing [s@macro-user-callerid:15] GosubIf("SIP/106-000000b3", "7?sub-ccss,s,1(macro-exten-vm,101)") in new stack – Executing [s@sub-ccss:1] ExecIf("SIP/106-000000b3", "0?Return()") in new stack – Executing [s@sub-ccss:2] Set("SIP/106-000000b3", "CCSS_SETUP=TRUE") in new stack – Executing [s@sub-ccss:3] GosubIf("SIP/106-000000b3", "0?monitor_config,1(macro-exten-vm,101):monitor_default,1(macro-exten-vm,101)") in new stack – Executing [monitor_default@sub-ccss:1] GotoIf("SIP/106-000000b3", "1?is_exten") in new stack – Goto (sub-ccss,monitor_default,4) – Executing [monitor_default@sub-ccss:4] Set("SIP/106-000000b3", "CALLCOMPLETION(cc_monitor_policy)=generic") in new stack – Executing [monitor_default@sub-ccss:5] Set("SIP/106-000000b3", "CALLCOMPLETION(cc_max_monitors)=5") in new stack – Executing [monitor_default@sub-ccss:6] Return("SIP/106-000000b3", "TRUE") in new stack – Executing [s@sub-ccss:4] GosubIf("SIP/106-000000b3", "7?agent_config,1():agent_default,1()") in new stack – Executing [agent_config@sub-ccss:1] Set("SIP/106-000000b3", "CALLCOMPLETION(cc_agent_policy)=generic") in new stack – Executing [agent_config@sub-ccss:2] Set("SIP/106-000000b3", "CALLCOMPLETION(cc_offer_timer)=30") in new stack – Executing [agent_config@sub-ccss:3] Set("SIP/106-000000b3", "CALLCOMPLETION(ccbs_available_timer)=") in new stack – Executing [agent_config@sub-ccss:4] Set("SIP/106-000000b3", "CALLCOMPLETION(ccnr_available_timer)=") in new stack – Executing [agent_config@sub-ccss:5] Set("SIP/106-000000b3", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack [2018-12-07 14:59:32] WARNING[32490][C-0000007a]: ccss.c:1012 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead. – Executing [agent_config@sub-ccss:6] ExecIf("SIP/106-000000b3", "1?Set(CALLCOMPLETION(cc_recall_timer)=)") in new stack – Executing [agent_config@sub-ccss:7] ExecIf("SIP/106-000000b3", "1?Set(CALLCOMPLETION(cc_max_agents)=)") in new stack – Executing [agent_config@sub-ccss:8] ExecIf("SIP/106-000000b3", "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/106_101@from-ccss-)") in new stack – Executing [agent_config@sub-ccss:9] Set("SIP/106-000000b3", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack [2018-12-07 14:59:32] WARNING[32490][C-0000007a]: ccss.c:1012 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead. – Executing [agent_config@sub-ccss:10] Return("SIP/106-000000b3", "") in new stack – Executing [s@sub-ccss:5] Set("SIP/106-000000b3", "DB(AMPUSER/106/ccss/last_number)=101") in new stack – Executing [s@sub-ccss:6] Return("SIP/106-000000b3", "") in new stack – Executing [s@macro-user-callerid:16] ExecIf("SIP/106-000000b3", "0?Set(CHANNEL(language)=)") in new stack – Executing [s@macro-user-callerid:17] NoOp("SIP/106-000000b3", "Macro Depth is 2") in new stack – Executing [s@macro-user-callerid:18] GotoIf("SIP/106-000000b3", "1?report2:macroerror") in new stack – Goto (macro-user-callerid,s,19) – Executing [s@macro-user-callerid:19] GotoIf("SIP/106-000000b3", "0?continue") in new stack – Executing [s@macro-user-callerid:20] ExecIf("SIP/106-000000b3", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack – Executing [s@macro-user-callerid:21] Set("SIP/106-000000b3", "__TTL=64") in new stack – Executing [s@macro-user-callerid:22] GotoIf("SIP/106-000000b3", "1?continue") in new stack – Goto (macro-user-callerid,s,38) – Executing [s@macro-user-callerid:38] Set("SIP/106-000000b3", "CALLERID(number)=106") in new stack – Executing [s@macro-user-callerid:39] Set("SIP/106-000000b3", "CALLERID(name)=Marianela Lopez") in new stack – Executing [s@macro-user-callerid:40] GotoIf("SIP/106-000000b3", "0?cnum") in new stack – Executing [s@macro-user-callerid:41] Set("SIP/106-000000b3", "CDR(cnam)=Marianela Lopez") in new stack – Executing [s@macro-user-callerid:42] Set("SIP/106-000000b3", "CDR(cnum)=106") in new stack – Executing [s@macro-user-callerid:43] Set("SIP/106-000000b3", "CHANNEL(language)=es") in new stack – Executing [s@macro-exten-vm:2] Set("SIP/106-000000b3", "RingGroupMethod=none") in new stack – Executing [s@macro-exten-vm:3] Set("SIP/106-000000b3", "__EXTTOCALL=101") in new stack – Executing [s@macro-exten-vm:4] Set("SIP/106-000000b3", "__PICKUPMARK=101") in new stack – Executing [s@macro-exten-vm:5] Set("SIP/106-000000b3", "RT=") in new stack – Executing [s@macro-exten-vm:6] Gosub("SIP/106-000000b3", "sub-record-check,s,1(exten,101,dontcare)") in new stack – Executing [s@sub-record-check:1] GotoIf("SIP/106-000000b3", "0?initialized") in new stack – Executing [s@sub-record-check:2] Set("SIP/106-000000b3", "__REC_STATUS=INITIALIZED") in new stack – Executing [s@sub-record-check:3] Set("SIP/106-000000b3", "NOW=1544205572") in new stack – Executing [s@sub-record-check:4] Set("SIP/106-000000b3", "__DAY=07") in new stack – Executing [s@sub-record-check:5] Set("SIP/106-000000b3", "__MONTH=12") in new stack – Executing [s@sub-record-check:6] Set("SIP/106-000000b3", "__YEAR=2018") in new stack – Executing [s@sub-record-check:7] Set("SIP/106-000000b3", "__TIMESTR=20181207-145932") in new stack – Executing [s@sub-record-check:8] Set("SIP/106-000000b3", "__FROMEXTEN=106") in new stack – Executing [s@sub-record-check:9] Set("SIP/106-000000b3", "__MON_FMT=wav") in new stack – Executing [s@sub-record-check:10] NoOp("SIP/106-000000b3", "Recordings initialized") in new stack – Executing [s@sub-record-check:11] ExecIf("SIP/106-000000b3", "0?Set(ARG3=dontcare)") in new stack – Executing [s@sub-record-check:12] Set("SIP/106-000000b3", "REC_POLICY_MODE_SAVE=") in new stack – Executing [s@sub-record-check:13] ExecIf("SIP/106-000000b3", "0?Set(REC_STATUS=NO)") in new stack – Executing [s@sub-record-check:14] GotoIf("SIP/106-000000b3", "5?checkaction") in new stack – Goto (sub-record-check,s,17) – Executing [s@sub-record-check:17] GotoIf("SIP/106-000000b3", "1?sub-record-check,exten,1") in new stack – Goto (sub-record-check,exten,1) – Executing [exten@sub-record-check:1] NoOp("SIP/106-000000b3", "Exten Recording Check between 106 and 101") in new stack – Executing [exten@sub-record-check:2] Set("SIP/106-000000b3", "CALLTYPE=internal") in new stack – Executing [exten@sub-record-check:3] ExecIf("SIP/106-000000b3", "0?Set(CALLTYPE=)") in new stack – Executing [exten@sub-record-check:4] Set("SIP/106-000000b3", "CALLEE=dontcare") in new stack – Executing [exten@sub-record-check:5] ExecIf("SIP/106-000000b3", "0?Set(CALLEE=dontcare)") in new stack – Executing [exten@sub-record-check:6] GotoIf("SIP/106-000000b3", "0?callee") in new stack – Executing [exten@sub-record-check:7] GotoIf("SIP/106-000000b3", "1?caller") in new stack – Goto (sub-record-check,exten,13) – Executing [exten@sub-record-check:13] Set("SIP/106-000000b3", "RECMODE=dontcare") in new stack – Executing [exten@sub-record-check:14] ExecIf("SIP/106-000000b3", "0?Set(RECMODE=dontcare)") in new stack – Executing [exten@sub-record-check:15] ExecIf("SIP/106-000000b3", "1?Set(RECMODE=dontcare)") in new stack – Executing [exten@sub-record-check:16] Gosub("SIP/106-000000b3", "recordcheck,1(dontcare,internal,101)") in new stack – Executing [recordcheck@sub-record-check:1] NoOp("SIP/106-000000b3", "Starting recording check against dontcare") in new stack – Executing [recordcheck@sub-record-check:2] Goto("SIP/106-000000b3", "dontcare") in new stack – Goto (sub-record-check,recordcheck,3) – Executing [recordcheck@sub-record-check:3] Return("SIP/106-000000b3", "") in new stack – Executing [exten@sub-record-check:17] Return("SIP/106-000000b3", "") in new stack – Executing [s@macro-exten-vm:7] GotoIf("SIP/106-000000b3", "1?macrodial") in new stack – Goto (macro-exten-vm,s,13) – Executing [s@macro-exten-vm:13] GosubIf("SIP/106-000000b3", "0?clrheader,1()") in new stack – Executing [s@macro-exten-vm:14] Macro("SIP/106-000000b3", "dial-one,,Ttr,101") in new stack – Executing [s@macro-dial-one:1] Set("SIP/106-000000b3", "DEXTEN=101") in new stack – Executing [s@macro-dial-one:2] ExecIf("SIP/106-000000b3", "0?Set(__EXTTOCALL=101)") in new stack – Executing [s@macro-dial-one:3] Set("SIP/106-000000b3", "DIALSTATUS_CW=") in new stack – Executing [s@macro-dial-one:4] GosubIf("SIP/106-000000b3", "0?screen,1()") in new stack – Executing [s@macro-dial-one:5] GosubIf("SIP/106-000000b3", "0?cf,1()") in new stack – Executing [s@macro-dial-one:6] GotoIf("SIP/106-000000b3", "1?skip1") in new stack – Goto (macro-dial-one,s,9) – Executing [s@macro-dial-one:9] GotoIf("SIP/106-000000b3", "0?nodial") in new stack – Executing [s@macro-dial-one:10] GotoIf("SIP/106-000000b3", "0?continue") in new stack – Executing [s@macro-dial-one:11] Set("SIP/106-000000b3", "EXTHASCW=ENABLED") in new stack – Executing [s@macro-dial-one:12] GotoIf("SIP/106-000000b3", "0?next1:cwinusebusy") in new stack – Goto (macro-dial-one,s,24) – Executing [s@macro-dial-one:24] GotoIf("SIP/106-000000b3", "0?next3:continue") in new stack – Goto (macro-dial-one,s,26) – Executing [s@macro-dial-one:26] GotoIf("SIP/106-000000b3", "0?nodial") in new stack – Executing [s@macro-dial-one:27] GosubIf("SIP/106-000000b3", "1?dstring,1():dlocal,1()") in new stack – Executing [dstring@macro-dial-one:1] Set("SIP/106-000000b3", "DSTRING=") in new stack – Executing [dstring@macro-dial-one:2] Set("SIP/106-000000b3", "DEVICES=101") in new stack – Executing [dstring@macro-dial-one:3] ExecIf("SIP/106-000000b3", "0?Return()") in new stack – Executing [dstring@macro-dial-one:4] ExecIf("SIP/106-000000b3", "0?Set(DEVICES=01)") in new stack – Executing [dstring@macro-dial-one:5] Set("SIP/106-000000b3", "LOOPCNT=1") in new stack – Executing [dstring@macro-dial-one:6] Set("SIP/106-000000b3", "ITER=1") in new stack – Executing [dstring@macro-dial-one:7] Set("SIP/106-000000b3", "THISDIAL=DAHDI/5") in new stack – Executing [dstring@macro-dial-one:8] GosubIf("SIP/106-000000b3", "1?zap2dahdi,1()") in new stack – Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/106-000000b3", "0?Return()") in new stack – Executing [zap2dahdi@macro-dial-one:2] Set("SIP/106-000000b3", "NEWDIAL=") in new stack – Executing [zap2dahdi@macro-dial-one:3] Set("SIP/106-000000b3", "LOOPCNT2=1") in new stack – Executing [zap2dahdi@macro-dial-one:4] Set("SIP/106-000000b3", "ITER2=1") in new stack – Executing [zap2dahdi@macro-dial-one:5] Set("SIP/106-000000b3", "THISPART2=DAHDI/5") in new stack – Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/106-000000b3", "0?Set(THISPART2=DAHDIDI/5)") in new stack – Executing [zap2dahdi@macro-dial-one:7] Set("SIP/106-000000b3", "NEWDIAL=DAHDI/5&") in new stack – Executing [zap2dahdi@macro-dial-one:8] Set("SIP/106-000000b3", "ITER2=2") in new stack – Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/106-000000b3", "0?begin2") in new stack – Executing [zap2dahdi@macro-dial-one:10] Set("SIP/106-000000b3", "THISDIAL=DAHDI/5") in new stack – Executing [zap2dahdi@macro-dial-one:11] Return("SIP/106-000000b3", "") in new stack – Executing [dstring@macro-dial-one:9] GotoIf("SIP/106-000000b3", "1?docheck") in new stack – Goto (macro-dial-one,dstring,14) – Executing [dstring@macro-dial-one:14] GotoIf("SIP/106-000000b3", "0?skipset") in new stack – Executing [dstring@macro-dial-one:15] Set("SIP/106-000000b3", "DSTRING=DAHDI/5&") in new stack – Executing [dstring@macro-dial-one:16] Set("SIP/106-000000b3", "ITER=2") in new stack – Executing [dstring@macro-dial-one:17] GotoIf("SIP/106-000000b3", "0?begin") in new stack – Executing [dstring@macro-dial-one:18] ExecIf("SIP/106-000000b3", "0?Return()") in new stack – Executing [dstring@macro-dial-one:19] Set("SIP/106-000000b3", "DSTRING=DAHDI/5") in new stack – Executing [dstring@macro-dial-one:20] Return("SIP/106-000000b3", "") in new stack – Executing [s@macro-dial-one:28] GotoIf("SIP/106-000000b3", "0?nodial") in new stack – Executing [s@macro-dial-one:29] GotoIf("SIP/106-000000b3", "0?skiptrace") in new stack – Executing [s@macro-dial-one:30] GosubIf("SIP/106-000000b3", "1?ctset,1():ctclear,1()") in new stack – Executing [ctset@macro-dial-one:1] Set("SIP/106-000000b3", "DB(CALLTRACE/101)=106") in new stack – Executing [ctset@macro-dial-one:2] Return("SIP/106-000000b3", "") in new stack – Executing [s@macro-dial-one:31] Set("SIP/106-000000b3", "D_OPTIONS=Ttr") in new stack – Executing [s@macro-dial-one:32] GosubIf("SIP/106-000000b3", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack – Executing [s@macro-dial-one:33] NoOp("SIP/106-000000b3", "Blind Transfer: , Attended Transfer: , User: 106, Alert Info: ") in new stack – Executing [s@macro-dial-one:34] ExecIf("SIP/106-000000b3", "1?Set(ALERT_INFO=)") in new stack – Executing [s@macro-dial-one:35] ExecIf("SIP/106-000000b3", "0?Set(ALERT_INFO=)") in new stack – Executing [s@macro-dial-one:36] ExecIf("SIP/106-000000b3", "0?Set(ALERT_INFO=)") in new stack – Executing [s@macro-dial-one:37] ExecIf("SIP/106-000000b3", "0?Set(ALERT_INFO=Normal;volume=)") in new stack – Executing [s@macro-dial-one:38] ExecIf("SIP/106-000000b3", "0?Set(ALERT_INFO=Normal;volume=)") in new stack – Executing [s@macro-dial-one:39] GosubIf("SIP/106-000000b3", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack – Executing [s@macro-dial-one:40] ExecIf("SIP/106-000000b3", "0?Set(CHANNEL(musicclass)=)") in new stack – Executing [s@macro-dial-one:41] GosubIf("SIP/106-000000b3", "0?qwait,1()") in new stack – Executing [s@macro-dial-one:42] Set("SIP/106-000000b3", "__CWIGNORE=") in new stack – Executing [s@macro-dial-one:43] Set("SIP/106-000000b3", "__KEEPCID=TRUE") in new stack – Executing [s@macro-dial-one:44] GotoIf("SIP/106-000000b3", "0?usegoto,1") in new stack – Executing [s@macro-dial-one:45] GotoIf("SIP/106-000000b3", "0?godial") in new stack – Executing [s@macro-dial-one:46] Gosub("SIP/106-000000b3", "sub-presencestate-display,s,1(101)") in new stack – Executing [s@sub-presencestate-display:1] Goto("SIP/106-000000b3", "state-not_set,1") in new stack – Goto (sub-presencestate-display,state-not_set,1) – Executing [state-not_set@sub-presencestate-display:1] Set("SIP/106-000000b3", "PRESENCESTATE_DISPLAY=") in new stack – Executing [state-not_set@sub-presencestate-display:2] Return("SIP/106-000000b3", "") in new stack – Executing [s@macro-dial-one:47] Set("SIP/106-000000b3", "CONNECTEDLINE(name,i)=Comedor") in new stack – Executing [s@macro-dial-one:48] Set("SIP/106-000000b3", "CONNECTEDLINE(num)=101") in new stack – Executing [s@macro-dial-one:49] Set("SIP/106-000000b3", "D_OPTIONS=TtrI") in new stack – Executing [s@macro-dial-one:50] Macro("SIP/106-000000b3", "dialout-one-predial-hook,") in new stack – Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/106-000000b3", "") in new stack – Executing [s@macro-dial-one:51] ExecIf("SIP/106-000000b3", "0?Set(D_OPTIONS=trII)") in new stack – Executing [s@macro-dial-one:52] NoOp("SIP/106-000000b3", "") in new stack – Executing [s@macro-dial-one:53] ExecIf("SIP/106-000000b3", "0?Set(D_OPTIONS=TtrIg)") in new stack – Executing [s@macro-dial-one:54] Dial("SIP/106-000000b3", "DAHDI/5,,TtrIb(func-apply-sipheaders^s^1)") in new stack – DAHDI/5-1 Internal Gosub(func-apply-sipheaders,s,1) start == Extension Changed 101[ext-local] new state InUse for Notify User 100 == Extension Changed 101[ext-local] new state InUse for Notify User 104 set_destination: Parsing for address/port to send to set_destination: set destination to 192.168.10.106:5060 – Executing [s@func-apply-sipheaders:1] NoOp("DAHDI/5-1", "Applying SIP Headers to channel") in new stack Reliably Transmitting (no NAT) to 192.168.10.106:5060: NOTIFY sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK15e6421b;rport Max-Forwards: 70 From: ;tag=as31fae48c To: ;tag=395955848 Contact: Call-ID: 413681140-5060-317@BJC.BGI.BA.BAG CSeq: 271 NOTIFY User-Agent: FPBX-13.0.195.19(13.23.1) Subscription-State: active Event: dialog Content-Type: application/dialog-info+xml Content-Length: 204 confirmed — == Extension Changed 101[ext-local] new state InUse for Notify User 106 – Executing [s@func-apply-sipheaders:2] Set("DAHDI/5-1", "SIPHEADERKEYS=") in new stack == Extension Changed 101[ext-local] new state InUse for Notify User 103 == Extension Changed 101[ext-local] new state InUse for Notify User 105 – Executing [s@func-apply-sipheaders:3] ExecIf("DAHDI/5-1", "0?Set(Rheader=1)") in new stack – Executing [s@func-apply-sipheaders:4] While("DAHDI/5-1", "0") in new stack – Jumping to priority 7 – Executing [s@func-apply-sipheaders:8] ExecIf("DAHDI/5-1", "0?SIPRemoveHeader(Alert-Info") in new stack – Executing [s@func-apply-sipheaders:9] Return("DAHDI/5-1", "") in new stack == Spawn extension (from-internal, 101, 1) exited non-zero on 'DAHDI/5-1' – DAHDI/5-1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL= == Extension Changed 101[ext-local] new state Ringing for Notify User 100 (queued) == Extension Changed 101[ext-local] new state Ringing for Notify User 104 (queued) == Extension Changed 101[ext-local] new state Ringing for Notify User 106 (queued) == Extension Changed 101[ext-local] new state Ringing for Notify User 103 (queued) == Extension Changed 101[ext-local] new state Ringing for Notify User 105 (queued) – Called DAHDI/5 <--- Transmitting (no NAT) to 192.168.10.106:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1868569856;received=192.168.10.106;rport=5060 From: ;tag=1674326932 To: ;tag=as2b2e2423 Call-ID: 2000295813-5060-336@BJC.BGI.BA.BAG CSeq: 1081 INVITE Server: FPBX-13.0.195.19(13.23.1) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> – DAHDI/5-1 is ringing <--- Transmitting (no NAT) to 192.168.10.106:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1868569856;received=192.168.10.106;rport=5060 From: ;tag=1674326932 To: ;tag=as2b2e2423 Call-ID: 2000295813-5060-336@BJC.BGI.BA.BAG CSeq: 1081 INVITE Server: FPBX-13.0.195.19(13.23.1) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK15e6421b;rport=5060 From: ;tag=as31fae48c To: ;tag=395955848 Call-ID: 413681140-5060-317@BJC.BGI.BA.BAG CSeq: 271 NOTIFY Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — set_destination: Parsing for address/port to send to set_destination: set destination to 192.168.10.106:5060 Reliably Transmitting (no NAT) to 192.168.10.106:5060: NOTIFY sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK0d69c982;rport Max-Forwards: 70 From: ;tag=as31fae48c To: ;tag=395955848 Contact: Call-ID: 413681140-5060-317@BJC.BGI.BA.BAG CSeq: 272 NOTIFY User-Agent: FPBX-13.0.195.19(13.23.1) Subscription-State: active Event: dialog Content-Type: application/dialog-info+xml Content-Length: 222 early — Retransmitting #1 (no NAT) to 192.168.10.106:5060: NOTIFY sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK0d69c982;rport Max-Forwards: 70 From: ;tag=as31fae48c To: ;tag=395955848 Contact: Call-ID: 413681140-5060-317@BJC.BGI.BA.BAG CSeq: 272 NOTIFY User-Agent: FPBX-13.0.195.19(13.23.1) Subscription-State: active Event: dialog Content-Type: application/dialog-info+xml Content-Length: 222 early — <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK0d69c982;rport=5060 From: ;tag=as31fae48c To: ;tag=395955848 Call-ID: 413681140-5060-317@BJC.BGI.BA.BAG CSeq: 272 NOTIFY Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK0d69c982;rport=5060 From: ;tag=as31fae48c To: ;tag=395955848 Call-ID: 413681140-5060-317@BJC.BGI.BA.BAG CSeq: 272 NOTIFY Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — – DAHDI/5-1 answered SIP/106-000000b3 == Extension Changed 101[ext-local] new state InUse for Notify User 100 == Extension Changed 101[ext-local] new state InUse for Notify User 104 set_destination: Parsing for address/port to send to set_destination: set destination to 192.168.10.106:5060 Reliably Transmitting (no NAT) to 192.168.10.106:5060: NOTIFY sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK412bd2cb;rport Max-Forwards: 70 From: ;tag=as31fae48c To: ;tag=395955848 Contact: Call-ID: 413681140-5060-317@BJC.BGI.BA.BAG CSeq: 273 NOTIFY User-Agent: FPBX-13.0.195.19(13.23.1) Subscription-State: active Event: dialog Content-Type: application/dialog-info+xml Content-Length: 204 confirmed — == Extension Changed 101[ext-local] new state InUse for Notify User 106 == Extension Changed 101[ext-local] new state InUse for Notify User 103 == Extension Changed 101[ext-local] new state InUse for Notify User 105 Audio is at 15854 Adding codec ilbc to SDP Adding codec g729 to SDP Adding codec ulaw to SDP Adding codec alaw to SDP Adding codec g722 to SDP Adding non-codec 0x1 (telephone-event) to SDP <--- Reliably Transmitting (no NAT) to 192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1868569856;received=192.168.10.106;rport=5060 From: ;tag=1674326932 To: ;tag=as2b2e2423 Call-ID: 2000295813-5060-336@BJC.BGI.BA.BAG CSeq: 1081 INVITE Server: FPBX-13.0.195.19(13.23.1) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Type: application/sdp Require: timer Content-Length: 371 v=0 o=root 650668316 650668316 IN IP4 192.168.10.4 s=Asterisk PBX 13.23.1 c=IN IP4 192.168.10.4 t=0 0 m=audio 15854 RTP/AVP 97 18 0 8 9 101 a=rtpmap:97 iLBC/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv <------------> – Channel DAHDI/5-1 joined 'simple_bridge' basic-bridge <68206207-19b4-4f84-9c43-a5a39b17eddd> – Channel SIP/106-000000b3 joined 'simple_bridge' basic-bridge <68206207-19b4-4f84-9c43-a5a39b17eddd> <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK412bd2cb;rport=5060 From: ;tag=as31fae48c To: ;tag=395955848 Call-ID: 413681140-5060-317@BJC.BGI.BA.BAG CSeq: 273 NOTIFY Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — <--- SIP read from UDP:192.168.10.106:5060 ---> ACK sip:101@192.168.10.4:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK2048660464;rport From: ;tag=1674326932 To: ;tag=as2b2e2423 Call-ID: 2000295813-5060-336@BJC.BGI.BA.BAG CSeq: 1081 ACK Contact: X-Grandstream-PBX: true Max-Forwards: 70 Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (13 headers 0 lines) — > 0xb7452bd0 – Strict RTP switching to RTP target address 192.168.10.106:5004 as source > 0xb7452bd0 – Strict RTP learning complete - Locking on source address 192.168.10.106:5004 Reliably Transmitting (no NAT) to 192.168.10.106:5060: OPTIONS sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK69daa9e3 Max-Forwards: 70 From: "Unknown" ;tag=as4ceb2595 To: Contact: Call-ID: 3c305f1e6d913a65413069d623a50a6a@192.168.10.4:5060 CSeq: 102 OPTIONS User-Agent: FPBX-13.0.195.19(13.23.1) Date: Fri, 07 Dec 2018 17:59:57 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 — <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK69daa9e3 From: "Unknown" ;tag=as4ceb2595 To: ;tag=312204839 Call-ID: 3c305f1e6d913a65413069d623a50a6a@192.168.10.4:5060 CSeq: 102 OPTIONS Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (10 headers 0 lines) — Really destroying SIP dialog '3c305f1e6d913a65413069d623a50a6a@192.168.10.4:5060' Method: OPTIONS <--- SIP read from UDP:192.168.10.106:5060 ---> BYE sip:101@192.168.10.4:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK342592800;rport From: ;tag=1674326932 To: ;tag=as2b2e2423 Call-ID: 2000295813-5060-336@BJC.BGI.BA.BAG CSeq: 1082 BYE Contact: X-Grandstream-PBX: true Max-Forwards: 70 Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (13 headers 0 lines) — Sending to 192.168.10.106:5060 (no NAT) Scheduling destruction of SIP dialog '2000295813-5060-336@BJC.BGI.BA.BAG' in 6400 ms (Method: BYE) <--- Transmitting (no NAT) to 192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK342592800;received=192.168.10.106;rport=5060 From: ;tag=1674326932 To: ;tag=as2b2e2423 Call-ID: 2000295813-5060-336@BJC.BGI.BA.BAG CSeq: 1082 BYE Server: FPBX-13.0.195.19(13.23.1) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 <------------> – Channel SIP/106-000000b3 left 'simple_bridge' basic-bridge <68206207-19b4-4f84-9c43-a5a39b17eddd> == Spawn extension (macro-dial-one, s, 54) exited non-zero on 'SIP/106-000000b3' in macro 'dial-one' == Spawn extension (macro-exten-vm, s, 14) exited non-zero on 'SIP/106-000000b3' in macro 'exten-vm' – Channel DAHDI/5-1 left 'simple_bridge' basic-bridge <68206207-19b4-4f84-9c43-a5a39b17eddd> == Spawn extension (ext-local, 101, 2) exited non-zero on 'SIP/106-000000b3' – Executing [h@ext-local:1] Macro("SIP/106-000000b3", "hangupcall,") in new stack – Hanging up on 'DAHDI/5-1' – Hungup 'DAHDI/5-1' – Executing [s@macro-hangupcall:1] GotoIf("SIP/106-000000b3", "1?theend") in new stack – Goto (macro-hangupcall,s,3) – Executing [s@macro-hangupcall:3] ExecIf("SIP/106-000000b3", "0?Set(CDR(recordingfile)=)") in new stack == Extension Changed 101[ext-local] new state Idle for Notify User 100 == Extension Changed 101[ext-local] new state Idle for Notify User 104 set_destination: Parsing for address/port to send to set_destination: set destination to 192.168.10.106:5060 Reliably Transmitting (no NAT) to 192.168.10.106:5060: NOTIFY sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK4c6e7a99;rport Max-Forwards: 70 From: ;tag=as31fae48c To: ;tag=395955848 Contact: Call-ID: 413681140-5060-317@BJC.BGI.BA.BAG CSeq: 274 NOTIFY User-Agent: FPBX-13.0.195.19(13.23.1) Subscription-State: active Event: dialog Content-Type: application/dialog-info+xml Content-Length: 205 terminated — == Extension Changed 101[ext-local] new state Idle for Notify User 106 == Extension Changed 101[ext-local] new state Idle for Notify User 103 – Executing [s@macro-hangupcall:4] Hangup("SIP/106-000000b3", "") in new stack == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/106-000000b3' in macro 'hangupcall' == Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/106-000000b3' == Extension Changed 101[ext-local] new state Idle for Notify User 105 == Extension Changed 106[ext-local] new state Idle for Notify User 103 == Extension Changed 106[ext-local] new state Idle for Notify User 100 == Extension Changed 106[ext-local] new state Idle for Notify User 104 == Extension Changed 106[ext-local] new state Idle for Notify User 105 Retransmitting #1 (no NAT) to 192.168.10.106:5060: NOTIFY sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK4c6e7a99;rport Max-Forwards: 70 From: ;tag=as31fae48c To: ;tag=395955848 Contact: Call-ID: 413681140-5060-317@BJC.BGI.BA.BAG CSeq: 274 NOTIFY User-Agent: FPBX-13.0.195.19(13.23.1) Subscription-State: active Event: dialog Content-Type: application/dialog-info+xml Content-Length: 205 terminated — <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK4c6e7a99;rport=5060 From: ;tag=as31fae48c To: ;tag=395955848 Call-ID: 413681140-5060-317@BJC.BGI.BA.BAG CSeq: 274 NOTIFY Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK4c6e7a99;rport=5060 From: ;tag=as31fae48c To: ;tag=395955848 Call-ID: 413681140-5060-317@BJC.BGI.BA.BAG CSeq: 274 NOTIFY Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — Here I'm attaching a SIP debug for peer 106, calling 107, both are set to iLBC, neither can hear each other. freepbx*CLI> sip set debug peer 106 SIP Debugging Enabled for IP: 192.168.10.106 <--- SIP read from UDP:192.168.10.106:5060 ---> INVITE sip:107@192.168.10.4 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1551511013;rport From: ;tag=118405106 To: Call-ID: 1224405074-5060-340@BJC.BGI.BA.BAG CSeq: 1120 INVITE Contact: X-Grandstream-PBX: true Max-Forwards: 70 User-Agent: Grandstream GXP2110 1.0.8.6 Privacy: none P-Preferred-Identity: Supported: replaces, path, timer Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Type: application/sdp Accept: application/sdp, application/dtmf-relay Content-Length: 405 v=0 o=106 8000 8000 IN IP4 192.168.10.106 s=SIP Call c=IN IP4 192.168.10.106 t=0 0 m=audio 5004 RTP/AVP 97 8 4 18 9 0 2 101 a=sendrecv a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=30 a=ptime:60 a=rtpmap:8 PCMA/8000 a=rtpmap:4 G723/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 <-------------> — (17 headers 19 lines) — Sending to 192.168.10.106:5060 (no NAT) Sending to 192.168.10.106:5060 (no NAT) Using INVITE request as basis request - 1224405074-5060-340@BJC.BGI.BA.BAG Found peer '106' for '106' from 192.168.10.106:5060 <--- Reliably Transmitting (no NAT) to 192.168.10.106:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1551511013;received=192.168.10.106;rport=5060 From: ;tag=118405106 To: ;tag=as77dff450 Call-ID: 1224405074-5060-340@BJC.BGI.BA.BAG CSeq: 1120 INVITE Server: FPBX-13.0.195.19(13.23.1) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="2242e0f6" Content-Length: 0 <------------> Scheduling destruction of SIP dialog '1224405074-5060-340@BJC.BGI.BA.BAG' in 6400 ms (Method: INVITE) <--- SIP read from UDP:192.168.10.106:5060 ---> ACK sip:107@192.168.10.4 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1551511013;rport From: ;tag=118405106 To: ;tag=as77dff450 Call-ID: 1224405074-5060-340@BJC.BGI.BA.BAG CSeq: 1120 ACK Content-Length: 0 <-------------> — (7 headers 0 lines) — <--- SIP read from UDP:192.168.10.106:5060 ---> INVITE sip:107@192.168.10.4 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK587288699;rport From: ;tag=118405106 To: Call-ID: 1224405074-5060-340@BJC.BGI.BA.BAG CSeq: 1121 INVITE Contact: Authorization: Digest username="106", realm="asterisk", nonce="2242e0f6", uri="sip:107@192.168.10.4", response="572c1e8497982e16b5f85ecf869e980b", algorithm=MD5 X-Grandstream-PBX: true Max-Forwards: 70 User-Agent: Grandstream GXP2110 1.0.8.6 Privacy: none P-Preferred-Identity: Supported: replaces, path, timer Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Type: application/sdp Accept: application/sdp, application/dtmf-relay Content-Length: 405 v=0 o=106 8000 8000 IN IP4 192.168.10.106 s=SIP Call c=IN IP4 192.168.10.106 t=0 0 m=audio 5004 RTP/AVP 97 8 4 18 9 0 2 101 a=sendrecv a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=30 a=ptime:60 a=rtpmap:8 PCMA/8000 a=rtpmap:4 G723/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 <-------------> — (18 headers 19 lines) — Sending to 192.168.10.106:5060 (no NAT) Using INVITE request as basis request - 1224405074-5060-340@BJC.BGI.BA.BAG Found peer '106' for '106' from 192.168.10.106:5060 == Using SIP RTP TOS bits 184 == Using SIP RTP CoS mark 5 Found RTP audio format 97 Found RTP audio format 8 Found RTP audio format 4 Found RTP audio format 18 Found RTP audio format 9 Found RTP audio format 0 Found RTP audio format 2 Found RTP audio format 101 Found audio description format iLBC for ID 97 Found audio description format PCMA for ID 8 Found audio description format G723 for ID 4 Found audio description format G729 for ID 18 Found audio description format G722 for ID 9 Found audio description format PCMU for ID 0 Found audio description format G726-32 for ID 2 Found audio description format telephone-event for ID 101 Capabilities: us - (ilbc|g729|ulaw|alaw|g722), peer - audio=(ulaw|g726|g723|alaw|g722|g729|ilbc)/video=(nothing)/text=(nothing), combined - (ilbc|g729|ulaw|alaw|g722) Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) > 0xb7452bd0 – Strict RTP learning after remote address set to: 192.168.10.106:5004 Peer audio RTP is at port 192.168.10.106:5004 Looking for 107 in from-internal (domain 192.168.10.4) == Extension Changed 106[ext-local] new state InUse for Notify User 103 == Extension Changed 106[ext-local] new state InUse for Notify User 100 == Extension Changed 106[ext-local] new state InUse for Notify User 104 == Extension Changed 106[ext-local] new state InUse for Notify User 105 sip_route_dump: route/path hop: <--- Transmitting (no NAT) to 192.168.10.106:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK587288699;received=192.168.10.106;rport=5060 From: ;tag=118405106 To: Call-ID: 1224405074-5060-340@BJC.BGI.BA.BAG CSeq: 1121 INVITE Server: FPBX-13.0.195.19(13.23.1) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> – Executing [107@from-internal:1] GotoIf("SIP/106-000000bc", "1?ext-local,107,1:followme-check,107,1") in new stack – Goto (ext-local,107,1) – Executing [107@ext-local:1] Set("SIP/106-000000bc", "__RINGTIMER=15") in new stack – Executing [107@ext-local:2] Macro("SIP/106-000000bc", "exten-vm,novm,107,0,0,0") in new stack – Executing [s@macro-exten-vm:1] Macro("SIP/106-000000bc", "user-callerid,") in new stack – Executing [s@macro-user-callerid:1] Set("SIP/106-000000bc", "TOUCH_MONITOR=1544206193.1628") in new stack – Executing [s@macro-user-callerid:2] Set("SIP/106-000000bc", "AMPUSER=106") in new stack – Executing [s@macro-user-callerid:3] GotoIf("SIP/106-000000bc", "0?report") in new stack – Executing [s@macro-user-callerid:4] ExecIf("SIP/106-000000bc", "1?Set(REALCALLERIDNUM=106)") in new stack – Executing [s@macro-user-callerid:5] Set("SIP/106-000000bc", "AMPUSER=106") in new stack – Executing [s@macro-user-callerid:6] GotoIf("SIP/106-000000bc", "0?limit") in new stack – Executing [s@macro-user-callerid:7] Set("SIP/106-000000bc", "AMPUSERCIDNAME=Marianela Lopez") in new stack – Executing [s@macro-user-callerid:8] ExecIf("SIP/106-000000bc", "0?Set(__CIDMASQUERADING=TRUE)") in new stack – Executing [s@macro-user-callerid:9] GotoIf("SIP/106-000000bc", "0?report") in new stack – Executing [s@macro-user-callerid:10] Set("SIP/106-000000bc", "AMPUSERCID=106") in new stack – Executing [s@macro-user-callerid:11] Set("SIP/106-000000bc", "__DIAL_OPTIONS=Ttr") in new stack – Executing [s@macro-user-callerid:12] Set("SIP/106-000000bc", "CALLERID(all)="Marianela Lopez" <106>") in new stack – Executing [s@macro-user-callerid:13] GotoIf("SIP/106-000000bc", "0?limit") in new stack – Executing [s@macro-user-callerid:14] ExecIf("SIP/106-000000bc", "0?Set(GROUP(concurrency_limit)=106)") in new stack – Executing [s@macro-user-callerid:15] GosubIf("SIP/106-000000bc", "7?sub-ccss,s,1(macro-exten-vm,107)") in new stack – Executing [s@sub-ccss:1] ExecIf("SIP/106-000000bc", "0?Return()") in new stack – Executing [s@sub-ccss:2] Set("SIP/106-000000bc", "CCSS_SETUP=TRUE") in new stack – Executing [s@sub-ccss:3] GosubIf("SIP/106-000000bc", "0?monitor_config,1(macro-exten-vm,107):monitor_default,1(macro-exten-vm,107)") in new stack – Executing [monitor_default@sub-ccss:1] GotoIf("SIP/106-000000bc", "1?is_exten") in new stack – Goto (sub-ccss,monitor_default,4) – Executing [monitor_default@sub-ccss:4] Set("SIP/106-000000bc", "CALLCOMPLETION(cc_monitor_policy)=generic") in new stack – Executing [monitor_default@sub-ccss:5] Set("SIP/106-000000bc", "CALLCOMPLETION(cc_max_monitors)=5") in new stack – Executing [monitor_default@sub-ccss:6] Return("SIP/106-000000bc", "TRUE") in new stack – Executing [s@sub-ccss:4] GosubIf("SIP/106-000000bc", "7?agent_config,1():agent_default,1()") in new stack – Executing [agent_config@sub-ccss:1] Set("SIP/106-000000bc", "CALLCOMPLETION(cc_agent_policy)=generic") in new stack – Executing [agent_config@sub-ccss:2] Set("SIP/106-000000bc", "CALLCOMPLETION(cc_offer_timer)=30") in new stack – Executing [agent_config@sub-ccss:3] Set("SIP/106-000000bc", "CALLCOMPLETION(ccbs_available_timer)=") in new stack – Executing [agent_config@sub-ccss:4] Set("SIP/106-000000bc", "CALLCOMPLETION(ccnr_available_timer)=") in new stack – Executing [agent_config@sub-ccss:5] Set("SIP/106-000000bc", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack [2018-12-07 15:09:54] WARNING[562][C-00000081]: ccss.c:1012 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead. – Executing [agent_config@sub-ccss:6] ExecIf("SIP/106-000000bc", "1?Set(CALLCOMPLETION(cc_recall_timer)=)") in new stack – Executing [agent_config@sub-ccss:7] ExecIf("SIP/106-000000bc", "1?Set(CALLCOMPLETION(cc_max_agents)=)") in new stack – Executing [agent_config@sub-ccss:8] ExecIf("SIP/106-000000bc", "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/106_107@from-ccss-)") in new stack – Executing [agent_config@sub-ccss:9] Set("SIP/106-000000bc", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack [2018-12-07 15:09:54] WARNING[562][C-00000081]: ccss.c:1012 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead. – Executing [agent_config@sub-ccss:10] Return("SIP/106-000000bc", "") in new stack – Executing [s@sub-ccss:5] Set("SIP/106-000000bc", "DB(AMPUSER/106/ccss/last_number)=107") in new stack – Executing [s@sub-ccss:6] Return("SIP/106-000000bc", "") in new stack – Executing [s@macro-user-callerid:16] ExecIf("SIP/106-000000bc", "0?Set(CHANNEL(language)=)") in new stack – Executing [s@macro-user-callerid:17] NoOp("SIP/106-000000bc", "Macro Depth is 2") in new stack – Executing [s@macro-user-callerid:18] GotoIf("SIP/106-000000bc", "1?report2:macroerror") in new stack – Goto (macro-user-callerid,s,19) – Executing [s@macro-user-callerid:19] GotoIf("SIP/106-000000bc", "0?continue") in new stack – Executing [s@macro-user-callerid:20] ExecIf("SIP/106-000000bc", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack – Executing [s@macro-user-callerid:21] Set("SIP/106-000000bc", "__TTL=64") in new stack – Executing [s@macro-user-callerid:22] GotoIf("SIP/106-000000bc", "1?continue") in new stack – Goto (macro-user-callerid,s,38) – Executing [s@macro-user-callerid:38] Set("SIP/106-000000bc", "CALLERID(number)=106") in new stack – Executing [s@macro-user-callerid:39] Set("SIP/106-000000bc", "CALLERID(name)=Marianela Lopez") in new stack – Executing [s@macro-user-callerid:40] GotoIf("SIP/106-000000bc", "0?cnum") in new stack – Executing [s@macro-user-callerid:41] Set("SIP/106-000000bc", "CDR(cnam)=Marianela Lopez") in new stack – Executing [s@macro-user-callerid:42] Set("SIP/106-000000bc", "CDR(cnum)=106") in new stack – Executing [s@macro-user-callerid:43] Set("SIP/106-000000bc", "CHANNEL(language)=es") in new stack – Executing [s@macro-exten-vm:2] Set("SIP/106-000000bc", "RingGroupMethod=none") in new stack – Executing [s@macro-exten-vm:3] Set("SIP/106-000000bc", "__EXTTOCALL=107") in new stack – Executing [s@macro-exten-vm:4] Set("SIP/106-000000bc", "__PICKUPMARK=107") in new stack – Executing [s@macro-exten-vm:5] Set("SIP/106-000000bc", "RT=") in new stack – Executing [s@macro-exten-vm:6] Gosub("SIP/106-000000bc", "sub-record-check,s,1(exten,107,dontcare)") in new stack – Executing [s@sub-record-check:1] GotoIf("SIP/106-000000bc", "0?initialized") in new stack – Executing [s@sub-record-check:2] Set("SIP/106-000000bc", "__REC_STATUS=INITIALIZED") in new stack – Executing [s@sub-record-check:3] Set("SIP/106-000000bc", "NOW=1544206194") in new stack – Executing [s@sub-record-check:4] Set("SIP/106-000000bc", "__DAY=07") in new stack – Executing [s@sub-record-check:5] Set("SIP/106-000000bc", "__MONTH=12") in new stack – Executing [s@sub-record-check:6] Set("SIP/106-000000bc", "__YEAR=2018") in new stack – Executing [s@sub-record-check:7] Set("SIP/106-000000bc", "__TIMESTR=20181207-150954") in new stack – Executing [s@sub-record-check:8] Set("SIP/106-000000bc", "__FROMEXTEN=106") in new stack – Executing [s@sub-record-check:9] Set("SIP/106-000000bc", "__MON_FMT=wav") in new stack – Executing [s@sub-record-check:10] NoOp("SIP/106-000000bc", "Recordings initialized") in new stack – Executing [s@sub-record-check:11] ExecIf("SIP/106-000000bc", "0?Set(ARG3=dontcare)") in new stack – Executing [s@sub-record-check:12] Set("SIP/106-000000bc", "REC_POLICY_MODE_SAVE=") in new stack – Executing [s@sub-record-check:13] ExecIf("SIP/106-000000bc", "0?Set(REC_STATUS=NO)") in new stack – Executing [s@sub-record-check:14] GotoIf("SIP/106-000000bc", "5?checkaction") in new stack – Goto (sub-record-check,s,17) – Executing [s@sub-record-check:17] GotoIf("SIP/106-000000bc", "1?sub-record-check,exten,1") in new stack – Goto (sub-record-check,exten,1) – Executing [exten@sub-record-check:1] NoOp("SIP/106-000000bc", "Exten Recording Check between 106 and 107") in new stack – Executing [exten@sub-record-check:2] Set("SIP/106-000000bc", "CALLTYPE=internal") in new stack – Executing [exten@sub-record-check:3] ExecIf("SIP/106-000000bc", "0?Set(CALLTYPE=)") in new stack – Executing [exten@sub-record-check:4] Set("SIP/106-000000bc", "CALLEE=dontcare") in new stack – Executing [exten@sub-record-check:5] ExecIf("SIP/106-000000bc", "0?Set(CALLEE=dontcare)") in new stack – Executing [exten@sub-record-check:6] GotoIf("SIP/106-000000bc", "0?callee") in new stack – Executing [exten@sub-record-check:7] GotoIf("SIP/106-000000bc", "1?caller") in new stack – Goto (sub-record-check,exten,13) – Executing [exten@sub-record-check:13] Set("SIP/106-000000bc", "RECMODE=dontcare") in new stack – Executing [exten@sub-record-check:14] ExecIf("SIP/106-000000bc", "0?Set(RECMODE=dontcare)") in new stack – Executing [exten@sub-record-check:15] ExecIf("SIP/106-000000bc", "1?Set(RECMODE=dontcare)") in new stack – Executing [exten@sub-record-check:16] Gosub("SIP/106-000000bc", "recordcheck,1(dontcare,internal,107)") in new stack – Executing [recordcheck@sub-record-check:1] NoOp("SIP/106-000000bc", "Starting recording check against dontcare") in new stack – Executing [recordcheck@sub-record-check:2] Goto("SIP/106-000000bc", "dontcare") in new stack – Goto (sub-record-check,recordcheck,3) – Executing [recordcheck@sub-record-check:3] Return("SIP/106-000000bc", "") in new stack – Executing [exten@sub-record-check:17] Return("SIP/106-000000bc", "") in new stack – Executing [s@macro-exten-vm:7] GotoIf("SIP/106-000000bc", "1?macrodial") in new stack – Goto (macro-exten-vm,s,13) – Executing [s@macro-exten-vm:13] GosubIf("SIP/106-000000bc", "0?clrheader,1()") in new stack – Executing [s@macro-exten-vm:14] Macro("SIP/106-000000bc", "dial-one,,Ttr,107") in new stack – Executing [s@macro-dial-one:1] Set("SIP/106-000000bc", "DEXTEN=107") in new stack – Executing [s@macro-dial-one:2] ExecIf("SIP/106-000000bc", "0?Set(__EXTTOCALL=107)") in new stack – Executing [s@macro-dial-one:3] Set("SIP/106-000000bc", "DIALSTATUS_CW=") in new stack – Executing [s@macro-dial-one:4] GosubIf("SIP/106-000000bc", "0?screen,1()") in new stack – Executing [s@macro-dial-one:5] GosubIf("SIP/106-000000bc", "0?cf,1()") in new stack – Executing [s@macro-dial-one:6] GotoIf("SIP/106-000000bc", "1?skip1") in new stack – Goto (macro-dial-one,s,9) – Executing [s@macro-dial-one:9] GotoIf("SIP/106-000000bc", "0?nodial") in new stack – Executing [s@macro-dial-one:10] GotoIf("SIP/106-000000bc", "0?continue") in new stack – Executing [s@macro-dial-one:11] Set("SIP/106-000000bc", "EXTHASCW=ENABLED") in new stack – Executing [s@macro-dial-one:12] GotoIf("SIP/106-000000bc", "0?next1:cwinusebusy") in new stack – Goto (macro-dial-one,s,24) – Executing [s@macro-dial-one:24] GotoIf("SIP/106-000000bc", "0?next3:continue") in new stack – Goto (macro-dial-one,s,26) – Executing [s@macro-dial-one:26] GotoIf("SIP/106-000000bc", "0?nodial") in new stack – Executing [s@macro-dial-one:27] GosubIf("SIP/106-000000bc", "1?dstring,1():dlocal,1()") in new stack – Executing [dstring@macro-dial-one:1] Set("SIP/106-000000bc", "DSTRING=") in new stack – Executing [dstring@macro-dial-one:2] Set("SIP/106-000000bc", "DEVICES=107") in new stack – Executing [dstring@macro-dial-one:3] ExecIf("SIP/106-000000bc", "0?Return()") in new stack – Executing [dstring@macro-dial-one:4] ExecIf("SIP/106-000000bc", "0?Set(DEVICES=07)") in new stack – Executing [dstring@macro-dial-one:5] Set("SIP/106-000000bc", "LOOPCNT=1") in new stack – Executing [dstring@macro-dial-one:6] Set("SIP/106-000000bc", "ITER=1") in new stack – Executing [dstring@macro-dial-one:7] Set("SIP/106-000000bc", "THISDIAL=SIP/107") in new stack – Executing [dstring@macro-dial-one:8] GosubIf("SIP/106-000000bc", "1?zap2dahdi,1()") in new stack – Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/106-000000bc", "0?Return()") in new stack – Executing [zap2dahdi@macro-dial-one:2] Set("SIP/106-000000bc", "NEWDIAL=") in new stack – Executing [zap2dahdi@macro-dial-one:3] Set("SIP/106-000000bc", "LOOPCNT2=1") in new stack – Executing [zap2dahdi@macro-dial-one:4] Set("SIP/106-000000bc", "ITER2=1") in new stack – Executing [zap2dahdi@macro-dial-one:5] Set("SIP/106-000000bc", "THISPART2=SIP/107") in new stack – Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/106-000000bc", "0?Set(THISPART2=DAHDI/107)") in new stack – Executing [zap2dahdi@macro-dial-one:7] Set("SIP/106-000000bc", "NEWDIAL=SIP/107&") in new stack – Executing [zap2dahdi@macro-dial-one:8] Set("SIP/106-000000bc", "ITER2=2") in new stack – Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/106-000000bc", "0?begin2") in new stack – Executing [zap2dahdi@macro-dial-one:10] Set("SIP/106-000000bc", "THISDIAL=SIP/107") in new stack – Executing [zap2dahdi@macro-dial-one:11] Return("SIP/106-000000bc", "") in new stack – Executing [dstring@macro-dial-one:9] GotoIf("SIP/106-000000bc", "1?docheck") in new stack – Goto (macro-dial-one,dstring,14) – Executing [dstring@macro-dial-one:14] GotoIf("SIP/106-000000bc", "0?skipset") in new stack – Executing [dstring@macro-dial-one:15] Set("SIP/106-000000bc", "DSTRING=SIP/107&") in new stack – Executing [dstring@macro-dial-one:16] Set("SIP/106-000000bc", "ITER=2") in new stack – Executing [dstring@macro-dial-one:17] GotoIf("SIP/106-000000bc", "0?begin") in new stack – Executing [dstring@macro-dial-one:18] ExecIf("SIP/106-000000bc", "0?Return()") in new stack – Executing [dstring@macro-dial-one:19] Set("SIP/106-000000bc", "DSTRING=SIP/107") in new stack – Executing [dstring@macro-dial-one:20] Return("SIP/106-000000bc", "") in new stack – Executing [s@macro-dial-one:28] GotoIf("SIP/106-000000bc", "0?nodial") in new stack – Executing [s@macro-dial-one:29] GotoIf("SIP/106-000000bc", "0?skiptrace") in new stack – Executing [s@macro-dial-one:30] GosubIf("SIP/106-000000bc", "1?ctset,1():ctclear,1()") in new stack – Executing [ctset@macro-dial-one:1] Set("SIP/106-000000bc", "DB(CALLTRACE/107)=106") in new stack – Executing [ctset@macro-dial-one:2] Return("SIP/106-000000bc", "") in new stack – Executing [s@macro-dial-one:31] Set("SIP/106-000000bc", "D_OPTIONS=Ttr") in new stack – Executing [s@macro-dial-one:32] GosubIf("SIP/106-000000bc", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack – Executing [s@macro-dial-one:33] NoOp("SIP/106-000000bc", "Blind Transfer: , Attended Transfer: , User: 106, Alert Info: ") in new stack – Executing [s@macro-dial-one:34] ExecIf("SIP/106-000000bc", "1?Set(ALERT_INFO=)") in new stack – Executing [s@macro-dial-one:35] ExecIf("SIP/106-000000bc", "0?Set(ALERT_INFO=)") in new stack – Executing [s@macro-dial-one:36] ExecIf("SIP/106-000000bc", "0?Set(ALERT_INFO=)") in new stack – Executing [s@macro-dial-one:37] ExecIf("SIP/106-000000bc", "0?Set(ALERT_INFO=Normal;volume=)") in new stack – Executing [s@macro-dial-one:38] ExecIf("SIP/106-000000bc", "0?Set(ALERT_INFO=Normal;volume=)") in new stack – Executing [s@macro-dial-one:39] GosubIf("SIP/106-000000bc", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack – Executing [s@macro-dial-one:40] ExecIf("SIP/106-000000bc", "0?Set(CHANNEL(musicclass)=)") in new stack – Executing [s@macro-dial-one:41] GosubIf("SIP/106-000000bc", "0?qwait,1()") in new stack – Executing [s@macro-dial-one:42] Set("SIP/106-000000bc", "__CWIGNORE=") in new stack – Executing [s@macro-dial-one:43] Set("SIP/106-000000bc", "__KEEPCID=TRUE") in new stack – Executing [s@macro-dial-one:44] GotoIf("SIP/106-000000bc", "0?usegoto,1") in new stack – Executing [s@macro-dial-one:45] GotoIf("SIP/106-000000bc", "0?godial") in new stack – Executing [s@macro-dial-one:46] Gosub("SIP/106-000000bc", "sub-presencestate-display,s,1(107)") in new stack – Executing [s@sub-presencestate-display:1] Goto("SIP/106-000000bc", "state-not_set,1") in new stack – Goto (sub-presencestate-display,state-not_set,1) – Executing [state-not_set@sub-presencestate-display:1] Set("SIP/106-000000bc", "PRESENCESTATE_DISPLAY=") in new stack – Executing [state-not_set@sub-presencestate-display:2] Return("SIP/106-000000bc", "") in new stack – Executing [s@macro-dial-one:47] Set("SIP/106-000000bc", "CONNECTEDLINE(name,i)=Rodrigo Martin") in new stack – Executing [s@macro-dial-one:48] Set("SIP/106-000000bc", "CONNECTEDLINE(num)=107") in new stack – Executing [s@macro-dial-one:49] Set("SIP/106-000000bc", "D_OPTIONS=TtrI") in new stack – Executing [s@macro-dial-one:50] Macro("SIP/106-000000bc", "dialout-one-predial-hook,") in new stack – Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/106-000000bc", "") in new stack – Executing [s@macro-dial-one:51] ExecIf("SIP/106-000000bc", "0?Set(D_OPTIONS=trII)") in new stack – Executing [s@macro-dial-one:52] NoOp("SIP/106-000000bc", "") in new stack – Executing [s@macro-dial-one:53] ExecIf("SIP/106-000000bc", "0?Set(D_OPTIONS=TtrIg)") in new stack – Executing [s@macro-dial-one:54] Dial("SIP/106-000000bc", "SIP/107,,TtrIb(func-apply-sipheaders^s^1)") in new stack == Using SIP RTP TOS bits 184 == Using SIP RTP CoS mark 5 – SIP/107-000000bd Internal Gosub(func-apply-sipheaders,s,1) start – Executing [s@func-apply-sipheaders:1] NoOp("SIP/107-000000bd", "Applying SIP Headers to channel") in new stack – Executing [s@func-apply-sipheaders:2] Set("SIP/107-000000bd", "SIPHEADERKEYS=") in new stack – Executing [s@func-apply-sipheaders:3] ExecIf("SIP/107-000000bd", "0?Set(Rheader=1)") in new stack – Executing [s@func-apply-sipheaders:4] While("SIP/107-000000bd", "0") in new stack – Jumping to priority 7 – Executing [s@func-apply-sipheaders:8] ExecIf("SIP/107-000000bd", "0?SIPRemoveHeader(Alert-Info") in new stack – Executing [s@func-apply-sipheaders:9] Return("SIP/107-000000bd", "") in new stack == Spawn extension (from-internal, 107, 1) exited non-zero on 'SIP/107-000000bd' – SIP/107-000000bd Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL= set_destination: Parsing for address/port to send to set_destination: set destination to 192.168.10.106:5060 Reliably Transmitting (no NAT) to 192.168.10.106:5060: NOTIFY sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK59fae04a;rport Max-Forwards: 70 From: ;tag=as76b7215d To: ;tag=1818557175 Contact: Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 259 NOTIFY User-Agent: FPBX-13.0.195.19(13.23.1) Subscription-State: active Event: dialog Content-Type: application/dialog-info+xml Content-Length: 222 early — == Extension Changed 107[ext-local] new state Ringing for Notify User 106 == Extension Changed 107[ext-local] new state Ringing for Notify User 105 == Extension Changed 107[ext-local] new state Ringing for Notify User 103 == Extension Changed 107[ext-local] new state Ringing for Notify User 100 == Extension Changed 107[ext-local] new state Ringing for Notify User 104 – Called SIP/107 <--- Transmitting (no NAT) to 192.168.10.106:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK587288699;received=192.168.10.106;rport=5060 From: ;tag=118405106 To: ;tag=as4b4d062d Call-ID: 1224405074-5060-340@BJC.BGI.BA.BAG CSeq: 1121 INVITE Server: FPBX-13.0.195.19(13.23.1) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> – Connected line update to SIP/106-000000bc prevented. <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK59fae04a;rport=5060 From: ;tag=as76b7215d To: ;tag=1818557175 Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 259 NOTIFY Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — set_destination: Parsing for address/port to send to set_destination: set destination to 192.168.10.106:5060 – SIP/107-000000bd is ringing Reliably Transmitting (no NAT) to 192.168.10.106:5060: NOTIFY sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK31bafbab;rport Max-Forwards: 70 From: ;tag=as76b7215d To: ;tag=1818557175 Contact: Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 260 NOTIFY User-Agent: FPBX-13.0.195.19(13.23.1) Subscription-State: active Event: dialog Content-Type: application/dialog-info+xml Content-Length: 222 early — == Extension Changed 107[ext-local] new state Ringing for Notify User 106 <--- Transmitting (no NAT) to 192.168.10.106:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK587288699;received=192.168.10.106;rport=5060 From: ;tag=118405106 To: ;tag=as4b4d062d Call-ID: 1224405074-5060-340@BJC.BGI.BA.BAG CSeq: 1121 INVITE Server: FPBX-13.0.195.19(13.23.1) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> == Extension Changed 107[ext-local] new state Ringing for Notify User 105 == Extension Changed 107[ext-local] new state Ringing for Notify User 103 == Extension Changed 107[ext-local] new state Ringing for Notify User 100 == Extension Changed 107[ext-local] new state Ringing for Notify User 104 <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK31bafbab;rport=5060 From: ;tag=as76b7215d To: ;tag=1818557175 Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 260 NOTIFY Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — > 0x918bc88 – Strict RTP learning after remote address set to: 192.168.10.107:5020 – Connected line update to SIP/106-000000bc prevented. – SIP/107-000000bd answered SIP/106-000000bc Audio is at 18696 Adding codec ilbc to SDP Adding codec g729 to SDP Adding codec ulaw to SDP Adding codec alaw to SDP Adding codec g722 to SDP Adding non-codec 0x1 (telephone-event) to SDP <--- Reliably Transmitting (no NAT) to 192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK587288699;received=192.168.10.106;rport=5060 From: ;tag=118405106 To: ;tag=as4b4d062d Call-ID: 1224405074-5060-340@BJC.BGI.BA.BAG CSeq: 1121 INVITE Server: FPBX-13.0.195.19(13.23.1) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Type: application/sdp Require: timer Content-Length: 371 v=0 o=root 986582881 986582881 IN IP4 192.168.10.4 s=Asterisk PBX 13.23.1 c=IN IP4 192.168.10.4 t=0 0 m=audio 18696 RTP/AVP 97 18 0 8 9 101 a=rtpmap:97 iLBC/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv <------------> set_destination: Parsing for address/port to send to set_destination: set destination to 192.168.10.106:5060 Reliably Transmitting (no NAT) to 192.168.10.106:5060: NOTIFY sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK240c6fa9;rport Max-Forwards: 70 From: ;tag=as76b7215d To: ;tag=1818557175 Contact: Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 261 NOTIFY User-Agent: FPBX-13.0.195.19(13.23.1) Subscription-State: active Event: dialog Content-Type: application/dialog-info+xml Content-Length: 204 confirmed — == Extension Changed 107[ext-local] new state InUse for Notify User 106 == Extension Changed 107[ext-local] new state InUse for Notify User 105 == Extension Changed 107[ext-local] new state InUse for Notify User 103 – Channel SIP/107-000000bd joined 'simple_bridge' basic-bridge <285a31cc-93e9-455f-9d95-6d70f85d7d2a> == Extension Changed 107[ext-local] new state InUse for Notify User 100 – Channel SIP/106-000000bc joined 'simple_bridge' basic-bridge <285a31cc-93e9-455f-9d95-6d70f85d7d2a> == Extension Changed 107[ext-local] new state InUse for Notify User 104 <--- SIP read from UDP:192.168.10.106:5060 ---> ACK sip:107@192.168.10.4:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK436859343;rport From: ;tag=118405106 To: ;tag=as4b4d062d Call-ID: 1224405074-5060-340@BJC.BGI.BA.BAG CSeq: 1121 ACK Contact: X-Grandstream-PBX: true Max-Forwards: 70 Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (13 headers 0 lines) — > 0x918bc88 – Strict RTP switching to RTP target address 192.168.10.107:5020 as source Retransmitting #1 (no NAT) to 192.168.10.106:5060: NOTIFY sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK240c6fa9;rport Max-Forwards: 70 From: ;tag=as76b7215d To: ;tag=1818557175 Contact: Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 261 NOTIFY User-Agent: FPBX-13.0.195.19(13.23.1) Subscription-State: active Event: dialog Content-Type: application/dialog-info+xml Content-Length: 204 confirmed — <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK240c6fa9;rport=5060 From: ;tag=as76b7215d To: ;tag=1818557175 Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 261 NOTIFY Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK240c6fa9;rport=5060 From: ;tag=as76b7215d To: ;tag=1818557175 Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 261 NOTIFY Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — > 0xb7452bd0 – Strict RTP switching to RTP target address 192.168.10.106:5004 as source > 0xb7452bd0 – Strict RTP learning complete - Locking on source address 192.168.10.106:5004 > 0x918bc88 – Strict RTP learning complete - Locking on source address 192.168.10.107:5020 – Channel SIP/107-000000bd left 'simple_bridge' basic-bridge <285a31cc-93e9-455f-9d95-6d70f85d7d2a> – Channel SIP/106-000000bc left 'simple_bridge' basic-bridge <285a31cc-93e9-455f-9d95-6d70f85d7d2a> == Spawn extension (macro-dial-one, s, 54) exited non-zero on 'SIP/106-000000bc' in macro 'dial-one' == Spawn extension (macro-exten-vm, s, 14) exited non-zero on 'SIP/106-000000bc' in macro 'exten-vm' == Spawn extension (ext-local, 107, 2) exited non-zero on 'SIP/106-000000bc' – Executing [h@ext-local:1] Macro("SIP/106-000000bc", "hangupcall,") in new stack – Executing [s@macro-hangupcall:1] GotoIf("SIP/106-000000bc", "1?theend") in new stack – Goto (macro-hangupcall,s,3) – Executing [s@macro-hangupcall:3] ExecIf("SIP/106-000000bc", "0?Set(CDR(recordingfile)=)") in new stack – Executing [s@macro-hangupcall:4] Hangup("SIP/106-000000bc", "") in new stack == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/106-000000bc' in macro 'hangupcall' == Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/106-000000bc' Scheduling destruction of SIP dialog '1224405074-5060-340@BJC.BGI.BA.BAG' in 6400 ms (Method: ACK) == Extension Changed 106[ext-local] new state Idle for Notify User 103 == Extension Changed 106[ext-local] new state Idle for Notify User 100 == Extension Changed 106[ext-local] new state Idle for Notify User 104 == Extension Changed 106[ext-local] new state Idle for Notify User 105 set_destination: Parsing for address/port to send to set_destination: set destination to 192.168.10.106:5060 Reliably Transmitting (no NAT) to 192.168.10.106:5060: NOTIFY sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK4064396f;rport Max-Forwards: 70 From: ;tag=as76b7215d To: ;tag=1818557175 Contact: Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 262 NOTIFY User-Agent: FPBX-13.0.195.19(13.23.1) Subscription-State: active Event: dialog Content-Type: application/dialog-info+xml Content-Length: 205 terminated — == Extension Changed 107[ext-local] new state Idle for Notify User 106 == Extension Changed 107[ext-local] new state Idle for Notify User 105 == Extension Changed 107[ext-local] new state Idle for Notify User 103 == Extension Changed 107[ext-local] new state Idle for Notify User 100 == Extension Changed 107[ext-local] new state Idle for Notify User 104 set_destination: Parsing for address/port to send to set_destination: set destination to 192.168.10.106:5060 Reliably Transmitting (no NAT) to 192.168.10.106:5060: BYE sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK4726c7de;rport Max-Forwards: 70 From: ;tag=as4b4d062d To: ;tag=118405106 Call-ID: 1224405074-5060-340@BJC.BGI.BA.BAG CSeq: 102 BYE User-Agent: FPBX-13.0.195.19(13.23.1) Proxy-Authorization: Digest username="106", realm="asterisk", algorithm=MD5, uri="sip:192.168.10.4", nonce="2242e0f6", response="0777db9fc88e45ae6380182084debed3" X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 Content-Length: 0 — <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK4064396f;rport=5060 From: ;tag=as76b7215d To: ;tag=1818557175 Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 262 NOTIFY Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK4726c7de;rport=5060 From: ;tag=as4b4d062d To: ;tag=118405106 Call-ID: 1224405074-5060-340@BJC.BGI.BA.BAG CSeq: 102 BYE Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — SIP Response message for INCOMING dialog BYE arrived Really destroying SIP dialog '1224405074-5060-340@BJC.BGI.BA.BAG' Method: ACK Really destroying SIP dialog '124920770-5060-1@BJC.BGI.BA.BAG' Method: REGISTER And lastly, here is a SIP debug for peer 106, calling 107, both set to g729 and both can hear each other, no audio issues whatsoever freepbx*CLI> sip set debug peer 106 SIP Debugging Enabled for IP: 192.168.10.106 <--- SIP read from UDP:192.168.10.106:5060 ---> INVITE sip:107@192.168.10.4 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK155806354;rport From: ;tag=1872648879 To: Call-ID: 543030694-5060-342@BJC.BGI.BA.BAG CSeq: 1140 INVITE Contact: X-Grandstream-PBX: true Max-Forwards: 70 User-Agent: Grandstream GXP2110 1.0.8.6 Privacy: none P-Preferred-Identity: Supported: replaces, path, timer Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Type: application/sdp Accept: application/sdp, application/dtmf-relay Content-Length: 405 v=0 o=106 8000 8000 IN IP4 192.168.10.106 s=SIP Call c=IN IP4 192.168.10.106 t=0 0 m=audio 5004 RTP/AVP 0 8 4 18 9 97 2 101 a=sendrecv a=rtpmap:0 PCMU/8000 a=ptime:20 a=rtpmap:8 PCMA/8000 a=rtpmap:4 G723/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:9 G722/8000 a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=30 a=rtpmap:2 G726-32/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 <-------------> — (17 headers 19 lines) — Sending to 192.168.10.106:5060 (no NAT) Sending to 192.168.10.106:5060 (no NAT) Using INVITE request as basis request - 543030694-5060-342@BJC.BGI.BA.BAG Found peer '106' for '106' from 192.168.10.106:5060 <--- Reliably Transmitting (no NAT) to 192.168.10.106:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK155806354;received=192.168.10.106;rport=5060 From: ;tag=1872648879 To: ;tag=as3e5472fb Call-ID: 543030694-5060-342@BJC.BGI.BA.BAG CSeq: 1140 INVITE Server: FPBX-13.0.195.19(13.23.1) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="60045ee1" Content-Length: 0 <------------> Scheduling destruction of SIP dialog '543030694-5060-342@BJC.BGI.BA.BAG' in 6400 ms (Method: INVITE) <--- SIP read from UDP:192.168.10.106:5060 ---> ACK sip:107@192.168.10.4 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK155806354;rport From: ;tag=1872648879 To: ;tag=as3e5472fb Call-ID: 543030694-5060-342@BJC.BGI.BA.BAG CSeq: 1140 ACK Content-Length: 0 <-------------> — (7 headers 0 lines) — <--- SIP read from UDP:192.168.10.106:5060 ---> INVITE sip:107@192.168.10.4 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1588148074;rport From: ;tag=1872648879 To: Call-ID: 543030694-5060-342@BJC.BGI.BA.BAG CSeq: 1141 INVITE Contact: Authorization: Digest username="106", realm="asterisk", nonce="60045ee1", uri="sip:107@192.168.10.4", response="05b9c873f97d0a31921580b707d82694", algorithm=MD5 X-Grandstream-PBX: true Max-Forwards: 70 User-Agent: Grandstream GXP2110 1.0.8.6 Privacy: none P-Preferred-Identity: Supported: replaces, path, timer Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Type: application/sdp Accept: application/sdp, application/dtmf-relay Content-Length: 405 v=0 o=106 8000 8000 IN IP4 192.168.10.106 s=SIP Call c=IN IP4 192.168.10.106 t=0 0 m=audio 5004 RTP/AVP 0 8 4 18 9 97 2 101 a=sendrecv a=rtpmap:0 PCMU/8000 a=ptime:20 a=rtpmap:8 PCMA/8000 a=rtpmap:4 G723/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:9 G722/8000 a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=30 a=rtpmap:2 G726-32/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 <-------------> — (18 headers 19 lines) — Sending to 192.168.10.106:5060 (no NAT) Using INVITE request as basis request - 543030694-5060-342@BJC.BGI.BA.BAG Found peer '106' for '106' from 192.168.10.106:5060 == Using SIP RTP TOS bits 184 == Using SIP RTP CoS mark 5 Found RTP audio format 0 Found RTP audio format 8 Found RTP audio format 4 Found RTP audio format 18 Found RTP audio format 9 Found RTP audio format 97 Found RTP audio format 2 Found RTP audio format 101 Found audio description format PCMU for ID 0 Found audio description format PCMA for ID 8 Found audio description format G723 for ID 4 Found audio description format G729 for ID 18 Found audio description format G722 for ID 9 Found audio description format iLBC for ID 97 Found audio description format G726-32 for ID 2 Found audio description format telephone-event for ID 101 Capabilities: us - (g729|ulaw|alaw|g722|ilbc), peer - audio=(ulaw|g726|g723|alaw|g722|g729|ilbc)/video=(nothing)/text=(nothing), combined - (g729|ulaw|alaw|g722|ilbc) Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) > 0xb601b280 – Strict RTP learning after remote address set to: 192.168.10.106:5004 Peer audio RTP is at port 192.168.10.106:5004 Looking for 107 in from-internal (domain 192.168.10.4) == Extension Changed 106[ext-local] new state InUse for Notify User 103 == Extension Changed 106[ext-local] new state InUse for Notify User 100 == Extension Changed 106[ext-local] new state InUse for Notify User 104 sip_route_dump: route/path hop: <--- Transmitting (no NAT) to 192.168.10.106:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1588148074;received=192.168.10.106;rport=5060 From: ;tag=1872648879 To: Call-ID: 543030694-5060-342@BJC.BGI.BA.BAG CSeq: 1141 INVITE Server: FPBX-13.0.195.19(13.23.1) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> == Extension Changed 106[ext-local] new state InUse for Notify User 105 – Executing [107@from-internal:1] GotoIf("SIP/106-000000c0", "1?ext-local,107,1:followme-check,107,1") in new stack – Goto (ext-local,107,1) – Executing [107@ext-local:1] Set("SIP/106-000000c0", "__RINGTIMER=15") in new stack – Executing [107@ext-local:2] Macro("SIP/106-000000c0", "exten-vm,novm,107,0,0,0") in new stack – Executing [s@macro-exten-vm:1] Macro("SIP/106-000000c0", "user-callerid,") in new stack – Executing [s@macro-user-callerid:1] Set("SIP/106-000000c0", "TOUCH_MONITOR=1544206463.1660") in new stack – Executing [s@macro-user-callerid:2] Set("SIP/106-000000c0", "AMPUSER=106") in new stack – Executing [s@macro-user-callerid:3] GotoIf("SIP/106-000000c0", "0?report") in new stack – Executing [s@macro-user-callerid:4] ExecIf("SIP/106-000000c0", "1?Set(REALCALLERIDNUM=106)") in new stack – Executing [s@macro-user-callerid:5] Set("SIP/106-000000c0", "AMPUSER=106") in new stack – Executing [s@macro-user-callerid:6] GotoIf("SIP/106-000000c0", "0?limit") in new stack – Executing [s@macro-user-callerid:7] Set("SIP/106-000000c0", "AMPUSERCIDNAME=Marianela Lopez") in new stack – Executing [s@macro-user-callerid:8] ExecIf("SIP/106-000000c0", "0?Set(__CIDMASQUERADING=TRUE)") in new stack – Executing [s@macro-user-callerid:9] GotoIf("SIP/106-000000c0", "0?report") in new stack – Executing [s@macro-user-callerid:10] Set("SIP/106-000000c0", "AMPUSERCID=106") in new stack – Executing [s@macro-user-callerid:11] Set("SIP/106-000000c0", "__DIAL_OPTIONS=Ttr") in new stack – Executing [s@macro-user-callerid:12] Set("SIP/106-000000c0", "CALLERID(all)="Marianela Lopez" <106>") in new stack – Executing [s@macro-user-callerid:13] GotoIf("SIP/106-000000c0", "0?limit") in new stack – Executing [s@macro-user-callerid:14] ExecIf("SIP/106-000000c0", "0?Set(GROUP(concurrency_limit)=106)") in new stack – Executing [s@macro-user-callerid:15] GosubIf("SIP/106-000000c0", "7?sub-ccss,s,1(macro-exten-vm,107)") in new stack – Executing [s@sub-ccss:1] ExecIf("SIP/106-000000c0", "0?Return()") in new stack – Executing [s@sub-ccss:2] Set("SIP/106-000000c0", "CCSS_SETUP=TRUE") in new stack – Executing [s@sub-ccss:3] GosubIf("SIP/106-000000c0", "0?monitor_config,1(macro-exten-vm,107):monitor_default,1(macro-exten-vm,107)") in new stack – Executing [monitor_default@sub-ccss:1] GotoIf("SIP/106-000000c0", "1?is_exten") in new stack – Goto (sub-ccss,monitor_default,4) – Executing [monitor_default@sub-ccss:4] Set("SIP/106-000000c0", "CALLCOMPLETION(cc_monitor_policy)=generic") in new stack – Executing [monitor_default@sub-ccss:5] Set("SIP/106-000000c0", "CALLCOMPLETION(cc_max_monitors)=5") in new stack – Executing [monitor_default@sub-ccss:6] Return("SIP/106-000000c0", "TRUE") in new stack – Executing [s@sub-ccss:4] GosubIf("SIP/106-000000c0", "7?agent_config,1():agent_default,1()") in new stack – Executing [agent_config@sub-ccss:1] Set("SIP/106-000000c0", "CALLCOMPLETION(cc_agent_policy)=generic") in new stack – Executing [agent_config@sub-ccss:2] Set("SIP/106-000000c0", "CALLCOMPLETION(cc_offer_timer)=30") in new stack – Executing [agent_config@sub-ccss:3] Set("SIP/106-000000c0", "CALLCOMPLETION(ccbs_available_timer)=") in new stack – Executing [agent_config@sub-ccss:4] Set("SIP/106-000000c0", "CALLCOMPLETION(ccnr_available_timer)=") in new stack – Executing [agent_config@sub-ccss:5] Set("SIP/106-000000c0", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack [2018-12-07 15:14:23] WARNING[1007][C-00000083]: ccss.c:1012 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead. – Executing [agent_config@sub-ccss:6] ExecIf("SIP/106-000000c0", "1?Set(CALLCOMPLETION(cc_recall_timer)=)") in new stack – Executing [agent_config@sub-ccss:7] ExecIf("SIP/106-000000c0", "1?Set(CALLCOMPLETION(cc_max_agents)=)") in new stack – Executing [agent_config@sub-ccss:8] ExecIf("SIP/106-000000c0", "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/106_107@from-ccss-)") in new stack – Executing [agent_config@sub-ccss:9] Set("SIP/106-000000c0", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack [2018-12-07 15:14:23] WARNING[1007][C-00000083]: ccss.c:1012 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead. – Executing [agent_config@sub-ccss:10] Return("SIP/106-000000c0", "") in new stack – Executing [s@sub-ccss:5] Set("SIP/106-000000c0", "DB(AMPUSER/106/ccss/last_number)=107") in new stack – Executing [s@sub-ccss:6] Return("SIP/106-000000c0", "") in new stack – Executing [s@macro-user-callerid:16] ExecIf("SIP/106-000000c0", "0?Set(CHANNEL(language)=)") in new stack – Executing [s@macro-user-callerid:17] NoOp("SIP/106-000000c0", "Macro Depth is 2") in new stack – Executing [s@macro-user-callerid:18] GotoIf("SIP/106-000000c0", "1?report2:macroerror") in new stack – Goto (macro-user-callerid,s,19) – Executing [s@macro-user-callerid:19] GotoIf("SIP/106-000000c0", "0?continue") in new stack – Executing [s@macro-user-callerid:20] ExecIf("SIP/106-000000c0", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack – Executing [s@macro-user-callerid:21] Set("SIP/106-000000c0", "__TTL=64") in new stack – Executing [s@macro-user-callerid:22] GotoIf("SIP/106-000000c0", "1?continue") in new stack – Goto (macro-user-callerid,s,38) – Executing [s@macro-user-callerid:38] Set("SIP/106-000000c0", "CALLERID(number)=106") in new stack – Executing [s@macro-user-callerid:39] Set("SIP/106-000000c0", "CALLERID(name)=Marianela Lopez") in new stack – Executing [s@macro-user-callerid:40] GotoIf("SIP/106-000000c0", "0?cnum") in new stack – Executing [s@macro-user-callerid:41] Set("SIP/106-000000c0", "CDR(cnam)=Marianela Lopez") in new stack – Executing [s@macro-user-callerid:42] Set("SIP/106-000000c0", "CDR(cnum)=106") in new stack – Executing [s@macro-user-callerid:43] Set("SIP/106-000000c0", "CHANNEL(language)=es") in new stack – Executing [s@macro-exten-vm:2] Set("SIP/106-000000c0", "RingGroupMethod=none") in new stack – Executing [s@macro-exten-vm:3] Set("SIP/106-000000c0", "__EXTTOCALL=107") in new stack – Executing [s@macro-exten-vm:4] Set("SIP/106-000000c0", "__PICKUPMARK=107") in new stack – Executing [s@macro-exten-vm:5] Set("SIP/106-000000c0", "RT=") in new stack – Executing [s@macro-exten-vm:6] Gosub("SIP/106-000000c0", "sub-record-check,s,1(exten,107,dontcare)") in new stack – Executing [s@sub-record-check:1] GotoIf("SIP/106-000000c0", "0?initialized") in new stack – Executing [s@sub-record-check:2] Set("SIP/106-000000c0", "__REC_STATUS=INITIALIZED") in new stack – Executing [s@sub-record-check:3] Set("SIP/106-000000c0", "NOW=1544206463") in new stack – Executing [s@sub-record-check:4] Set("SIP/106-000000c0", "__DAY=07") in new stack – Executing [s@sub-record-check:5] Set("SIP/106-000000c0", "__MONTH=12") in new stack – Executing [s@sub-record-check:6] Set("SIP/106-000000c0", "__YEAR=2018") in new stack – Executing [s@sub-record-check:7] Set("SIP/106-000000c0", "__TIMESTR=20181207-151423") in new stack – Executing [s@sub-record-check:8] Set("SIP/106-000000c0", "__FROMEXTEN=106") in new stack – Executing [s@sub-record-check:9] Set("SIP/106-000000c0", "__MON_FMT=wav") in new stack – Executing [s@sub-record-check:10] NoOp("SIP/106-000000c0", "Recordings initialized") in new stack – Executing [s@sub-record-check:11] ExecIf("SIP/106-000000c0", "0?Set(ARG3=dontcare)") in new stack – Executing [s@sub-record-check:12] Set("SIP/106-000000c0", "REC_POLICY_MODE_SAVE=") in new stack – Executing [s@sub-record-check:13] ExecIf("SIP/106-000000c0", "0?Set(REC_STATUS=NO)") in new stack – Executing [s@sub-record-check:14] GotoIf("SIP/106-000000c0", "5?checkaction") in new stack – Goto (sub-record-check,s,17) – Executing [s@sub-record-check:17] GotoIf("SIP/106-000000c0", "1?sub-record-check,exten,1") in new stack – Goto (sub-record-check,exten,1) – Executing [exten@sub-record-check:1] NoOp("SIP/106-000000c0", "Exten Recording Check between 106 and 107") in new stack – Executing [exten@sub-record-check:2] Set("SIP/106-000000c0", "CALLTYPE=internal") in new stack – Executing [exten@sub-record-check:3] ExecIf("SIP/106-000000c0", "0?Set(CALLTYPE=)") in new stack – Executing [exten@sub-record-check:4] Set("SIP/106-000000c0", "CALLEE=dontcare") in new stack – Executing [exten@sub-record-check:5] ExecIf("SIP/106-000000c0", "0?Set(CALLEE=dontcare)") in new stack – Executing [exten@sub-record-check:6] GotoIf("SIP/106-000000c0", "0?callee") in new stack – Executing [exten@sub-record-check:7] GotoIf("SIP/106-000000c0", "1?caller") in new stack – Goto (sub-record-check,exten,13) – Executing [exten@sub-record-check:13] Set("SIP/106-000000c0", "RECMODE=dontcare") in new stack – Executing [exten@sub-record-check:14] ExecIf("SIP/106-000000c0", "0?Set(RECMODE=dontcare)") in new stack – Executing [exten@sub-record-check:15] ExecIf("SIP/106-000000c0", "1?Set(RECMODE=dontcare)") in new stack – Executing [exten@sub-record-check:16] Gosub("SIP/106-000000c0", "recordcheck,1(dontcare,internal,107)") in new stack – Executing [recordcheck@sub-record-check:1] NoOp("SIP/106-000000c0", "Starting recording check against dontcare") in new stack – Executing [recordcheck@sub-record-check:2] Goto("SIP/106-000000c0", "dontcare") in new stack – Goto (sub-record-check,recordcheck,3) – Executing [recordcheck@sub-record-check:3] Return("SIP/106-000000c0", "") in new stack – Executing [exten@sub-record-check:17] Return("SIP/106-000000c0", "") in new stack – Executing [s@macro-exten-vm:7] GotoIf("SIP/106-000000c0", "1?macrodial") in new stack – Goto (macro-exten-vm,s,13) – Executing [s@macro-exten-vm:13] GosubIf("SIP/106-000000c0", "0?clrheader,1()") in new stack – Executing [s@macro-exten-vm:14] Macro("SIP/106-000000c0", "dial-one,,Ttr,107") in new stack – Executing [s@macro-dial-one:1] Set("SIP/106-000000c0", "DEXTEN=107") in new stack – Executing [s@macro-dial-one:2] ExecIf("SIP/106-000000c0", "0?Set(__EXTTOCALL=107)") in new stack – Executing [s@macro-dial-one:3] Set("SIP/106-000000c0", "DIALSTATUS_CW=") in new stack – Executing [s@macro-dial-one:4] GosubIf("SIP/106-000000c0", "0?screen,1()") in new stack – Executing [s@macro-dial-one:5] GosubIf("SIP/106-000000c0", "0?cf,1()") in new stack – Executing [s@macro-dial-one:6] GotoIf("SIP/106-000000c0", "1?skip1") in new stack – Goto (macro-dial-one,s,9) – Executing [s@macro-dial-one:9] GotoIf("SIP/106-000000c0", "0?nodial") in new stack – Executing [s@macro-dial-one:10] GotoIf("SIP/106-000000c0", "0?continue") in new stack – Executing [s@macro-dial-one:11] Set("SIP/106-000000c0", "EXTHASCW=ENABLED") in new stack – Executing [s@macro-dial-one:12] GotoIf("SIP/106-000000c0", "0?next1:cwinusebusy") in new stack – Goto (macro-dial-one,s,24) – Executing [s@macro-dial-one:24] GotoIf("SIP/106-000000c0", "0?next3:continue") in new stack – Goto (macro-dial-one,s,26) – Executing [s@macro-dial-one:26] GotoIf("SIP/106-000000c0", "0?nodial") in new stack – Executing [s@macro-dial-one:27] GosubIf("SIP/106-000000c0", "1?dstring,1():dlocal,1()") in new stack – Executing [dstring@macro-dial-one:1] Set("SIP/106-000000c0", "DSTRING=") in new stack – Executing [dstring@macro-dial-one:2] Set("SIP/106-000000c0", "DEVICES=107") in new stack – Executing [dstring@macro-dial-one:3] ExecIf("SIP/106-000000c0", "0?Return()") in new stack – Executing [dstring@macro-dial-one:4] ExecIf("SIP/106-000000c0", "0?Set(DEVICES=07)") in new stack – Executing [dstring@macro-dial-one:5] Set("SIP/106-000000c0", "LOOPCNT=1") in new stack – Executing [dstring@macro-dial-one:6] Set("SIP/106-000000c0", "ITER=1") in new stack – Executing [dstring@macro-dial-one:7] Set("SIP/106-000000c0", "THISDIAL=SIP/107") in new stack – Executing [dstring@macro-dial-one:8] GosubIf("SIP/106-000000c0", "1?zap2dahdi,1()") in new stack – Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/106-000000c0", "0?Return()") in new stack – Executing [zap2dahdi@macro-dial-one:2] Set("SIP/106-000000c0", "NEWDIAL=") in new stack – Executing [zap2dahdi@macro-dial-one:3] Set("SIP/106-000000c0", "LOOPCNT2=1") in new stack – Executing [zap2dahdi@macro-dial-one:4] Set("SIP/106-000000c0", "ITER2=1") in new stack – Executing [zap2dahdi@macro-dial-one:5] Set("SIP/106-000000c0", "THISPART2=SIP/107") in new stack – Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/106-000000c0", "0?Set(THISPART2=DAHDI/107)") in new stack – Executing [zap2dahdi@macro-dial-one:7] Set("SIP/106-000000c0", "NEWDIAL=SIP/107&") in new stack – Executing [zap2dahdi@macro-dial-one:8] Set("SIP/106-000000c0", "ITER2=2") in new stack – Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/106-000000c0", "0?begin2") in new stack – Executing [zap2dahdi@macro-dial-one:10] Set("SIP/106-000000c0", "THISDIAL=SIP/107") in new stack – Executing [zap2dahdi@macro-dial-one:11] Return("SIP/106-000000c0", "") in new stack – Executing [dstring@macro-dial-one:9] GotoIf("SIP/106-000000c0", "1?docheck") in new stack – Goto (macro-dial-one,dstring,14) – Executing [dstring@macro-dial-one:14] GotoIf("SIP/106-000000c0", "0?skipset") in new stack – Executing [dstring@macro-dial-one:15] Set("SIP/106-000000c0", "DSTRING=SIP/107&") in new stack – Executing [dstring@macro-dial-one:16] Set("SIP/106-000000c0", "ITER=2") in new stack – Executing [dstring@macro-dial-one:17] GotoIf("SIP/106-000000c0", "0?begin") in new stack – Executing [dstring@macro-dial-one:18] ExecIf("SIP/106-000000c0", "0?Return()") in new stack – Executing [dstring@macro-dial-one:19] Set("SIP/106-000000c0", "DSTRING=SIP/107") in new stack – Executing [dstring@macro-dial-one:20] Return("SIP/106-000000c0", "") in new stack – Executing [s@macro-dial-one:28] GotoIf("SIP/106-000000c0", "0?nodial") in new stack – Executing [s@macro-dial-one:29] GotoIf("SIP/106-000000c0", "0?skiptrace") in new stack – Executing [s@macro-dial-one:30] GosubIf("SIP/106-000000c0", "1?ctset,1():ctclear,1()") in new stack – Executing [ctset@macro-dial-one:1] Set("SIP/106-000000c0", "DB(CALLTRACE/107)=106") in new stack – Executing [ctset@macro-dial-one:2] Return("SIP/106-000000c0", "") in new stack – Executing [s@macro-dial-one:31] Set("SIP/106-000000c0", "D_OPTIONS=Ttr") in new stack – Executing [s@macro-dial-one:32] GosubIf("SIP/106-000000c0", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack – Executing [s@macro-dial-one:33] NoOp("SIP/106-000000c0", "Blind Transfer: , Attended Transfer: , User: 106, Alert Info: ") in new stack – Executing [s@macro-dial-one:34] ExecIf("SIP/106-000000c0", "1?Set(ALERT_INFO=)") in new stack – Executing [s@macro-dial-one:35] ExecIf("SIP/106-000000c0", "0?Set(ALERT_INFO=)") in new stack – Executing [s@macro-dial-one:36] ExecIf("SIP/106-000000c0", "0?Set(ALERT_INFO=)") in new stack – Executing [s@macro-dial-one:37] ExecIf("SIP/106-000000c0", "0?Set(ALERT_INFO=Normal;volume=)") in new stack – Executing [s@macro-dial-one:38] ExecIf("SIP/106-000000c0", "0?Set(ALERT_INFO=Normal;volume=)") in new stack – Executing [s@macro-dial-one:39] GosubIf("SIP/106-000000c0", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack – Executing [s@macro-dial-one:40] ExecIf("SIP/106-000000c0", "0?Set(CHANNEL(musicclass)=)") in new stack – Executing [s@macro-dial-one:41] GosubIf("SIP/106-000000c0", "0?qwait,1()") in new stack – Executing [s@macro-dial-one:42] Set("SIP/106-000000c0", "__CWIGNORE=") in new stack – Executing [s@macro-dial-one:43] Set("SIP/106-000000c0", "__KEEPCID=TRUE") in new stack – Executing [s@macro-dial-one:44] GotoIf("SIP/106-000000c0", "0?usegoto,1") in new stack – Executing [s@macro-dial-one:45] GotoIf("SIP/106-000000c0", "0?godial") in new stack – Executing [s@macro-dial-one:46] Gosub("SIP/106-000000c0", "sub-presencestate-display,s,1(107)") in new stack – Executing [s@sub-presencestate-display:1] Goto("SIP/106-000000c0", "state-not_set,1") in new stack – Goto (sub-presencestate-display,state-not_set,1) – Executing [state-not_set@sub-presencestate-display:1] Set("SIP/106-000000c0", "PRESENCESTATE_DISPLAY=") in new stack – Executing [state-not_set@sub-presencestate-display:2] Return("SIP/106-000000c0", "") in new stack – Executing [s@macro-dial-one:47] Set("SIP/106-000000c0", "CONNECTEDLINE(name,i)=Rodrigo Martin") in new stack – Executing [s@macro-dial-one:48] Set("SIP/106-000000c0", "CONNECTEDLINE(num)=107") in new stack – Executing [s@macro-dial-one:49] Set("SIP/106-000000c0", "D_OPTIONS=TtrI") in new stack – Executing [s@macro-dial-one:50] Macro("SIP/106-000000c0", "dialout-one-predial-hook,") in new stack – Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/106-000000c0", "") in new stack – Executing [s@macro-dial-one:51] ExecIf("SIP/106-000000c0", "0?Set(D_OPTIONS=trII)") in new stack – Executing [s@macro-dial-one:52] NoOp("SIP/106-000000c0", "") in new stack – Executing [s@macro-dial-one:53] ExecIf("SIP/106-000000c0", "0?Set(D_OPTIONS=TtrIg)") in new stack – Executing [s@macro-dial-one:54] Dial("SIP/106-000000c0", "SIP/107,,TtrIb(func-apply-sipheaders^s^1)") in new stack == Using SIP RTP TOS bits 184 == Using SIP RTP CoS mark 5 – SIP/107-000000c1 Internal Gosub(func-apply-sipheaders,s,1) start – Executing [s@func-apply-sipheaders:1] NoOp("SIP/107-000000c1", "Applying SIP Headers to channel") in new stack – Executing [s@func-apply-sipheaders:2] Set("SIP/107-000000c1", "SIPHEADERKEYS=") in new stack – Executing [s@func-apply-sipheaders:3] ExecIf("SIP/107-000000c1", "0?Set(Rheader=1)") in new stack – Executing [s@func-apply-sipheaders:4] While("SIP/107-000000c1", "0") in new stack – Jumping to priority 7 – Executing [s@func-apply-sipheaders:8] ExecIf("SIP/107-000000c1", "0?SIPRemoveHeader(Alert-Info") in new stack – Executing [s@func-apply-sipheaders:9] Return("SIP/107-000000c1", "") in new stack == Spawn extension (from-internal, 107, 1) exited non-zero on 'SIP/107-000000c1' – SIP/107-000000c1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL= set_destination: Parsing for address/port to send to set_destination: set destination to 192.168.10.106:5060 Reliably Transmitting (no NAT) to 192.168.10.106:5060: NOTIFY sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK1f94d422;rport Max-Forwards: 70 From: ;tag=as76b7215d To: ;tag=1818557175 Contact: Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 268 NOTIFY User-Agent: FPBX-13.0.195.19(13.23.1) Subscription-State: active Event: dialog Content-Type: application/dialog-info+xml Content-Length: 222 early — == Extension Changed 107[ext-local] new state Ringing for Notify User 106 == Extension Changed 107[ext-local] new state Ringing for Notify User 105 == Extension Changed 107[ext-local] new state Ringing for Notify User 103 – Called SIP/107 == Extension Changed 107[ext-local] new state Ringing for Notify User 100 <--- Transmitting (no NAT) to 192.168.10.106:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1588148074;received=192.168.10.106;rport=5060 From: ;tag=1872648879 To: ;tag=as1c2f4725 Call-ID: 543030694-5060-342@BJC.BGI.BA.BAG CSeq: 1141 INVITE Server: FPBX-13.0.195.19(13.23.1) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> == Extension Changed 107[ext-local] new state Ringing for Notify User 104 – Connected line update to SIP/106-000000c0 prevented. <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK1f94d422;rport=5060 From: ;tag=as76b7215d To: ;tag=1818557175 Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 268 NOTIFY Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — set_destination: Parsing for address/port to send to set_destination: set destination to 192.168.10.106:5060 Reliably Transmitting (no NAT) to 192.168.10.106:5060: NOTIFY sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK75404efa;rport Max-Forwards: 70 From: ;tag=as76b7215d To: ;tag=1818557175 Contact: Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 269 NOTIFY User-Agent: FPBX-13.0.195.19(13.23.1) Subscription-State: active Event: dialog Content-Type: application/dialog-info+xml Content-Length: 222 early — == Extension Changed 107[ext-local] new state Ringing for Notify User 106 == Extension Changed 107[ext-local] new state Ringing for Notify User 105 == Extension Changed 107[ext-local] new state Ringing for Notify User 103 – SIP/107-000000c1 is ringing <--- Transmitting (no NAT) to 192.168.10.106:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1588148074;received=192.168.10.106;rport=5060 From: ;tag=1872648879 To: ;tag=as1c2f4725 Call-ID: 543030694-5060-342@BJC.BGI.BA.BAG CSeq: 1141 INVITE Server: FPBX-13.0.195.19(13.23.1) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> == Extension Changed 107[ext-local] new state Ringing for Notify User 100 == Extension Changed 107[ext-local] new state Ringing for Notify User 104 Retransmitting #1 (no NAT) to 192.168.10.106:5060: NOTIFY sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK75404efa;rport Max-Forwards: 70 From: ;tag=as76b7215d To: ;tag=1818557175 Contact: Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 269 NOTIFY User-Agent: FPBX-13.0.195.19(13.23.1) Subscription-State: active Event: dialog Content-Type: application/dialog-info+xml Content-Length: 222 early — <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK75404efa;rport=5060 From: ;tag=as76b7215d To: ;tag=1818557175 Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 269 NOTIFY Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK75404efa;rport=5060 From: ;tag=as76b7215d To: ;tag=1818557175 Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 269 NOTIFY Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — > 0x99dd4f8 – Strict RTP learning after remote address set to: 192.168.10.107:5028 set_destination: Parsing for address/port to send to set_destination: set destination to 192.168.10.106:5060 – Connected line update to SIP/106-000000c0 prevented. Reliably Transmitting (no NAT) to 192.168.10.106:5060: NOTIFY sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK2da5702e;rport Max-Forwards: 70 From: ;tag=as76b7215d To: ;tag=1818557175 Contact: Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 270 NOTIFY User-Agent: FPBX-13.0.195.19(13.23.1) Subscription-State: active Event: dialog Content-Type: application/dialog-info+xml Content-Length: 204 confirmed — == Extension Changed 107[ext-local] new state InUse for Notify User 106 – SIP/107-000000c1 answered SIP/106-000000c0 == Extension Changed 107[ext-local] new state InUse for Notify User 105 Audio is at 10122 Adding codec g729 to SDP Adding codec ulaw to SDP Adding codec alaw to SDP Adding codec g722 to SDP Adding codec ilbc to SDP Adding non-codec 0x1 (telephone-event) to SDP <--- Reliably Transmitting (no NAT) to 192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1588148074;received=192.168.10.106;rport=5060 From: ;tag=1872648879 To: ;tag=as1c2f4725 Call-ID: 543030694-5060-342@BJC.BGI.BA.BAG CSeq: 1141 INVITE Server: FPBX-13.0.195.19(13.23.1) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Type: application/sdp Require: timer Content-Length: 373 v=0 o=root 2109965229 2109965229 IN IP4 192.168.10.4 s=Asterisk PBX 13.23.1 c=IN IP4 192.168.10.4 t=0 0 m=audio 10122 RTP/AVP 18 0 8 9 97 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:9 G722/8000 a=rtpmap:97 iLBC/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv <------------> == Extension Changed 107[ext-local] new state InUse for Notify User 103 == Extension Changed 107[ext-local] new state InUse for Notify User 100 == Extension Changed 107[ext-local] new state InUse for Notify User 104 – Channel SIP/107-000000c1 joined 'simple_bridge' basic-bridge – Channel SIP/106-000000c0 joined 'simple_bridge' basic-bridge <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK2da5702e;rport=5060 From: ;tag=as76b7215d To: ;tag=1818557175 Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 270 NOTIFY Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — > 0x99dd4f8 – Strict RTP switching to RTP target address 192.168.10.107:5028 as source <--- SIP read from UDP:192.168.10.106:5060 ---> ACK sip:107@192.168.10.4:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK2141261376;rport From: ;tag=1872648879 To: ;tag=as1c2f4725 Call-ID: 543030694-5060-342@BJC.BGI.BA.BAG CSeq: 1141 ACK Contact: X-Grandstream-PBX: true Max-Forwards: 70 Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (13 headers 0 lines) — > 0xb601b280 – Strict RTP switching to RTP target address 192.168.10.106:5004 as source > 0xb601b280 – Strict RTP learning complete - Locking on source address 192.168.10.106:5004 > 0x99dd4f8 – Strict RTP learning complete - Locking on source address 192.168.10.107:5028 – Channel SIP/107-000000c1 left 'simple_bridge' basic-bridge – Channel SIP/106-000000c0 left 'simple_bridge' basic-bridge == Spawn extension (macro-dial-one, s, 54) exited non-zero on 'SIP/106-000000c0' in macro 'dial-one' == Spawn extension (macro-exten-vm, s, 14) exited non-zero on 'SIP/106-000000c0' in macro 'exten-vm' == Spawn extension (ext-local, 107, 2) exited non-zero on 'SIP/106-000000c0' – Executing [h@ext-local:1] Macro("SIP/106-000000c0", "hangupcall,") in new stack – Executing [s@macro-hangupcall:1] GotoIf("SIP/106-000000c0", "1?theend") in new stack – Goto (macro-hangupcall,s,3) – Executing [s@macro-hangupcall:3] ExecIf("SIP/106-000000c0", "0?Set(CDR(recordingfile)=)") in new stack set_destination: Parsing for address/port to send to set_destination: set destination to 192.168.10.106:5060 Reliably Transmitting (no NAT) to 192.168.10.106:5060: NOTIFY sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK3e6352fa;rport Max-Forwards: 70 From: ;tag=as76b7215d To: ;tag=1818557175 Contact: Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 271 NOTIFY User-Agent: FPBX-13.0.195.19(13.23.1) Subscription-State: active Event: dialog Content-Type: application/dialog-info+xml Content-Length: 205 terminated — == Extension Changed 107[ext-local] new state Idle for Notify User 106 == Extension Changed 107[ext-local] new state Idle for Notify User 105 == Extension Changed 107[ext-local] new state Idle for Notify User 103 == Extension Changed 107[ext-local] new state Idle for Notify User 100 == Extension Changed 107[ext-local] new state Idle for Notify User 104 – Executing [s@macro-hangupcall:4] Hangup("SIP/106-000000c0", "") in new stack == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/106-000000c0' in macro 'hangupcall' == Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/106-000000c0' Scheduling destruction of SIP dialog '543030694-5060-342@BJC.BGI.BA.BAG' in 6400 ms (Method: ACK) == Extension Changed 106[ext-local] new state Idle for Notify User 103 == Extension Changed 106[ext-local] new state Idle for Notify User 100 == Extension Changed 106[ext-local] new state Idle for Notify User 104 set_destination: Parsing for address/port to send to == Extension Changed 106[ext-local] new state Idle for Notify User 105 set_destination: set destination to 192.168.10.106:5060 Reliably Transmitting (no NAT) to 192.168.10.106:5060: BYE sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK1e2485a8;rport Max-Forwards: 70 From: ;tag=as1c2f4725 To: ;tag=1872648879 Call-ID: 543030694-5060-342@BJC.BGI.BA.BAG CSeq: 102 BYE User-Agent: FPBX-13.0.195.19(13.23.1) Proxy-Authorization: Digest username="106", realm="asterisk", algorithm=MD5, uri="sip:192.168.10.4", nonce="60045ee1", response="4dd982eb2ad0570a0ff14d67beaaf811" X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 Content-Length: 0 — <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK3e6352fa;rport=5060 From: ;tag=as76b7215d To: ;tag=1818557175 Call-ID: 156301877-5060-322@BJC.BGI.BA.BAG CSeq: 271 NOTIFY Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — Retransmitting #1 (no NAT) to 192.168.10.106:5060: BYE sip:106@192.168.10.106:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK1e2485a8;rport Max-Forwards: 70 From: ;tag=as1c2f4725 To: ;tag=1872648879 Call-ID: 543030694-5060-342@BJC.BGI.BA.BAG CSeq: 102 BYE User-Agent: FPBX-13.0.195.19(13.23.1) Proxy-Authorization: Digest username="106", realm="asterisk", algorithm=MD5, uri="sip:192.168.10.4", nonce="60045ee1", response="4dd982eb2ad0570a0ff14d67beaaf811" X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 Content-Length: 0 — <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK1e2485a8;rport=5060 From: ;tag=as1c2f4725 To: ;tag=1872648879 Call-ID: 543030694-5060-342@BJC.BGI.BA.BAG CSeq: 102 BYE Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) — SIP Response message for INCOMING dialog BYE arrived Really destroying SIP dialog '543030694-5060-342@BJC.BGI.BA.BAG' Method: ACK <--- SIP read from UDP:192.168.10.106:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK1e2485a8;rport=5060 From: ;tag=as1c2f4725 To: ;tag=1872648879 Call-ID: 543030694-5060-342@BJC.BGI.BA.BAG CSeq: 102 BYE Contact: Supported: replaces, path, timer User-Agent: Grandstream GXP2110 1.0.8.6 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0 <-------------> — (11 headers 0 lines) —