Incoming & outgoing calls ring well but drop on accept with AT^DDSETEX on trunk

General Help
,
1

Hi.

I am posting this after having tried almost everything I could to debug the problem for last 4-5 days. Hope to find help here.

I have an FreePBX/Asterisk13 setup with SIP extensions 200-299 and a Chan_Dongle trunk (CID 93885940). External number being called or originating incoming call is 064013357 (0 prefix for outgoing). Internal calls over SIP work seamlessly. However, while both outgoing calls and incoming calls on Chan_Dongle trunk ring well, they drop (incoming) or give a circuit busy (outgoing) message as soon as they are accepted. I have reviewed my dialplans to best of my ability, reviewed the trunk and outgoing/incoming route settings, also tried issuing ATD directly from asterisk CLI but the behaviour remains same.

Guess since it is my first post, I am not allowed to attach the log file nor share a link to the file, so much to my dislike, I’ll paste the log entries below.

In an internal SIP extension to SIP extension call, the simple_bridge is joined followed by an iax2 channel establishing the connect followed by successful hangup. But the same call flow does not seem to be working with chan_dongle. I believe the channel establishment part is failing. Some of the warning messages may be indicating an error which I am unable to decipher. I have validated the dongle (E1750) - it supports voice calling, does not have any PIN or password to it. It works seamlessly on a windows box.

Currently I am just not sure if it is something minor in dialplan or config that is incorrect or if I have hit a bug. Since what I am trying is something basic, I suspect I have got it wrong somewhere. Appreciate any help or pointers to investigate. Thanks for your help.

/Rohit

Outgoing call from sip extension 200 via trunk chan_dongle

