911 Log

From Edrick, 2 Years ago, written in Plain Text, viewed 3 times. This paste will hop the perch in 1 Second.
URL https://pastebin.freepbx.org/view/fefca3bc Embed
Download Paste or View Raw
  1.         1       [2020-04-03 21:12:37] VERBOSE[7904] res_pjsip_registrar.c: Added contact 'sip:404-708b7bfeb6df4e8c2ab7edafad8a69c9@10.12.26.64:5060' to AOR '404' with expiration of 3600 seconds
  2.         2       [2020-04-03 21:12:37] VERBOSE[7904] res_pjsip_registrar.c: Removed contact 'sip:404@192.168.0.33:5060' from AOR '404' due to remove existing
  3.         3       [2020-04-03 21:12:37] VERBOSE[32699] res_pjsip/pjsip_options.c: Contact 404/sip:404@192.168.0.33:5060 has been deleted
  4.         4       [2020-04-03 21:12:40] VERBOSE[25941] res_pjsip/pjsip_options.c: Contact 404/sip:404-708b7bfeb6df4e8c2ab7edafad8a69c9@10.12.26.64:5060 is now Unreachable. RTT: 0.000 msec
  5.         5       [2020-04-03 21:14:32] VERBOSE[8966] pbx_variables.c: Setting global variable 'SIPDOMAIN' to ‘pbxnumber.deployments.pbxact.com'
  6.         6       [2020-04-03 21:14:32] VERBOSE[8966] netsock2.c: Using SIP RTP Audio TOS bits 184
  7.         7       [2020-04-03 21:14:32] VERBOSE[8966] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
  8.         8       [2020-04-03 21:14:32] VERBOSE[8966] netsock2.c: Using SIP RTP Audio CoS mark 5
  9.         9       [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [911@from-internal:1] Macro("PJSIP/EXT29-0000121f", "user-callerid,LIMIT") in new stack
  10.         10      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/EXT29-0000121f", "TOUCH_MONITOR=1585973672.7665") in new stack
  11.         11      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/EXT29-0000121f", "AMPUSER=EXT29") in new stack
  12.         12      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("PJSIP/EXT29-0000121f", "0?report") in new stack
  13.         13      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("PJSIP/EXT29-0000121f", "1?Set(REALCALLERIDNUM=EXT29)") in new stack
  14.         14      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/EXT29-0000121f", "AMPUSER=EXT29") in new stack
  15.         15      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("PJSIP/EXT29-0000121f", "0?limit") in new stack
  16.         16      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/EXT29-0000121f", "AMPUSERCIDNAME=Garage  Phone") in new stack
  17.         17      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:8] ExecIf("PJSIP/EXT29-0000121f", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  18.         18      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:9] GotoIf("PJSIP/EXT29-0000121f", "0?report") in new stack
  19.         19      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/EXT29-0000121f", "AMPUSERCID=EXT29") in new stack
  20.         20      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:11] Set("PJSIP/EXT29-0000121f", "__DIAL_OPTIONS=HhTtr") in new stack
  21.         21      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:12] Set("PJSIP/EXT29-0000121f", "CALLERID(all)="Garage  Phone" <EXT29>") in new stack
  22.         22      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/EXT29-0000121f", "0?Set(CALLERID(all)=)") in new stack
  23.         23      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("PJSIP/EXT29-0000121f", "0?limit") in new stack
  24.         24      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:15] ExecIf("PJSIP/EXT29-0000121f", "1?Set(GROUP(concurrency_limit)=EXT29)") in new stack
  25.         25      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:16] ExecIf("PJSIP/EXT29-0000121f", "0?Set(CHANNEL(language)=)") in new stack
  26.         26      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:17] NoOp("PJSIP/EXT29-0000121f", "Macro Depth is 1") in new stack
  27.         27      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("PJSIP/EXT29-0000121f", "1?report2:macroerror") in new stack
  28.         28      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx_builtins.c: Goto (macro-user-callerid,s,19)
  29.         29      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("PJSIP/EXT29-0000121f", "1?continue") in new stack
  30.         30      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx_builtins.c: Goto (macro-user-callerid,s,38)
  31.         31      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:38] Set("PJSIP/EXT29-0000121f", "CALLERID(number)=EXT29") in new stack
  32.         32      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:39] Set("PJSIP/EXT29-0000121f", "CALLERID(name)=Garage  Phone") in new stack
  33.         33      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:40] GotoIf("PJSIP/EXT29-0000121f", "0?cnum") in new stack
  34.         34      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:41] Set("PJSIP/EXT29-0000121f", "CDR(cnam)=Garage  Phone") in new stack
  35.         35      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:42] Set("PJSIP/EXT29-0000121f", "CDR(cnum)=EXT29") in new stack
  36.         36      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:43] Set("PJSIP/EXT29-0000121f", "CHANNEL(language)=en") in new stack
  37.         37      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:44] ExecIf("PJSIP/EXT29-0000121f", "0?Set(CALLERID(name)=)") in new stack
  38.         38      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-user-callerid:45] GosubIf("PJSIP/EXT29-0000121f", "0?app-check-classofservce,s,1()") in new stack
  39.         39      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [911@from-internal:2] Set("PJSIP/EXT29-0000121f", "ROUTEUSER=EXT29") in new stack
  40.         40      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [911@from-internal:3] Set("PJSIP/EXT29-0000121f", "ROUTEUSER=EXT29") in new stack
  41.         41      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [911@from-internal:4] GotoIf("PJSIP/EXT29-0000121f", "1?notblind") in new stack
  42.         42      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx_builtins.c: Goto (from-internal,911,7)
  43.         43      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [911@from-internal:7] GotoIf("PJSIP/EXT29-0000121f", "1?restrictedroute-c4ca4238a0b923820dcc509a6f75849b,911,2:outbound-allroutes,911,2") in new stack
  44.         44      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx_builtins.c: Goto (restrictedroute-c4ca4238a0b923820dcc509a6f75849b,911,2)
  45.         45      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [911@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:2] Gosub("PJSIP/EXT29-0000121f", "sub-record-check,s,1(out,911,dontcare)") in new stack
  46.         46      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/EXT29-0000121f", "0?initialized") in new stack
  47.         47      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/EXT29-0000121f", "__REC_STATUS=INITIALIZED") in new stack
  48.         48      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/EXT29-0000121f", "NOW=1585973672") in new stack
  49.         49      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/EXT29-0000121f", "__DAY=03") in new stack
  50.         50      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/EXT29-0000121f", "__MONTH=04") in new stack
  51.         51      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/EXT29-0000121f", "__YEAR=2020") in new stack
  52.         52      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/EXT29-0000121f", "__TIMESTR=20200403-211432") in new stack
  53.         53      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/EXT29-0000121f", "__FROMEXTEN=EXT29") in new stack
  54.         54      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/EXT29-0000121f", "__MON_FMT=wav") in new stack
  55.         55      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/EXT29-0000121f", "Recordings initialized") in new stack
  56.         56      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/EXT29-0000121f", "0?Set(ARG3=dontcare)") in new stack
  57.         57      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/EXT29-0000121f", "REC_POLICY_MODE_SAVE=") in new stack
  58.         58      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/EXT29-0000121f", "0?Set(REC_STATUS=NO)") in new stack
  59.         59      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/EXT29-0000121f", "3?checkaction") in new stack
  60.         60      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx_builtins.c: Goto (sub-record-check,s,17)
  61.         61      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/EXT29-0000121f", "1?sub-record-check,out,1") in new stack
  62.         62      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx_builtins.c: Goto (sub-record-check,out,1)
  63.         63      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [out@sub-record-check:1] NoOp("PJSIP/EXT29-0000121f", "Outbound Recording Check from EXT29 to 911") in new stack
  64.         64      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [out@sub-record-check:2] Set("PJSIP/EXT29-0000121f", "RECMODE=dontcare") in new stack
  65.         65      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [out@sub-record-check:3] ExecIf("PJSIP/EXT29-0000121f", "1?Goto(routewins)") in new stack
  66.         66      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx_builtins.c: Goto (sub-record-check,out,7)
  67.         67      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [out@sub-record-check:7] Gosub("PJSIP/EXT29-0000121f", "recordcheck,1(dontcare,out,911)") in new stack
  68.         68      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/EXT29-0000121f", "Starting recording check against dontcare") in new stack
  69.         69      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/EXT29-0000121f", "dontcare") in new stack
  70.         70      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  71.         71      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/EXT29-0000121f", "") in new stack
  72.         72      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [out@sub-record-check:8] Return("PJSIP/EXT29-0000121f", "") in new stack
  73.         73      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [911@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:3] ExecIf("PJSIP/EXT29-0000121f", "0 ?Set(CDR(accountcode)=)") in new stack
  74.         74      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [911@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:4] Set("PJSIP/EXT29-0000121f", "MOHCLASS=default") in new stack
  75.         75      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [911@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:5] ExecIf("PJSIP/EXT29-0000121f", "0?Set(TRUNKCIDOVERRIDE=“Trunk CID Name“ <XXXXXXXX>)") in new stack
  76.         76      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [911@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:6] Set("PJSIP/EXT29-0000121f", "_NODEST=") in new stack
  77.         77      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [911@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:7] Macro("PJSIP/EXT29-0000121f", "dialout-trunk,1,911,,off") in new stack
  78.         78      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:1] Set("PJSIP/EXT29-0000121f", "DIAL_TRUNK=1") in new stack
  79.         79      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:2] UserEvent("PJSIP/EXT29-0000121f", "zulu-outbound-call,from:EXT29,to:911") in new stack
  80.         80      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:3] ExecIf("PJSIP/EXT29-0000121f", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
  81.         81      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:4] GosubIf("PJSIP/EXT29-0000121f", "0?sub-pincheck,s,1()") in new stack
  82.         82      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:5] ExecIf("PJSIP/EXT29-0000121f", "0?Set(CALLERID(num)=EXT29)") in new stack
  83.         83      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:6] GotoIf("PJSIP/EXT29-0000121f", "0?disabletrunk,1") in new stack
  84.         84      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:7] Set("PJSIP/EXT29-0000121f", "DIAL_NUMBER=911") in new stack
  85.         85      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:8] Set("PJSIP/EXT29-0000121f", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack
  86.         86      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:9] Set("PJSIP/EXT29-0000121f", "OUTBOUND_GROUP=OUT_1") in new stack
  87.         87      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:10] Set("PJSIP/EXT29-0000121f", "DIAL_TRUNK_OPTIONS=T") in new stack
  88.         88      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf("PJSIP/EXT29-0000121f", "1?nomax") in new stack
  89.         89      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx_builtins.c: Goto (macro-dialout-trunk,s,13)
  90.         90      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:13] GotoIf("PJSIP/EXT29-0000121f", "0?skipoutcid") in new stack
  91.         91      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:14] Macro("PJSIP/EXT29-0000121f", "outbound-callerid,1") in new stack
  92.         92      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("PJSIP/EXT29-0000121f", "EXT29") in new stack
  93.         93      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("PJSIP/EXT29-0000121f", "") in new stack
  94.         94      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("PJSIP/EXT29-0000121f", "off") in new stack
  95.         95      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("PJSIP/EXT29-0000121f", "0?Set(CALLERPRES(name-pres)=)") in new stack
  96.         96      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("PJSIP/EXT29-0000121f", "0?Set(CALLERPRES(num-pres)=)") in new stack
  97.         97      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:6] ExecIf("PJSIP/EXT29-0000121f", "0?Set(REALCALLERIDNUM=EXT29)") in new stack
  98.         98      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:7] ExecIf("PJSIP/EXT29-0000121f", "0?Set(AMPUSER=EXT29)") in new stack
  99.         99      [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:8] GotoIf("PJSIP/EXT29-0000121f", "1?normcid") in new stack
  100.         100     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx_builtins.c: Goto (macro-outbound-callerid,s,12)
  101.         101     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:12] Set("PJSIP/EXT29-0000121f", "USEROUTCID=EXT29") in new stack
  102.         102     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:13] Set("PJSIP/EXT29-0000121f", "EMERGENCYCID=") in new stack
  103.         103     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:14] Set("PJSIP/EXT29-0000121f", "TRUNKOUTCID=") in new stack
  104.         104     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:15] GotoIf("PJSIP/EXT29-0000121f", "1?trunkcid") in new stack
  105.         105     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx_builtins.c: Goto (macro-outbound-callerid,s,21)
  106.         106     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:21] ExecIf("PJSIP/EXT29-0000121f", "0?Set(CALLERID(all)=)") in new stack
  107.         107     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("PJSIP/EXT29-0000121f", "1?Set(CALLERID(all)=EXT29)") in new stack
  108.         108     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:23] ExecIf("PJSIP/EXT29-0000121f", "0?Set(CALLERID(all)=)") in new stack
  109.         109     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:24] ExecIf("PJSIP/EXT29-0000121f", "0?Set(CALLERID(all)=EXT29)") in new stack
  110.         110     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:25] ExecIf("PJSIP/EXT29-0000121f", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
  111.         111     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:26] ExecIf("PJSIP/EXT29-0000121f", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
  112.         112     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:27] Set("PJSIP/EXT29-0000121f", "CDR(outbound_cnum)=EXT29") in new stack
  113.         113     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-outbound-callerid:28] Set("PJSIP/EXT29-0000121f", "CDR(outbound_cnam)=") in new stack
  114.         114     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:15] GosubIf("PJSIP/EXT29-0000121f", "0?sub-flp-1,s,1()") in new stack
  115.         115     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:16] Set("PJSIP/EXT29-0000121f", "OUTNUM=911") in new stack
  116.         116     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:17] Set("PJSIP/EXT29-0000121f", "custom=SIP/SIPTRUNKPROVIDER") in new stack
  117.         117     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("PJSIP/EXT29-0000121f", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
  118.         118     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:19] ExecIf("PJSIP/EXT29-0000121f", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
  119.         119     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:20] Macro("PJSIP/EXT29-0000121f", "dialout-trunk-predial-hook,") in new stack
  120.         120     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("PJSIP/EXT29-0000121f", "") in new stack
  121.         121     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:21] GotoIf("PJSIP/EXT29-0000121f", "0?skipcrm") in new stack
  122.         122     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:22] Set("PJSIP/EXT29-0000121f", "__CRM_DIRECTION=OUTBOUND") in new stack
  123.         123     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:23] Set("PJSIP/EXT29-0000121f", "__CRM_DESTINATION=911") in new stack
  124.         124     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:24] Set("PJSIP/EXT29-0000121f", "__CRM_SOURCE=EXT29") in new stack
  125.         125     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:25] AGI("PJSIP/EXT29-0000121f", "sangomacrm.agi") in new stack
  126.         126     [2020-04-03 21:14:32] VERBOSE[8702][C-00001ce7] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  127.         127     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] res_agi.c: sangomacrm.agi: LINKEDID: 1585973672.7665
  128.         128     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] res_agi.c: sangomacrm.agi: SOURCE: EXT29
  129.         129     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] res_agi.c: sangomacrm.agi: DESTINATION: 911
  130.         130     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] res_agi.c: sangomacrm.agi: DIRECTION: OUTBOUND
  131.         131     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] res_agi.c: sangomacrm.agi: EXTTOCALL:
  132.         132     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] res_agi.c: sangomacrm.agi: START
  133.         133     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] res_agi.c: sangomacrm.agi: SCRIPT: php /var/www/html/admin/modules/sangomacrm/importOne.php 'eyJ1dWlkIjoiMTU4NTk3MzY3Mi43NjY1Iiwic291cmNlIjoiNjE5NzgxMTEyOSIsImRlc3RpbmF0aW9uIjoiOTExIiwiZGlyZWN0aW9uIjoiT1VUQk9VTkQiLCJ0eXBlIjoiU1RBUlQiLCJ6dWx1X3Jhd190eXBlIjoiIiwienVsdV90eXBlIjoiIiwienVsdV91cmwiOiIiLCJleHR0b2NhbGwiOiIiLCJjbnVtIjoiNjE5NzgxMTEyOSIsImNuYW0iOiIiLCJjYWxscG9wIjpmYWxzZSwidm9pY2VtYWlsIjoiIn0=' > /dev/null 2>&1 &
  134.         134     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] res_agi.c: <PJSIP/EXT29-0000121f>AGI Script sangomacrm.agi completed, returning 0
  135.         135     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:26] Set("PJSIP/EXT29-0000121f", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack
  136.         136     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:27] NoOp("PJSIP/EXT29-0000121f", "CRM Finished") in new stack
  137.         137     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:28] GotoIf("PJSIP/EXT29-0000121f", "0?bypass,1") in new stack
  138.         138     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("PJSIP/EXT29-0000121f", "1?Set(CONNECTEDLINE(num,i)=911)") in new stack
  139.         139     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("PJSIP/EXT29-0000121f", "1?Set(CONNECTEDLINE(name,i)=CID:EXT29)") in new stack
  140.         140     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:31] ExecIf("PJSIP/EXT29-0000121f", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)EXT29)") in new stack
  141.         141     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:32] GotoIf("PJSIP/EXT29-0000121f", "0?customtrunk") in new stack
  142.         142     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:33] ExecIf("PJSIP/EXT29-0000121f", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
  143.         143     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@macro-dialout-trunk:34] Dial("PJSIP/EXT29-0000121f", "SIP/SIPTRUNKPROVIDER/911,300,Tb(func-apply-sipheaders^s^1,(1))") in new stack
  144.         144     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] netsock2.c: Using SIP RTP TOS bits 184
  145.         145     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] netsock2.c: Using SIP RTP CoS mark 5
  146.         146     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] app_stack.c: SIP/SIPTRUNKPROVIDER-00000b02 Internal Gosub(func-apply-sipheaders,s,1(1)) start
  147.         147     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/SIPTRUNKPROVIDER-00000b02", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  148.         148     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/SIPTRUNKPROVIDER-00000b02", "Applying SIP Headers to channel SIP/SIPTRUNKPROVIDER-00000b02") in new stack
  149.         149     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/SIPTRUNKPROVIDER-00000b02", "TECH=SIP") in new stack
  150.         150     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/SIPTRUNKPROVIDER-00000b02", "SIPHEADERKEYS=") in new stack
  151.         151     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/SIPTRUNKPROVIDER-00000b02", "0") in new stack
  152.         152     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] app_while.c: Jumping to priority 13
  153.         153     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/SIPTRUNKPROVIDER-00000b02", "") in new stack
  154.         154     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] app_stack.c: Spawn extension (from-trunk, 911, 1) exited non-zero on 'SIP/SIPTRUNKPROVIDER-00000b02'
  155.         155     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] app_stack.c: SIP/SIPTRUNKPROVIDER-00000b02 Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL=
  156.         156     [2020-04-03 21:14:33] VERBOSE[8702][C-00001ce7] app_dial.c: Called SIP/SIPTRUNKPROVIDER/911
  157.         157     [2020-04-03 21:14:35] VERBOSE[8702][C-00001ce7] app_dial.c: SIP/SIPTRUNKPROVIDER-00000b02 is ringing
  158.         158     [2020-04-03 21:14:35] VERBOSE[8702][C-00001ce7] app_dial.c: SIP/SIPTRUNKPROVIDER-00000b02 is making progress passing it to PJSIP/EXT29-0000121f
  159.         159     [2020-04-03 21:14:39] VERBOSE[8702][C-00001ce7] app_dial.c: SIP/SIPTRUNKPROVIDER-00000b02 answered PJSIP/EXT29-0000121f
  160.         160     [2020-04-03 21:14:39] VERBOSE[8774][C-00001ce7] bridge_channel.c: Channel SIP/SIPTRUNKPROVIDER-00000b02 joined 'simple_bridge' basic-bridge <578b63c4-d28b-47b8-b8e8-8abd835eef56>
  161.         161     [2020-04-03 21:14:39] VERBOSE[8702][C-00001ce7] bridge_channel.c: Channel PJSIP/EXT29-0000121f joined 'simple_bridge' basic-bridge <578b63c4-d28b-47b8-b8e8-8abd835eef56>
  162.         162     [2020-04-03 21:15:18] VERBOSE[8774][C-00001ce7] bridge_channel.c: Channel SIP/SIPTRUNKPROVIDER-00000b02 left 'simple_bridge' basic-bridge <578b63c4-d28b-47b8-b8e8-8abd835eef56>
  163.         163     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] bridge_channel.c: Channel PJSIP/EXT29-0000121f left 'simple_bridge' basic-bridge <578b63c4-d28b-47b8-b8e8-8abd835eef56>
  164.         164     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] app_macro.c: Spawn extension (macro-dialout-trunk, s, 34) exited non-zero on 'PJSIP/EXT29-0000121f' in macro 'dialout-trunk'
  165.         165     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] pbx.c: Spawn extension (restrictedroute-c4ca4238a0b923820dcc509a6f75849b, 911, 7) exited non-zero on 'PJSIP/EXT29-0000121f'
  166.         166     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] pbx.c: Executing [h@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:1] Hangup("PJSIP/EXT29-0000121f", "") in new stack
  167.         167     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] pbx.c: Spawn extension (restrictedroute-c4ca4238a0b923820dcc509a6f75849b, h, 1) exited non-zero on 'PJSIP/EXT29-0000121f'
  168.         168     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] app_stack.c: PJSIP/EXT29-0000121f Internal Gosub(crm-hangup,s,1) start
  169.         169     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/EXT29-0000121f", "Sending Hangup to CRM") in new stack
  170.         170     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/EXT29-0000121f", "HANGUP CAUSE: 16") in new stack
  171.         171     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/EXT29-0000121f", "0?Set(__CRM_VOICEMAIL=)") in new stack
  172.         172     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/EXT29-0000121f", "MASTER CHANNEL: 1585973672.7665 = 1585973672.7665") in new stack
  173.         173     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/EXT29-0000121f", "0?return") in new stack
  174.         174     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@crm-hangup:6] Set("PJSIP/EXT29-0000121f", "__CRM_HANGUP=1") in new stack
  175.         175     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@crm-hangup:7] AGI("PJSIP/EXT29-0000121f", "sangomacrm.agi") in new stack
  176.         176     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  177.         177     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] res_agi.c: sangomacrm.agi: LINKEDID: 1585973672.7665
  178.         178     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] res_agi.c: sangomacrm.agi: SOURCE: EXT29
  179.         179     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] res_agi.c: sangomacrm.agi: DESTINATION: 911
  180.         180     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] res_agi.c: sangomacrm.agi: DIRECTION: OUTBOUND
  181.         181     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] res_agi.c: sangomacrm.agi: EXTTOCALL:
  182.         182     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] res_agi.c: sangomacrm.agi: START
  183.         183     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] res_agi.c: sangomacrm.agi: SCRIPT: php /var/www/html/admin/modules/sangomacrm/importOne.php 'eyJ1dWlkIjoiMTU4NTk3MzY3Mi43NjY1Iiwic291cmNlIjoiNjE5NzgxMTEyOSIsImRlc3RpbmF0aW9uIjoiOTExIiwiZGlyZWN0aW9uIjoiT1VUQk9VTkQiLCJ0eXBlIjoiRU5EIiwienVsdV9yYXdfdHlwZSI6IiIsInp1bHVfdHlwZSI6IiIsInp1bHVfdXJsIjoiIiwiZXh0dG9jYWxsIjoiIiwiY251bSI6IjYxOTc4MTExMjkiLCJjbmFtIjoiIiwiY2FsbHBvcCI6ZmFsc2UsInZvaWNlbWFpbCI6IiJ9' > /dev/null 2>&1 &
  184.         184     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] res_agi.c: <PJSIP/EXT29-0000121f>AGI Script sangomacrm.agi completed, returning 0
  185.         185     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/EXT29-0000121f", "") in new stack
  186.         186     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] app_stack.c: Spawn extension (restrictedroute-c4ca4238a0b923820dcc509a6f75849b, h, 1) exited non-zero on 'PJSIP/EXT29-0000121f'
  187.         187     [2020-04-03 21:15:18] VERBOSE[8702][C-00001ce7] app_stack.c: PJSIP/EXT29-0000121f Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  188.         188     [2020-04-03 21:15:21] VERBOSE[12135][C-00001ce8] netsock2.c: Using SIP RTP TOS bits 184
  189.         189     [2020-04-03 21:15:21] VERBOSE[12135][C-00001ce8] netsock2.c: Using SIP RTP CoS mark 5
  190.         190     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:1] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__DIRECTION=INBOUND") in new stack
  191.         191     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:2] Gosub("SIP/SIPTRUNKPROVIDER-00000b03", "sub-record-check,s,1(in,1EXT29,dontcare)") in new stack
  192.         192     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?initialized") in new stack
  193.         193     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:2] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__REC_STATUS=INITIALIZED") in new stack
  194.         194     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:3] Set("SIP/SIPTRUNKPROVIDER-00000b03", "NOW=1585973721") in new stack
  195.         195     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:4] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__DAY=03") in new stack
  196.         196     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:5] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__MONTH=04") in new stack
  197.         197     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:6] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__YEAR=2020") in new stack
  198.         198     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:7] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__TIMESTR=20200403-211521") in new stack
  199.         199     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:8] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__FROMEXTEN=unknown") in new stack
  200.         200     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:9] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__MON_FMT=wav") in new stack
  201.         201     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "Recordings initialized") in new stack
  202.         202     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(ARG3=dontcare)") in new stack
  203.         203     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:12] Set("SIP/SIPTRUNKPROVIDER-00000b03", "REC_POLICY_MODE_SAVE=") in new stack
  204.         204     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(REC_STATUS=NO)") in new stack
  205.         205     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "2?checkaction") in new stack
  206.         206     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (sub-record-check,s,17)
  207.         207     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?sub-record-check,in,1") in new stack
  208.         208     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (sub-record-check,in,1)
  209.         209     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "Inbound Recording Check to 1EXT29") in new stack
  210.         210     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [in@sub-record-check:2] Set("SIP/SIPTRUNKPROVIDER-00000b03", "FROMEXTEN=unknown") in new stack
  211.         211     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "9?Set(FROMEXTEN=anonymous)") in new stack
  212.         212     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/SIPTRUNKPROVIDER-00000b03", "recordcheck,1(dontcare,in,1EXT29)") in new stack
  213.         213     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "Starting recording check against dontcare") in new stack
  214.         214     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/SIPTRUNKPROVIDER-00000b03", "dontcare") in new stack
  215.         215     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  216.         216     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/SIPTRUNKPROVIDER-00000b03", "") in new stack
  217.         217     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [in@sub-record-check:5] Return("SIP/SIPTRUNKPROVIDER-00000b03", "") in new stack
  218.         218     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:3] Set("SIP/SIPTRUNKPROVIDER-00000b03", "CHANNEL(tonezone)=us") in new stack
  219.         219     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:4] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__FROM_DID=1EXT29") in new stack
  220.         220     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:5] Set("SIP/SIPTRUNKPROVIDER-00000b03", "returnhere=1") in new stack
  221.         221     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:6] Gosub("SIP/SIPTRUNKPROVIDER-00000b03", "app-blacklist-check,s,1()") in new stack
  222.         222     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?blacklisted") in new stack
  223.         223     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/SIPTRUNKPROVIDER-00000b03", "CALLED_BLACKLIST=1") in new stack
  224.         224     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/SIPTRUNKPROVIDER-00000b03", "") in new stack
  225.         225     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:7] Set("SIP/SIPTRUNKPROVIDER-00000b03", "CDR(did)=1EXT29") in new stack
  226.         226     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:8] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?") in new stack
  227.         227     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:9] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "1 ?Set(CALLERID(name)=anonymous)") in new stack
  228.         228     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:10] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__MOHCLASS=") in new stack
  229.         229     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:11] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__REVERSAL_REJECT=FALSE") in new stack
  230.         230     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:12] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?post-reverse-charge") in new stack
  231.         231     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (from-trunk,1EXT29,14)
  232.         232     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:14] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "") in new stack
  233.         233     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:15] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
  234.         234     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:16] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
  235.         235     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:17] Set("SIP/SIPTRUNKPROVIDER-00000b03", "CALLERID(name-pres)=allowed_not_screened") in new stack
  236.         236     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:18] Set("SIP/SIPTRUNKPROVIDER-00000b03", "CALLERID(num-pres)=allowed_not_screened") in new stack
  237.         237     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:19] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "CallerID Entry Point") in new stack
  238.         238     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:20] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__CRM_DIRECTION=INBOUND") in new stack
  239.         239     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:21] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__CRM_SOURCE=anonymous") in new stack
  240.         240     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:22] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__CRM_LINKEDID=1585973721.7667") in new stack
  241.         241     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:23] AGI("SIP/SIPTRUNKPROVIDER-00000b03", "sangomacrm.agi,true") in new stack
  242.         242     [2020-04-03 21:15:21] VERBOSE[8931][C-00001ce8] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  243.         243     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] res_agi.c: sangomacrm.agi,true: LINKEDID: 1585973721.7667
  244.         244     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] res_agi.c: sangomacrm.agi,true: SOURCE: anonymous
  245.         245     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] res_agi.c: sangomacrm.agi,true: DESTINATION:
  246.         246     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] res_agi.c: sangomacrm.agi,true: DIRECTION: INBOUND
  247.         247     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] res_agi.c: sangomacrm.agi,true: EXTTOCALL:
  248.         248     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] res_agi.c: sangomacrm.agi,true: START
  249.         249     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] res_agi.c: sangomacrm.agi,true: SCRIPT: php /var/www/html/admin/modules/sangomacrm/importOne.php 'eyJ1dWlkIjoiMTU4NTk3MzcyMS43NjY3Iiwic291cmNlIjoiYW5vbnltb3VzIiwiZGVzdGluYXRpb24iOiIiLCJkaXJlY3Rpb24iOiJJTkJPVU5EIiwidHlwZSI6IlNUQVJUIiwienVsdV9yYXdfdHlwZSI6IiIsInp1bHVfdHlwZSI6IiIsInp1bHVfdXJsIjoiIiwiZXh0dG9jYWxsIjoiIiwiY251bSI6ImFub255bW91cyIsImNuYW0iOiJhbm9ueW1vdXMiLCJjYWxscG9wIjp0cnVlLCJ2b2ljZW1haWwiOiIifQ==' > /dev/null 2>&1 &
  250.         250     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] res_agi.c: <SIP/SIPTRUNKPROVIDER-00000b03>AGI Script sangomacrm.agi completed, returning 0
  251.         251     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:24] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  252.         252     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [1EXT29@from-trunk:25] Goto("SIP/SIPTRUNKPROVIDER-00000b03", "from-did-direct,EXT29,1") in new stack
  253.         253     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (from-did-direct,EXT29,1)
  254.         254     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [EXT29@from-did-direct:1] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?ext-local,EXT29,1:followme-check,EXT29,1") in new stack
  255.         255     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (ext-local,EXT29,1)
  256.         256     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [EXT29@ext-local:1] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__RINGTIMER=15") in new stack
  257.         257     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [EXT29@ext-local:2] Macro("SIP/SIPTRUNKPROVIDER-00000b03", "exten-vm,novm,EXT29,0,0,0") in new stack
  258.         258     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-exten-vm:1] Macro("SIP/SIPTRUNKPROVIDER-00000b03", "user-callerid,") in new stack
  259.         259     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/SIPTRUNKPROVIDER-00000b03", "TOUCH_MONITOR=1585973721.7667") in new stack
  260.         260     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/SIPTRUNKPROVIDER-00000b03", "AMPUSER=anonymous") in new stack
  261.         261     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?report") in new stack
  262.         262     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?Set(REALCALLERIDNUM=anonymous)") in new stack
  263.         263     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/SIPTRUNKPROVIDER-00000b03", "AMPUSER=") in new stack
  264.         264     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?limit") in new stack
  265.         265     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/SIPTRUNKPROVIDER-00000b03", "AMPUSERCIDNAME=") in new stack
  266.         266     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:8] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  267.         267     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:9] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?report") in new stack
  268.         268     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (macro-user-callerid,s,17)
  269.         269     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:17] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "Macro Depth is 2") in new stack
  270.         270     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?report2:macroerror") in new stack
  271.         271     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (macro-user-callerid,s,19)
  272.         272     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?continue") in new stack
  273.         273     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:20] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
  274.         274     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:21] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__TTL=64") in new stack
  275.         275     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:22] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?continue") in new stack
  276.         276     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (macro-user-callerid,s,38)
  277.         277     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:38] Set("SIP/SIPTRUNKPROVIDER-00000b03", "CALLERID(number)=anonymous") in new stack
  278.         278     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:39] Set("SIP/SIPTRUNKPROVIDER-00000b03", "CALLERID(name)=anonymous") in new stack
  279.         279     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:40] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?cnum") in new stack
  280.         280     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:41] Set("SIP/SIPTRUNKPROVIDER-00000b03", "CDR(cnam)=anonymous") in new stack
  281.         281     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:42] Set("SIP/SIPTRUNKPROVIDER-00000b03", "CDR(cnum)=anonymous") in new stack
  282.         282     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:43] Set("SIP/SIPTRUNKPROVIDER-00000b03", "CHANNEL(language)=en") in new stack
  283.         283     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:44] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(CALLERID(name)=)") in new stack
  284.         284     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-user-callerid:45] GosubIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?app-check-classofservce,s,1()") in new stack
  285.         285     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-exten-vm:2] Set("SIP/SIPTRUNKPROVIDER-00000b03", "RingGroupMethod=none") in new stack
  286.         286     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-exten-vm:3] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__EXTTOCALL=EXT29") in new stack
  287.         287     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-exten-vm:4] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__PICKUPMARK=EXT29") in new stack
  288.         288     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-exten-vm:5] Set("SIP/SIPTRUNKPROVIDER-00000b03", "RT=") in new stack
  289.         289     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-exten-vm:6] Gosub("SIP/SIPTRUNKPROVIDER-00000b03", "sub-record-check,s,1(exten,EXT29,dontcare)") in new stack
  290.         290     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "9?initialized") in new stack
  291.         291     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (sub-record-check,s,10)
  292.         292     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "Recordings initialized") in new stack
  293.         293     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(ARG3=dontcare)") in new stack
  294.         294     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:12] Set("SIP/SIPTRUNKPROVIDER-00000b03", "REC_POLICY_MODE_SAVE=") in new stack
  295.         295     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(REC_STATUS=NO)") in new stack
  296.         296     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "5?checkaction") in new stack
  297.         297     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (sub-record-check,s,17)
  298.         298     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?sub-record-check,exten,1") in new stack
  299.         299     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (sub-record-check,exten,1)
  300.         300     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "Exten Recording Check between anonymous and EXT29") in new stack
  301.         301     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/SIPTRUNKPROVIDER-00000b03", "CALLTYPE=external") in new stack
  302.         302     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(CALLTYPE=)") in new stack
  303.         303     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/SIPTRUNKPROVIDER-00000b03", "CALLEE=dontcare") in new stack
  304.         304     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(CALLEE=dontcare)") in new stack
  305.         305     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?callee") in new stack
  306.         306     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (sub-record-check,exten,11)
  307.         307     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [exten@sub-record-check:11] Gosub("SIP/SIPTRUNKPROVIDER-00000b03", "recordcheck,1(dontcare,external,EXT29)") in new stack
  308.         308     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "Starting recording check against dontcare") in new stack
  309.         309     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/SIPTRUNKPROVIDER-00000b03", "dontcare") in new stack
  310.         310     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  311.         311     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/SIPTRUNKPROVIDER-00000b03", "") in new stack
  312.         312     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [exten@sub-record-check:12] Return("SIP/SIPTRUNKPROVIDER-00000b03", "") in new stack
  313.         313     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-exten-vm:7] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?macrodial") in new stack
  314.         314     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (macro-exten-vm,s,13)
  315.         315     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-exten-vm:13] GosubIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?clrheader,1()") in new stack
  316.         316     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-exten-vm:14] Macro("SIP/SIPTRUNKPROVIDER-00000b03", "dial-one,,HhTtr,EXT29") in new stack
  317.         317     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:1] Set("SIP/SIPTRUNKPROVIDER-00000b03", "DEXTEN=EXT29") in new stack
  318.         318     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:2] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__CRM_SOURCE=anonymous") in new stack
  319.         319     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:3] UserEvent("SIP/SIPTRUNKPROVIDER-00000b03", "zulu-inbound-call,type:macro-dial-one,from:anonymous,to:EXT29") in new stack
  320.         320     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:4] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(__EXTTOCALL=EXT29)") in new stack
  321.         321     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:5] Set("SIP/SIPTRUNKPROVIDER-00000b03", "DIALSTATUS_CW=") in new stack
  322.         322     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:6] GosubIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?screen,1()") in new stack
  323.         323     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:7] GosubIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?cf,1()") in new stack
  324.         324     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:8] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?skip1") in new stack
  325.         325     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (macro-dial-one,s,11)
  326.         326     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:11] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?nodial") in new stack
  327.         327     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:12] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?continue") in new stack
  328.         328     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:13] Set("SIP/SIPTRUNKPROVIDER-00000b03", "EXTHASCW=ENABLED") in new stack
  329.         329     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:14] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?next1:cwinusebusy") in new stack
  330.         330     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (macro-dial-one,s,26)
  331.         331     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:26] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?next3:continue") in new stack
  332.         332     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (macro-dial-one,s,28)
  333.         333     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:28] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?nodial") in new stack
  334.         334     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:29] GosubIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?dstring,1():dlocal,1()") in new stack
  335.         335     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:1] Set("SIP/SIPTRUNKPROVIDER-00000b03", "DSTRING=") in new stack
  336.         336     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:2] Set("SIP/SIPTRUNKPROVIDER-00000b03", "DEVICES=EXT29") in new stack
  337.         337     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Return()") in new stack
  338.         338     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(DEVICES=197811129)") in new stack
  339.         339     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:5] Set("SIP/SIPTRUNKPROVIDER-00000b03", "LOOPCNT=1") in new stack
  340.         340     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:6] Set("SIP/SIPTRUNKPROVIDER-00000b03", "ITER=1") in new stack
  341.         341     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:7] Set("SIP/SIPTRUNKPROVIDER-00000b03", "THISDIAL=PJSIP/EXT29") in new stack
  342.         342     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?zap2dahdi,1()") in new stack
  343.         343     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Return()") in new stack
  344.         344     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("SIP/SIPTRUNKPROVIDER-00000b03", "NEWDIAL=") in new stack
  345.         345     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("SIP/SIPTRUNKPROVIDER-00000b03", "LOOPCNT2=1") in new stack
  346.         346     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("SIP/SIPTRUNKPROVIDER-00000b03", "ITER2=1") in new stack
  347.         347     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("SIP/SIPTRUNKPROVIDER-00000b03", "THISPART2=PJSIP/EXT29") in new stack
  348.         348     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(THISPART2=DAHDIIP/EXT29)") in new stack
  349.         349     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("SIP/SIPTRUNKPROVIDER-00000b03", "NEWDIAL=PJSIP/EXT29&") in new stack
  350.         350     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("SIP/SIPTRUNKPROVIDER-00000b03", "ITER2=2") in new stack
  351.         351     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?begin2") in new stack
  352.         352     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("SIP/SIPTRUNKPROVIDER-00000b03", "THISDIAL=PJSIP/EXT29") in new stack
  353.         353     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("SIP/SIPTRUNKPROVIDER-00000b03", "") in new stack
  354.         354     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?docheck") in new stack
  355.         355     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:10] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "Debug: Found PJSIP Destination PJSIP/EXT29") in new stack
  356.         356     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:11] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?doset") in new stack
  357.         357     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:12] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS") in new stack
  358.         358     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:13] Set("SIP/SIPTRUNKPROVIDER-00000b03", "THISDIAL=PJSIP/EXT29/sip:EXT29@PUBLICIPOFEXTENSION:5060") in new stack
  359.         359     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:14] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(DIALSTATUS=CHANUNAVAIL)") in new stack
  360.         360     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?skipset") in new stack
  361.         361     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:16] Set("SIP/SIPTRUNKPROVIDER-00000b03", "DSTRING=PJSIP/EXT29/sip:EXT29@PUBLICIPOFEXTENSION:5060&") in new stack
  362.         362     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:17] Set("SIP/SIPTRUNKPROVIDER-00000b03", "ITER=2") in new stack
  363.         363     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?begin") in new stack
  364.         364     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Return()") in new stack
  365.         365     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:20] Set("SIP/SIPTRUNKPROVIDER-00000b03", "DSTRING=PJSIP/EXT29/sip:EXT29@PUBLICIPOFEXTENSION:5060") in new stack
  366.         366     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [dstring@macro-dial-one:21] Return("SIP/SIPTRUNKPROVIDER-00000b03", "") in new stack
  367.         367     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:30] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?nodial") in new stack
  368.         368     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:31] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?skiptrace") in new stack
  369.         369     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:32] GosubIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?ctset,1():ctclear,1()") in new stack
  370.         370     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [ctclear@macro-dial-one:1] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "Deleting: CALLTRACE/EXT29 13235031983") in new stack
  371.         371     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [ctclear@macro-dial-one:2] Return("SIP/SIPTRUNKPROVIDER-00000b03", "") in new stack
  372.         372     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:33] Set("SIP/SIPTRUNKPROVIDER-00000b03", "D_OPTIONS=HhTtr") in new stack
  373.         373     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:34] GosubIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
  374.         374     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:35] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
  375.         375     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:36] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(ALERT_INFO=)") in new stack
  376.         376     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:37] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(ALERT_INFO=)") in new stack
  377.         377     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:38] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(ALERT_INFO=)") in new stack
  378.         378     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:39] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
  379.         379     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:40] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
  380.         380     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:41] GosubIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
  381.         381     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:42] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(CHANNEL(musicclass)=)") in new stack
  382.         382     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:43] GosubIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?qwait,1()") in new stack
  383.         383     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:44] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__CWIGNORE=") in new stack
  384.         384     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:45] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__KEEPCID=TRUE") in new stack
  385.         385     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:46] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?usegoto,1") in new stack
  386.         386     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:47] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?godial") in new stack
  387.         387     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (macro-dial-one,s,52)
  388.         388     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:52] Macro("SIP/SIPTRUNKPROVIDER-00000b03", "dialout-one-predial-hook,") in new stack
  389.         389     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/SIPTRUNKPROVIDER-00000b03", "") in new stack
  390.         390     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:53] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?Set(D_OPTIONS=HhtrI)") in new stack
  391.         391     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:54] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__ZULU_TIMEOUT=15") in new stack
  392.         392     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:55] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__ZULU_FROMEXTEN=anonymous") in new stack
  393.         393     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:56] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__ZULU_DIALOPTS=HhTtr") in new stack
  394.         394     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:57] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "Finished") in new stack
  395.         395     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:58] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "") in new stack
  396.         396     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:59] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(D_OPTIONS=HhtrIg)") in new stack
  397.         397     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:60] Dial("SIP/SIPTRUNKPROVIDER-00000b03", "PJSIP/EXT29/sip:EXT29@PUBLICIPOFEXTENSION:5060,,HhtrIb(func-apply-sipheaders^s^1)") in new stack
  398.         398     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] app_stack.c: PJSIP/EXT29-00001220 Internal Gosub(func-apply-sipheaders,s,1) start
  399.         399     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/EXT29-00001220", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  400.         400     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/EXT29-00001220", "Applying SIP Headers to channel PJSIP/EXT29-00001220") in new stack
  401.         401     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/EXT29-00001220", "TECH=PJSIP") in new stack
  402.         402     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/EXT29-00001220", "SIPHEADERKEYS=") in new stack
  403.         403     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@func-apply-sipheaders:5] While("PJSIP/EXT29-00001220", "0") in new stack
  404.         404     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] app_while.c: Jumping to priority 13
  405.         405     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@func-apply-sipheaders:14] Return("PJSIP/EXT29-00001220", "") in new stack
  406.         406     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] app_stack.c: Spawn extension (from-internal, EXT29, 1) exited non-zero on 'PJSIP/EXT29-00001220'
  407.         407     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] app_stack.c: PJSIP/EXT29-00001220 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  408.         408     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] app_dial.c: Called PJSIP/EXT29/sip:EXT29@PUBLICIPOFEXTENSION:5060
  409.         409     [2020-04-03 21:15:22] VERBOSE[32699] netsock2.c: Using SIP RTP Audio TOS bits 184
  410.         410     [2020-04-03 21:15:22] VERBOSE[32699] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
  411.         411     [2020-04-03 21:15:22] VERBOSE[32699] netsock2.c: Using SIP RTP Audio CoS mark 5
  412.         412     [2020-04-03 21:15:22] VERBOSE[8931][C-00001ce8] app_dial.c: Connected line update to SIP/SIPTRUNKPROVIDER-00000b03 prevented.
  413.         413     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] app_stack.c: PJSIP/EXT29-00001220 Internal Gosub(crm-hangup,s,1) start
  414.         414     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/EXT29-00001220", "Sending Hangup to CRM") in new stack
  415.         415     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/EXT29-00001220", "HANGUP CAUSE: 17") in new stack
  416.         416     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/EXT29-00001220", "0?Set(__CRM_VOICEMAIL=)") in new stack
  417.         417     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/EXT29-00001220", "MASTER CHANNEL: 1585973722.7668 = 1585973721.7667") in new stack
  418.         418     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/EXT29-00001220", "1?return") in new stack
  419.         419     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (crm-hangup,s,8)
  420.         420     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/EXT29-00001220", "") in new stack
  421.         421     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] app_stack.c: Spawn extension (from-internal, EXT29, 1) exited non-zero on 'PJSIP/EXT29-00001220'
  422.         422     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] app_stack.c: PJSIP/EXT29-00001220 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  423.         423     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] app_dial.c: Everyone is busy/congested at this time (1:1/0/0)
  424.         424     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:61] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?MacroExit()") in new stack
  425.         425     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:62] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(DIALSTATUS=)") in new stack
  426.         426     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:63] GosubIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?s-BUSY,1()") in new stack
  427.         427     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-dial-one:64] MacroExit("SIP/SIPTRUNKPROVIDER-00000b03", "") in new stack
  428.         428     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-exten-vm:15] Set("SIP/SIPTRUNKPROVIDER-00000b03", "SV_DIALSTATUS=BUSY") in new stack
  429.         429     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-exten-vm:16] GosubIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?docfu,1()") in new stack
  430.         430     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-exten-vm:17] GosubIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?docfb,1()") in new stack
  431.         431     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-exten-vm:18] Set("SIP/SIPTRUNKPROVIDER-00000b03", "DIALSTATUS=BUSY") in new stack
  432.         432     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-exten-vm:19] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?MacroExit()") in new stack
  433.         433     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-exten-vm:20] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?s-BUSY,1") in new stack
  434.         434     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (macro-exten-vm,s-BUSY,1)
  435.         435     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s-BUSY@macro-exten-vm:1] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?exit,1") in new stack
  436.         436     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s-BUSY@macro-exten-vm:2] PlayTones("SIP/SIPTRUNKPROVIDER-00000b03", "busy") in new stack
  437.         437     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s-BUSY@macro-exten-vm:3] Busy("SIP/SIPTRUNKPROVIDER-00000b03", "20") in new stack
  438.         438     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] app_macro.c: Spawn extension (macro-exten-vm, s-BUSY, 3) exited non-zero on 'SIP/SIPTRUNKPROVIDER-00000b03' in macro 'exten-vm'
  439.         439     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Spawn extension (ext-local, EXT29, 2) exited non-zero on 'SIP/SIPTRUNKPROVIDER-00000b03'
  440.         440     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [h@ext-local:1] Macro("SIP/SIPTRUNKPROVIDER-00000b03", "hangupcall,") in new stack
  441.         441     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?theend") in new stack
  442.         442     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  443.         443     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(CDR(recordingfile)=)") in new stack
  444.         444     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", " montior file= ") in new stack
  445.         445     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "1?skipagi") in new stack
  446.         446     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx_builtins.c: Goto (macro-hangupcall,s,7)
  447.         447     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/SIPTRUNKPROVIDER-00000b03", "") in new stack
  448.         448     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/SIPTRUNKPROVIDER-00000b03' in macro 'hangupcall'
  449.         449     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/SIPTRUNKPROVIDER-00000b03'
  450.         450     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] app_stack.c: SIP/SIPTRUNKPROVIDER-00000b03 Internal Gosub(crm-hangup,s,1) start
  451.         451     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "Sending Hangup to CRM") in new stack
  452.         452     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "HANGUP CAUSE: 17") in new stack
  453.         453     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?Set(__CRM_VOICEMAIL=)") in new stack
  454.         454     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/SIPTRUNKPROVIDER-00000b03", "MASTER CHANNEL: 1585973721.7667 = 1585973721.7667") in new stack
  455.         455     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/SIPTRUNKPROVIDER-00000b03", "0?return") in new stack
  456.         456     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@crm-hangup:6] Set("SIP/SIPTRUNKPROVIDER-00000b03", "__CRM_HANGUP=1") in new stack
  457.         457     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/SIPTRUNKPROVIDER-00000b03", "sangomacrm.agi") in new stack
  458.         458     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  459.         459     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] res_agi.c: sangomacrm.agi: LINKEDID: 1585973721.7667
  460.         460     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] res_agi.c: sangomacrm.agi: SOURCE: anonymous
  461.         461     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] res_agi.c: sangomacrm.agi: DESTINATION:
  462.         462     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] res_agi.c: sangomacrm.agi: DIRECTION: INBOUND
  463.         463     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] res_agi.c: sangomacrm.agi: EXTTOCALL: EXT29
  464.         464     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] res_agi.c: sangomacrm.agi: START
  465.         465     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] res_agi.c: sangomacrm.agi: SCRIPT: php /var/www/html/admin/modules/sangomacrm/importOne.php 'eyJ1dWlkIjoiMTU4NTk3MzcyMS43NjY3Iiwic291cmNlIjoiYW5vbnltb3VzIiwiZGVzdGluYXRpb24iOiIiLCJkaXJlY3Rpb24iOiJJTkJPVU5EIiwidHlwZSI6IkVORCIsInp1bHVfcmF3X3R5cGUiOiIiLCJ6dWx1X3R5cGUiOiIiLCJ6dWx1X3VybCI6IiIsImV4dHRvY2FsbCI6IjYxOTc4MTExMjkiLCJjbnVtIjoiYW5vbnltb3VzIiwiY25hbSI6ImFub255bW91cyIsImNhbGxwb3AiOmZhbHNlLCJ2b2ljZW1haWwiOiIifQ==' > /dev/null 2>&1 &
  466.         466     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] res_agi.c: <SIP/SIPTRUNKPROVIDER-00000b03>AGI Script sangomacrm.agi completed, returning 0
  467.         467     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] pbx.c: Executing [s@crm-hangup:8] Return("SIP/SIPTRUNKPROVIDER-00000b03", "") in new stack
  468.         468     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] app_stack.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/SIPTRUNKPROVIDER-00000b03'
  469.         469     [2020-04-03 21:15:23] VERBOSE[8931][C-00001ce8] app_stack.c: SIP/SIPTRUNKPROVIDER-00000b03 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  470.         470     [2020-04-03 21:15:23] VERBOSE[12135][C-00001ce9] netsock2.c: Using SIP RTP TOS bits 184
  471.         471     [2020-04-03 21:15:23] VERBOSE[12135][C-00001ce9] netsock2.c: Using SIP RTP CoS mark 5
  472.         472     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:1] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__DIRECTION=INBOUND") in new stack
  473.         473     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:2] Gosub("SIP/SIPTRUNKPROVIDER-00000b04", "sub-record-check,s,1(in,1EXT29,dontcare)") in new stack
  474.         474     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?initialized") in new stack
  475.         475     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:2] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__REC_STATUS=INITIALIZED") in new stack
  476.         476     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:3] Set("SIP/SIPTRUNKPROVIDER-00000b04", "NOW=1585973723") in new stack
  477.         477     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:4] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__DAY=03") in new stack
  478.         478     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:5] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__MONTH=04") in new stack
  479.         479     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:6] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__YEAR=2020") in new stack
  480.         480     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:7] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__TIMESTR=20200403-211523") in new stack
  481.         481     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:8] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__FROMEXTEN=unknown") in new stack
  482.         482     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:9] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__MON_FMT=wav") in new stack
  483.         483     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "Recordings initialized") in new stack
  484.         484     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(ARG3=dontcare)") in new stack
  485.         485     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:12] Set("SIP/SIPTRUNKPROVIDER-00000b04", "REC_POLICY_MODE_SAVE=") in new stack
  486.         486     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(REC_STATUS=NO)") in new stack
  487.         487     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "2?checkaction") in new stack
  488.         488     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (sub-record-check,s,17)
  489.         489     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?sub-record-check,in,1") in new stack
  490.         490     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (sub-record-check,in,1)
  491.         491     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "Inbound Recording Check to 1EXT29") in new stack
  492.         492     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [in@sub-record-check:2] Set("SIP/SIPTRUNKPROVIDER-00000b04", "FROMEXTEN=unknown") in new stack
  493.         493     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "9?Set(FROMEXTEN=anonymous)") in new stack
  494.         494     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/SIPTRUNKPROVIDER-00000b04", "recordcheck,1(dontcare,in,1EXT29)") in new stack
  495.         495     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "Starting recording check against dontcare") in new stack
  496.         496     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/SIPTRUNKPROVIDER-00000b04", "dontcare") in new stack
  497.         497     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  498.         498     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/SIPTRUNKPROVIDER-00000b04", "") in new stack
  499.         499     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [in@sub-record-check:5] Return("SIP/SIPTRUNKPROVIDER-00000b04", "") in new stack
  500.         500     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:3] Set("SIP/SIPTRUNKPROVIDER-00000b04", "CHANNEL(tonezone)=us") in new stack
  501.         501     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:4] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__FROM_DID=1EXT29") in new stack
  502.         502     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:5] Set("SIP/SIPTRUNKPROVIDER-00000b04", "returnhere=1") in new stack
  503.         503     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:6] Gosub("SIP/SIPTRUNKPROVIDER-00000b04", "app-blacklist-check,s,1()") in new stack
  504.         504     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?blacklisted") in new stack
  505.         505     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/SIPTRUNKPROVIDER-00000b04", "CALLED_BLACKLIST=1") in new stack
  506.         506     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/SIPTRUNKPROVIDER-00000b04", "") in new stack
  507.         507     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:7] Set("SIP/SIPTRUNKPROVIDER-00000b04", "CDR(did)=1EXT29") in new stack
  508.         508     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:8] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?") in new stack
  509.         509     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:9] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "1 ?Set(CALLERID(name)=anonymous)") in new stack
  510.         510     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:10] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__MOHCLASS=") in new stack
  511.         511     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:11] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__REVERSAL_REJECT=FALSE") in new stack
  512.         512     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:12] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?post-reverse-charge") in new stack
  513.         513     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (from-trunk,1EXT29,14)
  514.         514     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:14] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "") in new stack
  515.         515     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:15] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
  516.         516     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:16] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
  517.         517     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:17] Set("SIP/SIPTRUNKPROVIDER-00000b04", "CALLERID(name-pres)=allowed_not_screened") in new stack
  518.         518     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:18] Set("SIP/SIPTRUNKPROVIDER-00000b04", "CALLERID(num-pres)=allowed_not_screened") in new stack
  519.         519     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:19] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "CallerID Entry Point") in new stack
  520.         520     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:20] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__CRM_DIRECTION=INBOUND") in new stack
  521.         521     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:21] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__CRM_SOURCE=anonymous") in new stack
  522.         522     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:22] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__CRM_LINKEDID=1585973723.7669") in new stack
  523.         523     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:23] AGI("SIP/SIPTRUNKPROVIDER-00000b04", "sangomacrm.agi,true") in new stack
  524.         524     [2020-04-03 21:15:23] VERBOSE[9049][C-00001ce9] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  525.         525     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] res_agi.c: sangomacrm.agi,true: LINKEDID: 1585973723.7669
  526.         526     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] res_agi.c: sangomacrm.agi,true: SOURCE: anonymous
  527.         527     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] res_agi.c: sangomacrm.agi,true: DESTINATION:
  528.         528     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] res_agi.c: sangomacrm.agi,true: DIRECTION: INBOUND
  529.         529     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] res_agi.c: sangomacrm.agi,true: EXTTOCALL:
  530.         530     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] res_agi.c: sangomacrm.agi,true: START
  531.         531     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] res_agi.c: sangomacrm.agi,true: SCRIPT: php /var/www/html/admin/modules/sangomacrm/importOne.php 'eyJ1dWlkIjoiMTU4NTk3MzcyMy43NjY5Iiwic291cmNlIjoiYW5vbnltb3VzIiwiZGVzdGluYXRpb24iOiIiLCJkaXJlY3Rpb24iOiJJTkJPVU5EIiwidHlwZSI6IlNUQVJUIiwienVsdV9yYXdfdHlwZSI6IiIsInp1bHVfdHlwZSI6IiIsInp1bHVfdXJsIjoiIiwiZXh0dG9jYWxsIjoiIiwiY251bSI6ImFub255bW91cyIsImNuYW0iOiJhbm9ueW1vdXMiLCJjYWxscG9wIjp0cnVlLCJ2b2ljZW1haWwiOiIifQ==' > /dev/null 2>&1 &
  532.         532     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] res_agi.c: <SIP/SIPTRUNKPROVIDER-00000b04>AGI Script sangomacrm.agi completed, returning 0
  533.         533     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:24] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  534.         534     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [1EXT29@from-trunk:25] Goto("SIP/SIPTRUNKPROVIDER-00000b04", "from-did-direct,EXT29,1") in new stack
  535.         535     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (from-did-direct,EXT29,1)
  536.         536     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [EXT29@from-did-direct:1] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?ext-local,EXT29,1:followme-check,EXT29,1") in new stack
  537.         537     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (ext-local,EXT29,1)
  538.         538     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [EXT29@ext-local:1] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__RINGTIMER=15") in new stack
  539.         539     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [EXT29@ext-local:2] Macro("SIP/SIPTRUNKPROVIDER-00000b04", "exten-vm,novm,EXT29,0,0,0") in new stack
  540.         540     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-exten-vm:1] Macro("SIP/SIPTRUNKPROVIDER-00000b04", "user-callerid,") in new stack
  541.         541     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/SIPTRUNKPROVIDER-00000b04", "TOUCH_MONITOR=1585973723.7669") in new stack
  542.         542     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/SIPTRUNKPROVIDER-00000b04", "AMPUSER=anonymous") in new stack
  543.         543     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?report") in new stack
  544.         544     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?Set(REALCALLERIDNUM=anonymous)") in new stack
  545.         545     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/SIPTRUNKPROVIDER-00000b04", "AMPUSER=") in new stack
  546.         546     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?limit") in new stack
  547.         547     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/SIPTRUNKPROVIDER-00000b04", "AMPUSERCIDNAME=") in new stack
  548.         548     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:8] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  549.         549     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:9] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?report") in new stack
  550.         550     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (macro-user-callerid,s,17)
  551.         551     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:17] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "Macro Depth is 2") in new stack
  552.         552     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?report2:macroerror") in new stack
  553.         553     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (macro-user-callerid,s,19)
  554.         554     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?continue") in new stack
  555.         555     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:20] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
  556.         556     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:21] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__TTL=64") in new stack
  557.         557     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:22] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?continue") in new stack
  558.         558     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (macro-user-callerid,s,38)
  559.         559     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:38] Set("SIP/SIPTRUNKPROVIDER-00000b04", "CALLERID(number)=anonymous") in new stack
  560.         560     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:39] Set("SIP/SIPTRUNKPROVIDER-00000b04", "CALLERID(name)=anonymous") in new stack
  561.         561     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:40] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?cnum") in new stack
  562.         562     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:41] Set("SIP/SIPTRUNKPROVIDER-00000b04", "CDR(cnam)=anonymous") in new stack
  563.         563     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:42] Set("SIP/SIPTRUNKPROVIDER-00000b04", "CDR(cnum)=anonymous") in new stack
  564.         564     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:43] Set("SIP/SIPTRUNKPROVIDER-00000b04", "CHANNEL(language)=en") in new stack
  565.         565     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:44] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(CALLERID(name)=)") in new stack
  566.         566     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-user-callerid:45] GosubIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?app-check-classofservce,s,1()") in new stack
  567.         567     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-exten-vm:2] Set("SIP/SIPTRUNKPROVIDER-00000b04", "RingGroupMethod=none") in new stack
  568.         568     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-exten-vm:3] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__EXTTOCALL=EXT29") in new stack
  569.         569     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-exten-vm:4] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__PICKUPMARK=EXT29") in new stack
  570.         570     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-exten-vm:5] Set("SIP/SIPTRUNKPROVIDER-00000b04", "RT=") in new stack
  571.         571     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-exten-vm:6] Gosub("SIP/SIPTRUNKPROVIDER-00000b04", "sub-record-check,s,1(exten,EXT29,dontcare)") in new stack
  572.         572     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "9?initialized") in new stack
  573.         573     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (sub-record-check,s,10)
  574.         574     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "Recordings initialized") in new stack
  575.         575     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(ARG3=dontcare)") in new stack
  576.         576     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:12] Set("SIP/SIPTRUNKPROVIDER-00000b04", "REC_POLICY_MODE_SAVE=") in new stack
  577.         577     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(REC_STATUS=NO)") in new stack
  578.         578     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "5?checkaction") in new stack
  579.         579     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (sub-record-check,s,17)
  580.         580     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?sub-record-check,exten,1") in new stack
  581.         581     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (sub-record-check,exten,1)
  582.         582     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "Exten Recording Check between anonymous and EXT29") in new stack
  583.         583     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/SIPTRUNKPROVIDER-00000b04", "CALLTYPE=external") in new stack
  584.         584     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(CALLTYPE=)") in new stack
  585.         585     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/SIPTRUNKPROVIDER-00000b04", "CALLEE=dontcare") in new stack
  586.         586     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(CALLEE=dontcare)") in new stack
  587.         587     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?callee") in new stack
  588.         588     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (sub-record-check,exten,11)
  589.         589     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [exten@sub-record-check:11] Gosub("SIP/SIPTRUNKPROVIDER-00000b04", "recordcheck,1(dontcare,external,EXT29)") in new stack
  590.         590     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "Starting recording check against dontcare") in new stack
  591.         591     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/SIPTRUNKPROVIDER-00000b04", "dontcare") in new stack
  592.         592     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  593.         593     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/SIPTRUNKPROVIDER-00000b04", "") in new stack
  594.         594     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [exten@sub-record-check:12] Return("SIP/SIPTRUNKPROVIDER-00000b04", "") in new stack
  595.         595     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-exten-vm:7] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?macrodial") in new stack
  596.         596     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (macro-exten-vm,s,13)
  597.         597     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-exten-vm:13] GosubIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?clrheader,1()") in new stack
  598.         598     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-exten-vm:14] Macro("SIP/SIPTRUNKPROVIDER-00000b04", "dial-one,,HhTtr,EXT29") in new stack
  599.         599     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:1] Set("SIP/SIPTRUNKPROVIDER-00000b04", "DEXTEN=EXT29") in new stack
  600.         600     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:2] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__CRM_SOURCE=anonymous") in new stack
  601.         601     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:3] UserEvent("SIP/SIPTRUNKPROVIDER-00000b04", "zulu-inbound-call,type:macro-dial-one,from:anonymous,to:EXT29") in new stack
  602.         602     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:4] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(__EXTTOCALL=EXT29)") in new stack
  603.         603     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:5] Set("SIP/SIPTRUNKPROVIDER-00000b04", "DIALSTATUS_CW=") in new stack
  604.         604     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:6] GosubIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?screen,1()") in new stack
  605.         605     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:7] GosubIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?cf,1()") in new stack
  606.         606     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:8] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?skip1") in new stack
  607.         607     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (macro-dial-one,s,11)
  608.         608     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:11] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?nodial") in new stack
  609.         609     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:12] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?continue") in new stack
  610.         610     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:13] Set("SIP/SIPTRUNKPROVIDER-00000b04", "EXTHASCW=ENABLED") in new stack
  611.         611     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:14] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?next1:cwinusebusy") in new stack
  612.         612     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (macro-dial-one,s,26)
  613.         613     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:26] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?next3:continue") in new stack
  614.         614     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (macro-dial-one,s,28)
  615.         615     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:28] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?nodial") in new stack
  616.         616     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:29] GosubIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?dstring,1():dlocal,1()") in new stack
  617.         617     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:1] Set("SIP/SIPTRUNKPROVIDER-00000b04", "DSTRING=") in new stack
  618.         618     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:2] Set("SIP/SIPTRUNKPROVIDER-00000b04", "DEVICES=EXT29") in new stack
  619.         619     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Return()") in new stack
  620.         620     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(DEVICES=197811129)") in new stack
  621.         621     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:5] Set("SIP/SIPTRUNKPROVIDER-00000b04", "LOOPCNT=1") in new stack
  622.         622     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:6] Set("SIP/SIPTRUNKPROVIDER-00000b04", "ITER=1") in new stack
  623.         623     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:7] Set("SIP/SIPTRUNKPROVIDER-00000b04", "THISDIAL=PJSIP/EXT29") in new stack
  624.         624     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?zap2dahdi,1()") in new stack
  625.         625     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Return()") in new stack
  626.         626     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("SIP/SIPTRUNKPROVIDER-00000b04", "NEWDIAL=") in new stack
  627.         627     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("SIP/SIPTRUNKPROVIDER-00000b04", "LOOPCNT2=1") in new stack
  628.         628     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("SIP/SIPTRUNKPROVIDER-00000b04", "ITER2=1") in new stack
  629.         629     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("SIP/SIPTRUNKPROVIDER-00000b04", "THISPART2=PJSIP/EXT29") in new stack
  630.         630     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(THISPART2=DAHDIIP/EXT29)") in new stack
  631.         631     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("SIP/SIPTRUNKPROVIDER-00000b04", "NEWDIAL=PJSIP/EXT29&") in new stack
  632.         632     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("SIP/SIPTRUNKPROVIDER-00000b04", "ITER2=2") in new stack
  633.         633     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?begin2") in new stack
  634.         634     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("SIP/SIPTRUNKPROVIDER-00000b04", "THISDIAL=PJSIP/EXT29") in new stack
  635.         635     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("SIP/SIPTRUNKPROVIDER-00000b04", "") in new stack
  636.         636     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?docheck") in new stack
  637.         637     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:10] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "Debug: Found PJSIP Destination PJSIP/EXT29") in new stack
  638.         638     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:11] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?doset") in new stack
  639.         639     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:12] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS") in new stack
  640.         640     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:13] Set("SIP/SIPTRUNKPROVIDER-00000b04", "THISDIAL=PJSIP/EXT29/sip:EXT29@PUBLICIPOFEXTENSION:5060") in new stack
  641.         641     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:14] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(DIALSTATUS=CHANUNAVAIL)") in new stack
  642.         642     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?skipset") in new stack
  643.         643     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:16] Set("SIP/SIPTRUNKPROVIDER-00000b04", "DSTRING=PJSIP/EXT29/sip:EXT29@PUBLICIPOFEXTENSION:5060&") in new stack
  644.         644     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:17] Set("SIP/SIPTRUNKPROVIDER-00000b04", "ITER=2") in new stack
  645.         645     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?begin") in new stack
  646.         646     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Return()") in new stack
  647.         647     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:20] Set("SIP/SIPTRUNKPROVIDER-00000b04", "DSTRING=PJSIP/EXT29/sip:EXT29@PUBLICIPOFEXTENSION:5060") in new stack
  648.         648     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [dstring@macro-dial-one:21] Return("SIP/SIPTRUNKPROVIDER-00000b04", "") in new stack
  649.         649     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:30] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?nodial") in new stack
  650.         650     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:31] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?skiptrace") in new stack
  651.         651     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:32] GosubIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?ctset,1():ctclear,1()") in new stack
  652.         652     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [ctclear@macro-dial-one:1] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "Deleting: CALLTRACE/EXT29 ") in new stack
  653.         653     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [ctclear@macro-dial-one:2] Return("SIP/SIPTRUNKPROVIDER-00000b04", "") in new stack
  654.         654     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:33] Set("SIP/SIPTRUNKPROVIDER-00000b04", "D_OPTIONS=HhTtr") in new stack
  655.         655     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:34] GosubIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
  656.         656     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:35] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
  657.         657     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:36] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(ALERT_INFO=)") in new stack
  658.         658     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:37] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(ALERT_INFO=)") in new stack
  659.         659     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:38] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(ALERT_INFO=)") in new stack
  660.         660     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:39] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
  661.         661     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:40] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
  662.         662     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:41] GosubIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
  663.         663     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:42] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(CHANNEL(musicclass)=)") in new stack
  664.         664     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:43] GosubIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?qwait,1()") in new stack
  665.         665     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:44] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__CWIGNORE=") in new stack
  666.         666     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:45] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__KEEPCID=TRUE") in new stack
  667.         667     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:46] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?usegoto,1") in new stack
  668.         668     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:47] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?godial") in new stack
  669.         669     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (macro-dial-one,s,52)
  670.         670     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:52] Macro("SIP/SIPTRUNKPROVIDER-00000b04", "dialout-one-predial-hook,") in new stack
  671.         671     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/SIPTRUNKPROVIDER-00000b04", "") in new stack
  672.         672     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:53] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?Set(D_OPTIONS=HhtrI)") in new stack
  673.         673     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:54] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__ZULU_TIMEOUT=15") in new stack
  674.         674     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:55] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__ZULU_FROMEXTEN=anonymous") in new stack
  675.         675     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:56] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__ZULU_DIALOPTS=HhTtr") in new stack
  676.         676     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:57] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "Finished") in new stack
  677.         677     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:58] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "") in new stack
  678.         678     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:59] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(D_OPTIONS=HhtrIg)") in new stack
  679.         679     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-dial-one:60] Dial("SIP/SIPTRUNKPROVIDER-00000b04", "PJSIP/EXT29/sip:EXT29@PUBLICIPOFEXTENSION:5060,,HhtrIb(func-apply-sipheaders^s^1)") in new stack
  680.         680     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] app_stack.c: PJSIP/EXT29-00001221 Internal Gosub(func-apply-sipheaders,s,1) start
  681.         681     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/EXT29-00001221", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  682.         682     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/EXT29-00001221", "Applying SIP Headers to channel PJSIP/EXT29-00001221") in new stack
  683.         683     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/EXT29-00001221", "TECH=PJSIP") in new stack
  684.         684     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/EXT29-00001221", "SIPHEADERKEYS=") in new stack
  685.         685     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@func-apply-sipheaders:5] While("PJSIP/EXT29-00001221", "0") in new stack
  686.         686     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] app_while.c: Jumping to priority 13
  687.         687     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@func-apply-sipheaders:14] Return("PJSIP/EXT29-00001221", "") in new stack
  688.         688     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] app_stack.c: Spawn extension (from-internal, EXT29, 1) exited non-zero on 'PJSIP/EXT29-00001221'
  689.         689     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] app_stack.c: PJSIP/EXT29-00001221 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  690.         690     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] app_dial.c: Called PJSIP/EXT29/sip:EXT29@PUBLICIPOFEXTENSION:5060
  691.         691     [2020-04-03 21:15:24] VERBOSE[19900] netsock2.c: Using SIP RTP Audio TOS bits 184
  692.         692     [2020-04-03 21:15:24] VERBOSE[19900] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
  693.         693     [2020-04-03 21:15:24] VERBOSE[19900] netsock2.c: Using SIP RTP Audio CoS mark 5
  694.         694     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] app_dial.c: Connected line update to SIP/SIPTRUNKPROVIDER-00000b04 prevented.
  695.         695     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] app_dial.c: Connected line update to SIP/SIPTRUNKPROVIDER-00000b04 prevented.
  696.         696     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] app_dial.c: PJSIP/EXT29-00001221 is ringing
  697.         697     [2020-04-03 21:15:24] VERBOSE[9049][C-00001ce9] app_dial.c: PJSIP/EXT29-00001221 is ringing
  698.         698     [2020-04-03 21:15:25] VERBOSE[9049][C-00001ce9] app_dial.c: PJSIP/EXT29-00001221 answered SIP/SIPTRUNKPROVIDER-00000b04
  699.         699     [2020-04-03 21:15:25] VERBOSE[9148][C-00001ce9] bridge_channel.c: Channel PJSIP/EXT29-00001221 joined 'simple_bridge' basic-bridge <3cdf6876-695d-4be6-b0eb-992728be731e>
  700.         700     [2020-04-03 21:15:25] VERBOSE[9049][C-00001ce9] bridge_channel.c: Channel SIP/SIPTRUNKPROVIDER-00000b04 joined 'simple_bridge' basic-bridge <3cdf6876-695d-4be6-b0eb-992728be731e>
  701.         701     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] bridge_channel.c: Channel SIP/SIPTRUNKPROVIDER-00000b04 left 'simple_bridge' basic-bridge <3cdf6876-695d-4be6-b0eb-992728be731e>
  702.         702     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] app_macro.c: Spawn extension (macro-dial-one, s, 60) exited non-zero on 'SIP/SIPTRUNKPROVIDER-00000b04' in macro 'dial-one'
  703.         703     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] app_macro.c: Spawn extension (macro-exten-vm, s, 14) exited non-zero on 'SIP/SIPTRUNKPROVIDER-00000b04' in macro 'exten-vm'
  704.         704     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx.c: Spawn extension (ext-local, EXT29, 2) exited non-zero on 'SIP/SIPTRUNKPROVIDER-00000b04'
  705.         705     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx.c: Executing [h@ext-local:1] Macro("SIP/SIPTRUNKPROVIDER-00000b04", "hangupcall,") in new stack
  706.         706     [2020-04-03 21:15:59] VERBOSE[9148][C-00001ce9] bridge_channel.c: Channel PJSIP/EXT29-00001221 left 'simple_bridge' basic-bridge <3cdf6876-695d-4be6-b0eb-992728be731e>
  707.         707     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?theend") in new stack
  708.         708     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  709.         709     [2020-04-03 21:15:59] VERBOSE[9148][C-00001ce9] app_stack.c: PJSIP/EXT29-00001221 Internal Gosub(crm-hangup,s,1) start
  710.         710     [2020-04-03 21:15:59] VERBOSE[9148][C-00001ce9] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/EXT29-00001221", "Sending Hangup to CRM") in new stack
  711.         711     [2020-04-03 21:15:59] VERBOSE[9148][C-00001ce9] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/EXT29-00001221", "HANGUP CAUSE: 16") in new stack
  712.         712     [2020-04-03 21:15:59] VERBOSE[9148][C-00001ce9] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/EXT29-00001221", "0?Set(__CRM_VOICEMAIL=)") in new stack
  713.         713     [2020-04-03 21:15:59] VERBOSE[9148][C-00001ce9] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/EXT29-00001221", "MASTER CHANNEL: 1585973724.7670 = 1585973723.7669") in new stack
  714.         714     [2020-04-03 21:15:59] VERBOSE[9148][C-00001ce9] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/EXT29-00001221", "1?return") in new stack
  715.         715     [2020-04-03 21:15:59] VERBOSE[9148][C-00001ce9] pbx_builtins.c: Goto (crm-hangup,s,8)
  716.         716     [2020-04-03 21:15:59] VERBOSE[9148][C-00001ce9] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/EXT29-00001221", "") in new stack
  717.         717     [2020-04-03 21:15:59] VERBOSE[9148][C-00001ce9] app_stack.c: Spawn extension (from-internal, , 1) exited non-zero on 'PJSIP/EXT29-00001221'
  718.         718     [2020-04-03 21:15:59] VERBOSE[9148][C-00001ce9] app_stack.c: PJSIP/EXT29-00001221 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  719.         719     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(CDR(recordingfile)=)") in new stack
  720.         720     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "PJSIP/EXT29-00001221 montior file= ") in new stack
  721.         721     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "1?skipagi") in new stack
  722.         722     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx_builtins.c: Goto (macro-hangupcall,s,7)
  723.         723     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/SIPTRUNKPROVIDER-00000b04", "") in new stack
  724.         724     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/SIPTRUNKPROVIDER-00000b04' in macro 'hangupcall'
  725.         725     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/SIPTRUNKPROVIDER-00000b04'
  726.         726     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] app_stack.c: SIP/SIPTRUNKPROVIDER-00000b04 Internal Gosub(crm-hangup,s,1) start
  727.         727     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "Sending Hangup to CRM") in new stack
  728.         728     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "HANGUP CAUSE: 16") in new stack
  729.         729     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?Set(__CRM_VOICEMAIL=)") in new stack
  730.         730     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/SIPTRUNKPROVIDER-00000b04", "MASTER CHANNEL: 1585973723.7669 = 1585973723.7669") in new stack
  731.         731     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/SIPTRUNKPROVIDER-00000b04", "0?return") in new stack
  732.         732     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@crm-hangup:6] Set("SIP/SIPTRUNKPROVIDER-00000b04", "__CRM_HANGUP=1") in new stack
  733.         733     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/SIPTRUNKPROVIDER-00000b04", "sangomacrm.agi") in new stack
  734.         734     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  735.         735     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] res_agi.c: sangomacrm.agi: LINKEDID: 1585973723.7669
  736.         736     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] res_agi.c: sangomacrm.agi: SOURCE: anonymous
  737.         737     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] res_agi.c: sangomacrm.agi: DESTINATION:
  738.         738     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] res_agi.c: sangomacrm.agi: DIRECTION: INBOUND
  739.         739     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] res_agi.c: sangomacrm.agi: EXTTOCALL: EXT29
  740.         740     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] res_agi.c: sangomacrm.agi: START
  741.         741     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] res_agi.c: sangomacrm.agi: SCRIPT: php /var/www/html/admin/modules/sangomacrm/importOne.php 'eyJ1dWlkIjoiMTU4NTk3MzcyMy43NjY5Iiwic291cmNlIjoiYW5vbnltb3VzIiwiZGVzdGluYXRpb24iOiIiLCJkaXJlY3Rpb24iOiJJTkJPVU5EIiwidHlwZSI6IkVORCIsInp1bHVfcmF3X3R5cGUiOiIiLCJ6dWx1X3R5cGUiOiIiLCJ6dWx1X3VybCI6IiIsImV4dHRvY2FsbCI6IjYxOTc4MTExMjkiLCJjbnVtIjoiYW5vbnltb3VzIiwiY25hbSI6ImFub255bW91cyIsImNhbGxwb3AiOmZhbHNlLCJ2b2ljZW1haWwiOiIifQ==' > /dev/null 2>&1 &
  742.         742     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] res_agi.c: <SIP/SIPTRUNKPROVIDER-00000b04>AGI Script sangomacrm.agi completed, returning 0
  743.         743     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] pbx.c: Executing [s@crm-hangup:8] Return("SIP/SIPTRUNKPROVIDER-00000b04", "") in new stack
  744.         744     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] app_stack.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/SIPTRUNKPROVIDER-00000b04'
  745.         745     [2020-04-03 21:15:59] VERBOSE[9049][C-00001ce9] app_stack.c: SIP/SIPTRUNKPROVIDER-00000b04 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=

Reply to "911 Log"

Here you can reply to the paste above

captcha