[2017-02-07 17:31:27] VERBOSE[24846][C-00000005] netsock2.c: Using SIP VIDEO TOS bits 136
[2017-02-07 17:31:27] VERBOSE[24846][C-00000005] netsock2.c: Using SIP VIDEO CoS mark 6
[2017-02-07 17:31:27] VERBOSE[24846][C-00000005] netsock2.c: Using SIP RTP TOS bits 184
[2017-02-07 17:31:27] VERBOSE[24846][C-00000005] netsock2.c: Using SIP RTP CoS mark 5
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [064013357@from-internal:1] Macro(“SIP/200-00000008”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/200-00000008”, “TOUCH_MONITOR=1486459887.10”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/200-00000008”, “AMPUSER=200”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/200-00000008”, “0?report”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/200-00000008”, “1?Set(REALCALLERIDNUM=200)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/200-00000008”, “AMPUSER=200”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/200-00000008”, “0?limit”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/200-00000008”, “AMPUSERCIDNAME=Rohit Gupta 200”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“SIP/200-00000008”, “0?report”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:9] Set(“SIP/200-00000008”, “AMPUSERCID=200”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/200-00000008”, “__DIAL_OPTIONS=Ttr”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:11] Set(“SIP/200-00000008”, “CALLERID(all)=“Rohit Gupta 200” <200>”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:12] GotoIf(“SIP/200-00000008”, “0?limit”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“SIP/200-00000008”, “1?Set(GROUP(concurrency_limit)=200)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“SIP/200-00000008”, “0?Set(CHANNEL(language)=)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:15] GotoIf(“SIP/200-00000008”, “1?continue”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:29] Set(“SIP/200-00000008”, “CALLERID(number)=200”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:30] Set(“SIP/200-00000008”, “CALLERID(name)=Rohit Gupta 200”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:31] GotoIf(“SIP/200-00000008”, “0?cnum”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:32] Set(“SIP/200-00000008”, “CDR(cnam)=Rohit Gupta 200”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:33] Set(“SIP/200-00000008”, “CDR(cnum)=200”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-user-callerid:34] Set(“SIP/200-00000008”, “CHANNEL(language)=en”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [064013357@from-internal:2] Gosub(“SIP/200-00000008”, “sub-record-check,s,1(out,064013357,dontcare)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/200-00000008”, “0?initialized”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/200-00000008”, “__REC_STATUS=INITIALIZED”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/200-00000008”, “NOW=1486459887”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/200-00000008”, “__DAY=07”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/200-00000008”, “__MONTH=02”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/200-00000008”, “__YEAR=2017”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/200-00000008”, “__TIMESTR=20170207-173127”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/200-00000008”, “__FROMEXTEN=200”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/200-00000008”, “__MON_FMT=wav”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/200-00000008”, “Recordings initialized”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/200-00000008”, “0?Set(ARG3=dontcare)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/200-00000008”, “REC_POLICY_MODE_SAVE=”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/200-00000008”, “0?Set(REC_STATUS=NO)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/200-00000008”, “3?checkaction”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx_builtins.c: Goto (sub-record-check,s,17)
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/200-00000008”, “1?sub-record-check,out,1”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx_builtins.c: Goto (sub-record-check,out,1)
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [out@sub-record-check:1] NoOp(“SIP/200-00000008”, “Outbound Recording Check from 200 to 064013357”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [out@sub-record-check:2] Set(“SIP/200-00000008”, “RECMODE=dontcare”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [out@sub-record-check:3] ExecIf(“SIP/200-00000008”, “1?Goto(routewins)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx_builtins.c: Goto (sub-record-check,out,7)
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [out@sub-record-check:7] Gosub(“SIP/200-00000008”, “recordcheck,1(dontcare,out,064013357)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/200-00000008”, “Starting recording check against dontcare”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/200-00000008”, “dontcare”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/200-00000008”, “”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [out@sub-record-check:8] Return(“SIP/200-00000008”, “”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [064013357@from-internal:3] ExecIf(“SIP/200-00000008”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [064013357@from-internal:4] Set(“SIP/200-00000008”, “MOHCLASS=default”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [064013357@from-internal:5] ExecIf(“SIP/200-00000008”, “1?Set(TRUNKCIDOVERRIDE=<93885940>)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [064013357@from-internal:6] Set(“SIP/200-00000008”, “_NODEST=”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [064013357@from-internal:7] Macro(“SIP/200-00000008”, “dialout-trunk,1,64013357,on”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:1] Set(“SIP/200-00000008”, “DIAL_TRUNK=1”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/200-00000008”, “0?sub-pincheck,s,1()”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/200-00000008”, “0?disabletrunk,1”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:4] Set(“SIP/200-00000008”, “DIAL_NUMBER=64013357”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:5] Set(“SIP/200-00000008”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:6] Set(“SIP/200-00000008”, “OUTBOUND_GROUP=OUT_1”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/200-00000008”, “0?nomax”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:8] GotoIf(“SIP/200-00000008”, “0?chanfull”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/200-00000008”, “0?skipoutcid”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:10] Set(“SIP/200-00000008”, “DIAL_TRUNK_OPTIONS=Tt”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:11] Macro(“SIP/200-00000008”, “outbound-callerid,1”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/200-00000008”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/200-00000008”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:3] ExecIf(“SIP/200-00000008”, “0?Set(REALCALLERIDNUM=200)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:4] GotoIf(“SIP/200-00000008”, “1?normcid”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx_builtins.c: Goto (macro-outbound-callerid,s,7)
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:7] Set(“SIP/200-00000008”, “USEROUTCID=”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:8] Set(“SIP/200-00000008”, “EMERGENCYCID=”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:9] Set(“SIP/200-00000008”, “TRUNKOUTCID=<93885940>”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:10] GotoIf(“SIP/200-00000008”, “1?trunkcid”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx_builtins.c: Goto (macro-outbound-callerid,s,15)
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/200-00000008”, “1?Set(CALLERID(all)=<93885940>)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:16] ExecIf(“SIP/200-00000008”, “0?Set(CALLERID(all)=)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:17] ExecIf(“SIP/200-00000008”, “1?Set(CALLERID(all)=<93885940>)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:18] ExecIf(“SIP/200-00000008”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:19] ExecIf(“SIP/200-00000008”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:20] Set(“SIP/200-00000008”, “CALLERID(name)=”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:21] Set(“SIP/200-00000008”, “CDR(outbound_cnum)=93885940”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:22] Set(“SIP/200-00000008”, “CDR(outbound_cnam)=”) in new stack
[2017-02-07 17:31:27] WARNING[24749] func_cdr.c: CDR requires a value (CDR(variable)=value)
)[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/200-00000008”, “1?sub-flp-1,s,1()”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-flp-1:1] ExecIf(“SIP/200-00000008”, “0?Set(TARGET_FLP_1=4013357)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-flp-1:2] GotoIf(“SIP/200-00000008”, “0?match”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-flp-1:3] ExecIf(“SIP/200-00000008”, “0?Set(TARGET_FLP_1=4013357)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-flp-1:4] GotoIf(“SIP/200-00000008”, “0?match”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-flp-1:5] ExecIf(“SIP/200-00000008”, “0?Set(TARGET_FLP_1=13357)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-flp-1:6] GotoIf(“SIP/200-00000008”, “0?match”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@sub-flp-1:7] Return(“SIP/200-00000008”, “”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:13] Set(“SIP/200-00000008”, “OUTNUM=64013357”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:14] Set(“SIP/200-00000008”, “custom=AMP”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/200-00000008”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/200-00000008”, “0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:17] Macro(“SIP/200-00000008”, “dialout-trunk-predial-hook,”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/200-00000008”, “”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/200-00000008”, “0?bypass,1”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/200-00000008”, “1?Set(CONNECTEDLINE(num,i)=64013357)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/200-00000008”, “1?Set(CONNECTEDLINE(name,i)=CID:93885940)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf(“SIP/200-00000008”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)93885940)”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:22] GotoIf(“SIP/200-00000008”, “1?customtrunk”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx_builtins.c: Goto (macro-dialout-trunk,s,26)
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:26] Set(“SIP/200-00000008”, “pre_num=AMP:dongle/dongle0/”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:27] Set(“SIP/200-00000008”, “the_num=OUTNUM”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:28] Set(“SIP/200-00000008”, “post_num=”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:29] GotoIf(“SIP/200-00000008”, “1?outnum:skipoutnum”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx_builtins.c: Goto (macro-dialout-trunk,s,30)
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:30] Set(“SIP/200-00000008”, “the_num=64013357”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:31] Dial(“SIP/200-00000008”, “dongle/dongle0/64013357,300,Tt”) in new stack
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] app_dial.c: Called dongle/dongle0/64013357
[2017-02-07 17:31:27] VERBOSE[32165][C-00000005] app_dial.c: Dongle/dongle0-0100000002 is making progress passing it to SIP/200-00000008
[2017-02-07 17:31:27] ERROR[30759] at_response.c: [dongle0] AT^DDSETEX failed (This appears BEFORE call accepted but AFTER call starts ringing on external number)
[2017-02-07 17:31:44] ERROR[30759] at_response.c: [dongle0] answered incoming call with not exists call idx 4, hanging up! (This line appears AFTER call accepted but drops)
[2017-02-07 17:31:44] ERROR[30759] at_response.c: [dongle0] Command ‘AT+CLCC’ failed
[2017-02-07 17:31:44] VERBOSE[30759] chan_dongle.c: [dongle0] Dongle has disconnected
[2017-02-07 17:31:44] VERBOSE[32165][C-00000005] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
[2017-02-07 17:31:44] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:32] NoOp(“SIP/200-00000008”, “Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 0”) in new stack
[2017-02-07 17:31:44] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:33] GotoIf(“SIP/200-00000008”, “1?continue,1:s-CHANUNAVAIL,1”) in new stack
[2017-02-07 17:31:44] VERBOSE[32165][C-00000005] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1)
[2017-02-07 17:31:44] VERBOSE[32165][C-00000005] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp(“SIP/200-00000008”, “TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 0 - failing through to other trunks”) in new stack
[2017-02-07 17:31:44] VERBOSE[32165][C-00000005] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf(“SIP/200-00000008”, “1?Set(CALLERID(number)=200)”) in new stack
[2017-02-07 17:31:44] VERBOSE[32165][C-00000005] pbx.c: Executing [064013357@from-internal:8] Macro(“SIP/200-00000008”, “outisbusy,”) in new stack
[2017-02-07 17:31:44] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outisbusy:1] Progress(“SIP/200-00000008”, “”) in new stack
[2017-02-07 17:31:44] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outisbusy:2] GotoIf(“SIP/200-00000008”, “0?emergency,1”) in new stack
[2017-02-07 17:31:44] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outisbusy:3] GotoIf(“SIP/200-00000008”, “0?intracompany,1”) in new stack
[2017-02-07 17:31:44] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outisbusy:4] Playback(“SIP/200-00000008”, “all-circuits-busy-now&pls-try-call-later, noanswer”) in new stack
[2017-02-07 17:31:44] VERBOSE[32165][C-00000005] file.c: <SIP/200-00000008> Playing ‘all-circuits-busy-now.slin’ (language ‘en’)
[2017-02-07 17:31:46] VERBOSE[32165][C-00000005] file.c: <SIP/200-00000008> Playing ‘pls-try-call-later.slin’ (language ‘en’)
[2017-02-07 17:31:48] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-outisbusy:5] Congestion(“SIP/200-00000008”, “20”) in new stack
[2017-02-07 17:31:48] WARNING[32165][C-00000005] channel.c: Prodding channel ‘SIP/200-00000008’ failed
[2017-02-07 17:31:48] VERBOSE[32165][C-00000005] app_macro.c: Spawn extension (macro-outisbusy, s, 5) exited non-zero on ‘SIP/200-00000008’ in macro ‘outisbusy’
[2017-02-07 17:31:48] VERBOSE[32165][C-00000005] pbx.c: Spawn extension (from-internal, 064013357, 8) exited non-zero on ‘SIP/200-00000008’
[2017-02-07 17:31:48] VERBOSE[32165][C-00000005] pbx.c: Executing [h@from-internal:1] Macro(“SIP/200-00000008”, “hangupcall”) in new stack
[2017-02-07 17:31:48] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/200-00000008”, “1?theend”) in new stack
[2017-02-07 17:31:48] VERBOSE[32165][C-00000005] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2017-02-07 17:31:48] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/200-00000008”, “0?Set(CDR(recordingfile)=)”) in new stack
[2017-02-07 17:31:48] VERBOSE[32165][C-00000005] pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/200-00000008”, “”) in new stack
[2017-02-07 17:31:48] VERBOSE[32165][C-00000005] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/200-00000008’ in macro ‘hangupcall’
[2017-02-07 17:31:48] VERBOSE[32165][C-00000005] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/200-00000008’
[2017-02-07 17:32:07] VERBOSE[24864] chan_dongle.c: [dongle0] IMEI 000000000000001 IMSI XXXXXXXXXXXXXXX found on data_tty=/dev/ttyUSB2 audio_tty=/dev/ttyUSB1
[2017-02-07 17:32:07] VERBOSE[24864] chan_dongle.c: [dongle0] Trying to connect on /dev/ttyUSB2…
[2017-02-07 17:32:07] VERBOSE[24864] chan_dongle.c: [dongle0] Dongle has connected, initializing…
[2017-02-07 17:32:07] VERBOSE[32204] at_response.c: [dongle0] Dongle initialized and ready

Contd…

2

Contd…

Incoming call on trunk chan_dongle routed to sip extension 200

[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [93885940@from-trunk-dongle:1] Set(“Dongle/dongle0-0100000003”, “CALLERID(name)=64013357”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [93885940@from-trunk-dongle:2] Goto(“Dongle/dongle0-0100000003”, “from-trunk,93885940,1”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (from-trunk,93885940,1)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [93885940@from-trunk:1] NoOp(“Dongle/dongle0-0100000003”, “Catch-All DID Match - Found 93885940 - You probably want a DID for this.”) in new stack
[2017-02-07 17:33:21] WARNING[32241][C-00000006] chan_sip.c: This function can only be used on SIP channels.
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [93885940@from-trunk:2] Log(“Dongle/dongle0-0100000003”, "WARNING,Friendly Scanner from ") in new stack
[2017-02-07 17:33:21] WARNING[32241][C-00000006] Ext. 93885940: Friendly Scanner from
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [93885940@from-trunk:3] Set(“Dongle/dongle0-0100000003”, “__FROM_DID=93885940”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [93885940@from-trunk:4] Goto(“Dongle/dongle0-0100000003”, “ext-did,s,1”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (ext-did,s,1)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@ext-did:1] Set(“Dongle/dongle0-0100000003”, “__DIRECTION=INBOUND”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@ext-did:2] Gosub(“Dongle/dongle0-0100000003”, “app-blacklist-check,s,1()”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@app-blacklist-check:1] GotoIf(“Dongle/dongle0-0100000003”, “0?blacklisted”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@app-blacklist-check:2] Set(“Dongle/dongle0-0100000003”, “CALLED_BLACKLIST=1”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@app-blacklist-check:3] Return(“Dongle/dongle0-0100000003”, “”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@ext-did:3] ExecIf(“Dongle/dongle0-0100000003”, “0?Set(__FROM_DID=s)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@ext-did:4] Set(“Dongle/dongle0-0100000003”, “CDR(did)=93885940”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@ext-did:5] ExecIf(“Dongle/dongle0-0100000003”, “0 ?Set(CALLERID(name)=64013357)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@ext-did:6] Set(“Dongle/dongle0-0100000003”, “__MOHCLASS=”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@ext-did:7] Set(“Dongle/dongle0-0100000003”, “__REVERSAL_REJECT=FALSE”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@ext-did:8] GotoIf(“Dongle/dongle0-0100000003”, “1?post-reverse-charge”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (ext-did,s,10)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@ext-did:10] NoOp(“Dongle/dongle0-0100000003”, “”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@ext-did:11] Set(“Dongle/dongle0-0100000003”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@ext-did:12] Set(“Dongle/dongle0-0100000003”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@ext-did:13] Set(“Dongle/dongle0-0100000003”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@ext-did:14] Set(“Dongle/dongle0-0100000003”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@ext-did:15] NoOp(“Dongle/dongle0-0100000003”, “CallerID Entry Point”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@ext-did:16] Goto(“Dongle/dongle0-0100000003”, “from-did-direct,200,1”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (from-did-direct,200,1)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@from-did-direct:1] GotoIf(“Dongle/dongle0-0100000003”, “0?ext-local,200,1:followme-check,200,1”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (followme-check,200,1)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-check:1] Gosub(“Dongle/dongle0-0100000003”, “followme-sub,200,1()”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:1] Macro(“Dongle/dongle0-0100000003”, “user-callerid,”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:1] Set(“Dongle/dongle0-0100000003”, “TOUCH_MONITOR=1486460001.12”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:2] Set(“Dongle/dongle0-0100000003”, “AMPUSER=64013357”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“Dongle/dongle0-0100000003”, “0?report”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“Dongle/dongle0-0100000003”, “1?Set(REALCALLERIDNUM=64013357)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:5] Set(“Dongle/dongle0-0100000003”, “AMPUSER=”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“Dongle/dongle0-0100000003”, “0?limit”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:7] Set(“Dongle/dongle0-0100000003”, “AMPUSERCIDNAME=”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“Dongle/dongle0-0100000003”, “1?report”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (macro-user-callerid,s,15)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:15] GotoIf(“Dongle/dongle0-0100000003”, “0?continue”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:16] ExecIf(“Dongle/dongle0-0100000003”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:17] Set(“Dongle/dongle0-0100000003”, “__TTL=64”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“Dongle/dongle0-0100000003”, “1?continue”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:29] Set(“Dongle/dongle0-0100000003”, “CALLERID(number)=64013357”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:30] Set(“Dongle/dongle0-0100000003”, “CALLERID(name)=64013357”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:31] GotoIf(“Dongle/dongle0-0100000003”, “0?cnum”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:32] Set(“Dongle/dongle0-0100000003”, “CDR(cnam)=64013357”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:33] Set(“Dongle/dongle0-0100000003”, “CDR(cnum)=64013357”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-user-callerid:34] Set(“Dongle/dongle0-0100000003”, “CHANNEL(language)=en”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:2] Set(“Dongle/dongle0-0100000003”, “DIAL_OPTIONS=TtrI”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:3] Set(“Dongle/dongle0-0100000003”, “CONNECTEDLINE(num,i)=200”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:4] Gosub(“Dongle/dongle0-0100000003”, “sub-presencestate-display,s,1(200)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@sub-presencestate-display:1] Goto(“Dongle/dongle0-0100000003”, “state-not_set,1”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (sub-presencestate-display,state-not_set,1)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [state-not_set@sub-presencestate-display:1] Set(“Dongle/dongle0-0100000003”, “PRESENCESTATE_DISPLAY=”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [state-not_set@sub-presencestate-display:2] Return(“Dongle/dongle0-0100000003”, “”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:5] Set(“Dongle/dongle0-0100000003”, “CONNECTEDLINE(name)=Rohit Gupta 200”) in new stack
[2017-02-07 17:33:21] WARNING[32241][C-00000006] channel.c: [Dongle/dongle0-0100000003] Don’t know how to indicate condition 22
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:6] Set(“Dongle/dongle0-0100000003”, “FM_DIALSTATUS=NOT_INUSE”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:7] Set(“Dongle/dongle0-0100000003”, “__EXTTOCALL=200”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:8] Set(“Dongle/dongle0-0100000003”, “__PICKUPMARK=200”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:9] Macro(“Dongle/dongle0-0100000003”, “blkvm-setifempty,”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf(“Dongle/dongle0-0100000003”, “1?init”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (macro-blkvm-setifempty,s,4)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-blkvm-setifempty:4] Set(“Dongle/dongle0-0100000003”, “__BLKVM_CHANNEL=Dongle/dongle0-0100000003”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-blkvm-setifempty:5] Set(“Dongle/dongle0-0100000003”, “SHARED(BLKVM,Dongle/dongle0-0100000003)=TRUE”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-blkvm-setifempty:6] Set(“Dongle/dongle0-0100000003”, “GOSUB_RETVAL=TRUE”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-blkvm-setifempty:7] MacroExit(“Dongle/dongle0-0100000003”, “”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:10] GotoIf(“Dongle/dongle0-0100000003”, “1?skipov”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (followme-sub,200,13)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:13] Set(“Dongle/dongle0-0100000003”, “RRNODEST=”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:14] Set(“Dongle/dongle0-0100000003”, “__NODEST=200”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:15] GosubIf(“Dongle/dongle0-0100000003”, “0?sub-fmsetcid,s,1()”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:16] GotoIf(“Dongle/dongle0-0100000003”, “1?skipprepend”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (followme-sub,200,18)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:18] Set(“Dongle/dongle0-0100000003”, “RecordMethod=Group”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:19] Gosub(“Dongle/dongle0-0100000003”, “sub-record-check,s,1(exten,200,)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@sub-record-check:1] GotoIf(“Dongle/dongle0-0100000003”, “0?initialized”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@sub-record-check:2] Set(“Dongle/dongle0-0100000003”, “__REC_STATUS=INITIALIZED”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@sub-record-check:3] Set(“Dongle/dongle0-0100000003”, “NOW=1486460001”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@sub-record-check:4] Set(“Dongle/dongle0-0100000003”, “__DAY=07”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@sub-record-check:5] Set(“Dongle/dongle0-0100000003”, “__MONTH=02”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@sub-record-check:6] Set(“Dongle/dongle0-0100000003”, “__YEAR=2017”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@sub-record-check:7] Set(“Dongle/dongle0-0100000003”, “__TIMESTR=20170207-173321”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@sub-record-check:8] Set(“Dongle/dongle0-0100000003”, “__FROMEXTEN=64013357”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@sub-record-check:9] Set(“Dongle/dongle0-0100000003”, “__MON_FMT=wav”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@sub-record-check:10] NoOp(“Dongle/dongle0-0100000003”, “Recordings initialized”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@sub-record-check:11] ExecIf(“Dongle/dongle0-0100000003”, “1?Set(ARG3=dontcare)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@sub-record-check:12] Set(“Dongle/dongle0-0100000003”, “REC_POLICY_MODE_SAVE=”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@sub-record-check:13] ExecIf(“Dongle/dongle0-0100000003”, “0?Set(REC_STATUS=NO)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@sub-record-check:14] GotoIf(“Dongle/dongle0-0100000003”, “5?checkaction”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (sub-record-check,s,17)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@sub-record-check:17] GotoIf(“Dongle/dongle0-0100000003”, “1?sub-record-check,exten,1”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [exten@sub-record-check:1] NoOp(“Dongle/dongle0-0100000003”, “Exten Recording Check between 64013357 and 200”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [exten@sub-record-check:2] Set(“Dongle/dongle0-0100000003”, “CALLTYPE=external”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [exten@sub-record-check:3] ExecIf(“Dongle/dongle0-0100000003”, “0?Set(CALLTYPE=)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [exten@sub-record-check:4] Set(“Dongle/dongle0-0100000003”, “CALLEE=dontcare”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [exten@sub-record-check:5] ExecIf(“Dongle/dongle0-0100000003”, “0?Set(CALLEE=dontcare)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [exten@sub-record-check:6] GotoIf(“Dongle/dongle0-0100000003”, “1?callee”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (sub-record-check,exten,11)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [exten@sub-record-check:11] Gosub(“Dongle/dongle0-0100000003”, “recordcheck,1(dontcare,external,200)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“Dongle/dongle0-0100000003”, “Starting recording check against dontcare”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“Dongle/dongle0-0100000003”, “dontcare”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“Dongle/dongle0-0100000003”, “”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [exten@sub-record-check:12] Return(“Dongle/dongle0-0100000003”, “”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:20] GotoIf(“Dongle/dongle0-0100000003”, “1?skipdring”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (followme-sub,200,23)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:23] Set(“Dongle/dongle0-0100000003”, “STRATEGY=ringallv2-prim”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:24] Set(“Dongle/dongle0-0100000003”, “RVOL=”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:25] GotoIf(“Dongle/dongle0-0100000003”, “1?skipsimple”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (followme-sub,200,28)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:28] Set(“Dongle/dongle0-0100000003”, “RingGroupMethod=ringallv2-prim”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:29] Set(“Dongle/dongle0-0100000003”, “_FMGRP=200”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:30] GotoIf(“Dongle/dongle0-0100000003”, “1?DIALGRP”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (followme-sub,200,34)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:34] ExecIf(“Dongle/dongle0-0100000003”, “1?Set(DOPTS=TtrI):Set(DOPTS=m(Ring)TtI)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:35] GotoIf(“Dongle/dongle0-0100000003”, “0?doconfirm”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:36] GotoIf(“Dongle/dongle0-0100000003”, “1?ringallv21”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx_builtins.c: Goto (followme-sub,200,39)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [200@followme-sub:39] Macro(“Dongle/dongle0-0100000003”, “dial,27,TtrI,200”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:1] NoOp(“Dongle/dongle0-0100000003”, "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:2] ExecIf(“Dongle/dongle0-0100000003”, “0?Set(ALERT_INFO=)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:3] ExecIf(“Dongle/dongle0-0100000003”, “0?Set(ALERT_INFO=)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:4] ExecIf(“Dongle/dongle0-0100000003”, “0?Set(ALERT_INFO=)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:5] ExecIf(“Dongle/dongle0-0100000003”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:6] AGI(“Dongle/dongle0-0100000003”, “dialparties.agi”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] res_agi.c: dialparties.agi: Caller ID name is ‘64013357’ number is ‘64013357’
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] res_agi.c: dialparties.agi: CW Ignore is:
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] res_agi.c: dialparties.agi: CF Ignore is:
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] res_agi.c: dialparties.agi: Methodology of ring is ‘ringallv2-prim’
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] res_agi.c: dialparties.agi: Added extension 200 to extension map
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] res_agi.c: dialparties.agi: Extension 200 cf is disabled
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] res_agi.c: dialparties.agi: Extension 200 do not disturb is disabled
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] res_agi.c: dialparties.agi: Extension 200 has ExtensionState: 0
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] res_agi.c: dialparties.agi: dbset CALLTRACE/200 to 64013357
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] res_agi.c: dialparties.agi: Filtered ARG3: 200
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] res_agi.c: <Dongle/dongle0-0100000003>AGI Script dialparties.agi completed, returning 0
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:9] NoOp(“Dongle/dongle0-0100000003”, “Returned from dialparties with groups to dial”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:10] Set(“Dongle/dongle0-0100000003”, “LOOPCNT=1”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:11] Set(“Dongle/dongle0-0100000003”, “ITER=1”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:12] Set(“Dongle/dongle0-0100000003”, “EXTTOCALL=200”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:13] NoOp(“Dongle/dongle0-0100000003”, “Working with 200”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:14] ExecIf(“Dongle/dongle0-0100000003”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=;volume=)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:15] ExecIf(“Dongle/dongle0-0100000003”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=;volume=)”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:16] Set(“Dongle/dongle0-0100000003”, “ITER=2”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:17] GotoIf(“Dongle/dongle0-0100000003”, “0?ndloopbegin”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:18] Macro(“Dongle/dongle0-0100000003”, “dial-ringall-predial-hook,”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit(“Dongle/dongle0-0100000003”, “”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-dial:19] Dial(“Dongle/dongle0-0100000003”, “SIP/200,27,trIM(auto-blkvm)b(func-apply-sipheaders^s^1),”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] netsock2.c: Using SIP VIDEO TOS bits 136
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] netsock2.c: Using SIP VIDEO CoS mark 6
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] netsock2.c: Using SIP RTP TOS bits 184
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] netsock2.c: Using SIP RTP CoS mark 5
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] app_stack.c: SIP/200-00000009 Internal Gosub(func-apply-sipheaders,s,1) start
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“SIP/200-00000009”, “Applying SIP Headers to channel”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“SIP/200-00000009”, “SIPHEADERKEYS=”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:3] While(“SIP/200-00000009”, “0”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] app_while.c: Jumping to priority 7
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:8] Return(“SIP/200-00000009”, “”) in new stack
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] app_stack.c: Spawn extension (from-internal, 200, 1) exited non-zero on ‘SIP/200-00000009’
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] app_stack.c: SIP/200-00000009 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] app_dial.c: Called SIP/200
[2017-02-07 17:33:21] VERBOSE[32241][C-00000006] app_dial.c: Connected line update to Dongle/dongle0-0100000003 prevented.
[2017-02-07 17:33:23] VERBOSE[32241][C-00000006] app_dial.c: SIP/200-00000009 is ringing
[2017-02-07 17:33:24] VERBOSE[32241][C-00000006] app_dial.c: SIP/200-00000009 is ringing
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] app_dial.c: Connected line update to Dongle/dongle0-0100000003 prevented.
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] app_dial.c: SIP/200-00000009 answered Dongle/dongle0-0100000003
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-auto-blkvm:1] Set(“SIP/200-00000009”, “__MACRO_RESULT=”) in new stack
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-auto-blkvm:2] Set(“SIP/200-00000009”, “CFIGNORE=”) in new stack
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-auto-blkvm:3] Set(“SIP/200-00000009”, “MASTER_CHANNEL(CFIGNORE)=”) in new stack
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-auto-blkvm:4] Set(“SIP/200-00000009”, “FORWARD_CONTEXT=from-internal”) in new stack
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-auto-blkvm:5] Set(“SIP/200-00000009”, “MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal”) in new stack
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-auto-blkvm:6] Macro(“SIP/200-00000009”, “blkvm-clr,”) in new stack
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-blkvm-clr:1] Set(“SIP/200-00000009”, “SHARED(BLKVM,Dongle/dongle0-0100000003)=”) in new stack
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-blkvm-clr:2] Set(“SIP/200-00000009”, “GOSUB_RETVAL=”) in new stack
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit(“SIP/200-00000009”, “”) in new stack
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-auto-blkvm:7] ExecIf(“SIP/200-00000009”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=200)”) in new stack
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] pbx.c: Executing [s@macro-auto-blkvm:8] ExecIf(“SIP/200-00000009”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=Rohit Gupta 200)”) in new stack
[2017-02-07 17:33:32] VERBOSE[32246][C-00000006] bridge_channel.c: Channel SIP/200-00000009 joined ‘simple_bridge’ basic-bridge <832e95bd-6456-4b5d-893b-0e1621887d8e>
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] bridge_channel.c: Channel Dongle/dongle0-0100000003 joined ‘simple_bridge’ basic-bridge <832e95bd-6456-4b5d-893b-0e1621887d8e>
[2017-02-07 17:33:32] ERROR[32204] at_response.c: [dongle0] AT^DDSETEX failed (This appears AFTER call accepted at extension 200 but call drops)
[2017-02-07 17:33:32] ERROR[32204] at_response.c: [dongle0] answered incoming call with not exists call idx 4, hanging up!
[2017-02-07 17:33:32] ERROR[32204] at_response.c: [dongle0] Command ‘AT+CLCC’ failed
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] bridge_channel.c: Channel Dongle/dongle0-0100000003 left ‘simple_bridge’ basic-bridge <832e95bd-6456-4b5d-893b-0e1621887d8e>
[2017-02-07 17:33:32] VERBOSE[32204] chan_dongle.c: [dongle0] Dongle has disconnected
[2017-02-07 17:33:32] VERBOSE[32246][C-00000006] bridge_channel.c: Channel SIP/200-00000009 left ‘simple_bridge’ basic-bridge <832e95bd-6456-4b5d-893b-0e1621887d8e>
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] app_macro.c: Spawn extension (macro-dial, s, 19) exited non-zero on ‘Dongle/dongle0-0100000003’ in macro ‘dial’
[2017-02-07 17:33:32] VERBOSE[32241][C-00000006] pbx.c: Spawn extension (followme-sub, 200, 39) exited non-zero on ‘Dongle/dongle0-0100000003’
[2017-02-07 17:33:53] VERBOSE[24864] chan_dongle.c: [dongle0] IMEI 000000000000001 IMSI XXXXXXXXXXXXXXX found on data_tty=/dev/ttyUSB2 audio_tty=/dev/ttyUSB1
[2017-02-07 17:33:53] VERBOSE[24864] chan_dongle.c: [dongle0] Trying to connect on /dev/ttyUSB2…
[2017-02-07 17:33:53] VERBOSE[24864] chan_dongle.c: [dongle0] Dongle has connected, initializing…
[2017-02-07 17:33:53] VERBOSE[32247] at_response.c: [dongle0] Dongle initialized and ready