2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@zapinbound:1] Set("DAHDI/52-1", "CALLERID(num)=01284755621") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@zapinbound:2] NoOp("DAHDI/52-1", "CALLERID=01284755621") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@zapinbound:3] Set("DAHDI/52-1", "ingress_type=pstn") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@zapinbound:4] Set("DAHDI/52-1", "sipstatus="magrathea1=OK (1 ms),magrathea2=OK (1 ms)"") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@zapinbound:5] Goto("DAHDI/52-1", "skycom_master,08000125245,1") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Goto (skycom_master,08000125245,1)
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@skycom_master:1] AGI("DAHDI/52-1", "skycom_master,08000125245") in new stack
2011-07-28 14:54:10 | res_agi.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/skycom_master
2011-07-28 14:54:10 | res_agi.c:     -- <dahdi 52-1="">AGI Script skycom_master completed, returning 0
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@skycom_master:2] NoOp("DAHDI/52-1", "service_nts_nextgen_v2") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@skycom_master:3] Goto("DAHDI/52-1", "service_nts_nextgen_v2,08000125245,1") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Goto (service_nts_nextgen_v2,08000125245,1)
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:1] Set("DAHDI/52-1", "__channel1=1311861250.12137_6") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:2] Set("DAHDI/52-1", "__CALLSTATUS=NODIAL") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:3] Set("DAHDI/52-1", "__num_hangups=0") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:4] AGI("DAHDI/52-1", "agi://127.0.0.1:4573/ServiceNTSNextgenV2,mode=start,uniqueid=1311861250.12137_6,callerid=01284755621,dnid=08000125245,channel=DAHDI/52-1,callingpres=1,intype=pstn,Goto=AGIStartOK,now=1311861250,sipstatus="magrathea1=OK (1 ms),magrathea2=OK (1 ms)"") in new stack
2011-07-28 14:54:10 | res_agi.c:     -- AGI Script Executing Application: (Goto) Options: (AGIStartOK)
2011-07-28 14:54:10 | pbx.c:     -- Goto (service_nts_nextgen_v2,08000125245,7)
2011-07-28 14:54:10 | res_agi.c:     -- <dahdi 52-1="">AGI Script agi://127.0.0.1:4573/ServiceNTSNextgenV2 completed, returning 0
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:7] Set("DAHDI/52-1", "__connect_epoch=0") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:8] Set("DAHDI/52-1", "__egress_start_epoch=0") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:9] Set("DAHDI/52-1", "recowner=callee") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:10] Set("DAHDI/52-1", "servicetype=nts") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:11] Set("DAHDI/52-1", "CallCLIorig=01284755621") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:12] Set("DAHDI/52-1", "CallCLItoPresent=01284755621") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:13] Set("DAHDI/52-1", "__CPresOrig=1") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:14] NoOp("DAHDI/52-1", "DNIASCLI="0"") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:15] GotoIf("DAHDI/52-1", "1?ida_jumpin") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Goto (service_nts_nextgen_v2,08000125245,18)
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:18] NoOp("DAHDI/52-1", "") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:19] Set("DAHDI/52-1", "__channel1=1311861250.12137_6") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:20] GotoIf("DAHDI/52-1", "1?end_callerprewhisper") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Goto (service_nts_nextgen_v2,08000125245,22)
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:22] GotoIf("DAHDI/52-1", "1?end_callerpostwhisper") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Goto (service_nts_nextgen_v2,08000125245,26)
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:26] NoOp("DAHDI/52-1", "") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:27] Set("DAHDI/52-1", "__busy_count=0") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:28] GotoIf("DAHDI/52-1", "0?t,busy") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:29] Set("DAHDI/52-1", "__busy_count=0") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:30] Set("DAHDI/52-1", "__cur_item=1") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:31] Set("DAHDI/52-1", "__phone_number=08000125245") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:32] GotoIf("DAHDI/52-1", "0?route2number") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:33] GotoIf("DAHDI/52-1", "0?t,busy") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:34] GotoIf("DAHDI/52-1", "0?t,voicemail") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:35] GotoIf("DAHDI/52-1", "0?t,announcement") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:36] GotoIf("DAHDI/52-1", "0?service_nts_nextgen_v2_ivr,ivr08000125245,1") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:37] GotoIf("DAHDI/52-1", "1?service_nts_nextgen_v2_ntsida,ida08000125245,1") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Goto (service_nts_nextgen_v2_ntsida,ida08000125245,1)
2011-07-28 14:54:10 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:1] Set("DAHDI/52-1", "CLI_in=01284755621") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:2] Set("DAHDI/52-1", "ida_caller_validity=0") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:3] NoOp("DAHDI/52-1", "Required caller validity is 2") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:4] Set("DAHDI/52-1", "ida_entered_PIN=") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:5] Set("DAHDI/52-1", "ida_badPINs=") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:6] Set("DAHDI/52-1", "ida_reject_reason=") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:7] Set("DAHDI/52-1", "TIMEOUT(response)=3") in new stack
2011-07-28 14:54:10 | func_timeout.c:     -- Response timeout set to 3.000
2011-07-28 14:54:10 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:8] Set("DAHDI/52-1", "TIMEOUT(digit)=3") in new stack
2011-07-28 14:54:10 | func_timeout.c:     -- Digit timeout set to 3.000
2011-07-28 14:54:10 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:9] Answer("DAHDI/52-1", "") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:10] GotoIf("DAHDI/52-1", "0?12") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:11] Set("DAHDI/52-1", "__connect_epoch=1311861250") in new stack
2011-07-28 14:54:10 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:12] ExecIf("DAHDI/52-1", "1?Playback(ida/connect-sound)") in new stack
2011-07-28 14:54:10 | file.c:     -- <dahdi 52-1=""> Playing 'ida/connect-sound.slin' (language 'en')
2011-07-28 14:54:11 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:13] GotoIf("DAHDI/52-1", "0?dialtone") in new stack
2011-07-28 14:54:11 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:14] GotoIf("DAHDI/52-1", "0?no_matching_CLI") in new stack
2011-07-28 14:54:11 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:15] GotoIf("DAHDI/52-1", "0?no_matching_CLI") in new stack
2011-07-28 14:54:11 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:16] NoOp("DAHDI/52-1", "CLI matched one of the allowed list") in new stack
2011-07-28 14:54:11 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:17] Set("DAHDI/52-1", "ida_caller_validity=1") in new stack
2011-07-28 14:54:11 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:18] Goto("DAHDI/52-1", "check_PIN") in new stack
2011-07-28 14:54:11 | pbx.c:     -- Goto (service_nts_nextgen_v2_ntsida,ida08000125245,24)
2011-07-28 14:54:11 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:24] NoOp("DAHDI/52-1", "Checking PIN") in new stack
2011-07-28 14:54:11 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:25] GotoIf("DAHDI/52-1", "0?dialtone") in new stack
2011-07-28 14:54:11 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:26] Set("DAHDI/52-1", "ida_PIN_entry_attempts=0") in new stack
2011-07-28 14:54:11 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:27] GotoIf("DAHDI/52-1", "1?prompt_for_PIN") in new stack
2011-07-28 14:54:11 | pbx.c:     -- Goto (service_nts_nextgen_v2_ntsida,ida08000125245,30)
2011-07-28 14:54:11 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:30] NoOp("DAHDI/52-1", "Prompting and reading digits") in new stack
2011-07-28 14:54:11 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:31] Set("DAHDI/52-1", "__ida_background_interrupt_returnpoint=ida_foreground_read_PIN") in new stack
2011-07-28 14:54:11 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:32] Set("DAHDI/52-1", "entered_digits=") in new stack
2011-07-28 14:54:11 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:33] ExecIf("DAHDI/52-1", "0?Playback()") in new stack
2011-07-28 14:54:11 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:34] GotoIf("DAHDI/52-1", "0?ida_foreground_read_PIN") in new stack
2011-07-28 14:54:11 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:35] BackGround("DAHDI/52-1", "ida/enter-your-pin") in new stack
2011-07-28 14:54:11 | file.c:     -- <dahdi 52-1=""> Playing 'ida/enter-your-pin.slin' (language 'en')
2011-07-28 14:54:13 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:36] NoOp("DAHDI/52-1", "doing foreground PIN read - digits entered=") in new stack
2011-07-28 14:54:13 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:37] Set("DAHDI/52-1", "entered_digits=") in new stack
2011-07-28 14:54:13 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:38] Set("DAHDI/52-1", "num_digits_entered=0") in new stack
2011-07-28 14:54:13 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:39] GotoIf("DAHDI/52-1", "0?ida08000125245,check_entered_PIN") in new stack
2011-07-28 14:54:13 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:40] Set("DAHDI/52-1", "max_digits_remaining=4") in new stack
2011-07-28 14:54:13 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:41] NoOp("DAHDI/52-1", "TIME NOW=1311861253") in new stack
2011-07-28 14:54:13 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:42] ExecIf("DAHDI/52-1", "1?Set(ida_last_input_tstamp=1311861253)") in new stack
2011-07-28 14:54:13 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:43] Read("DAHDI/52-1", "new_digits,,4") in new stack
2011-07-28 14:54:13 | app_read.c:     -- Accepting a maximum of 4 digits.
2011-07-28 14:54:14 | app_read.c:     -- User entered '3987'
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:44] GotoIf("DAHDI/52-1", "1?GotSomeDigitsPIN") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Goto (service_nts_nextgen_v2_ntsida,ida08000125245,47)
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:47] Set("DAHDI/52-1", "entered_digits=3987") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:48] Set("DAHDI/52-1", "ida_last_input_tstamp=1311861254") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:49] Goto("DAHDI/52-1", "ida_foreground_read_PIN") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Goto (service_nts_nextgen_v2_ntsida,ida08000125245,36)
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:36] NoOp("DAHDI/52-1", "doing foreground PIN read - digits entered=3987") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:37] Set("DAHDI/52-1", "entered_digits=3987") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:38] Set("DAHDI/52-1", "num_digits_entered=4") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:39] GotoIf("DAHDI/52-1", "1?ida08000125245,check_entered_PIN") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Goto (service_nts_nextgen_v2_ntsida,ida08000125245,50)
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:50] NoOp("DAHDI/52-1", "checking entered pin 3987") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:51] GotoIf("DAHDI/52-1", "0?54") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:52] Set("DAHDI/52-1", "ida_entered_PIN=3987") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:53] Goto("DAHDI/52-1", "dialtone") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Goto (service_nts_nextgen_v2_ntsida,ida08000125245,61)
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:61] NoOp("DAHDI/52-1", "Presenting dialtone") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:62] GotoIf("DAHDI/52-1", "0?65") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:63] Set("DAHDI/52-1", "CALLERPRES()=allowed_passed_screen") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:64] Set("DAHDI/52-1", "CallCLItoPresent=08446492660") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:65] Set("DAHDI/52-1", "__ida_background_interrupt_returnpoint=ida_foreground_read_DDI") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:66] Set("DAHDI/52-1", "entered_digits=") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:67] ExecIf("DAHDI/52-1", "0?Playback()") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:68] GotoIf("DAHDI/52-1", "0?ida_foreground_read_DDI") in new stack
2011-07-28 14:54:14 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:69] BackGround("DAHDI/52-1", "ida/dialling-prompt") in new stack
2011-07-28 14:54:14 | file.c:     -- <dahdi 52-1=""> Playing 'ida/dialling-prompt.slin' (language 'en')
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:70] Set("DAHDI/52-1", "ida_last_input_tstamp=1311861257") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:71] NoOp("DAHDI/52-1", "doing foreground DDI read - digits entered=") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:72] Set("DAHDI/52-1", "entered_digits=") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:73] Set("DAHDI/52-1", "num_digits_entered=0") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:74] GotoIf("DAHDI/52-1", "0?ida08000125245,check_entered_DDI") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:75] Set("DAHDI/52-1", "max_digits_remaining=14") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:76] GotoIf("DAHDI/52-1", "0?ida_read_remaining_DDI") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:77] PlayTones("DAHDI/52-1", "dial") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:78] Read("DAHDI/52-1", "entered_digits,,1,,,180") in new stack
2011-07-28 14:54:17 | app_read.c:     -- Accepting a maximum of 1 digits.
2011-07-28 14:54:17 | app_read.c:     -- User entered '0'
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:79] StopPlayTones("DAHDI/52-1", "") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:80] ExecIf("DAHDI/52-1", "0?Hangup") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:81] Set("DAHDI/52-1", "ida_last_input_tstamp=1311861257") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:82] Goto("DAHDI/52-1", "ida_foreground_read_DDI") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Goto (service_nts_nextgen_v2_ntsida,ida08000125245,71)
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:71] NoOp("DAHDI/52-1", "doing foreground DDI read - digits entered=0") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:72] Set("DAHDI/52-1", "entered_digits=0") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:73] Set("DAHDI/52-1", "num_digits_entered=1") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:74] GotoIf("DAHDI/52-1", "0?ida08000125245,check_entered_DDI") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:75] Set("DAHDI/52-1", "max_digits_remaining=13") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:76] GotoIf("DAHDI/52-1", "1?ida_read_remaining_DDI") in new stack
2011-07-28 14:54:17 | pbx.c:     -- Goto (service_nts_nextgen_v2_ntsida,ida08000125245,83)
2011-07-28 14:54:17 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:83] Read("DAHDI/52-1", "new_digits,,13,,,3") in new stack
2011-07-28 14:54:17 | app_read.c:     -- Accepting a maximum of 13 digits.
2011-07-28 14:54:26 | app_read.c:     -- User entered '116254661'
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:84] GotoIf("DAHDI/52-1", "1?GotSomeDigitsDDI") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Goto (service_nts_nextgen_v2_ntsida,ida08000125245,87)
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:87] Set("DAHDI/52-1", "entered_digits=0116254661") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:88] Set("DAHDI/52-1", "ida_last_input_tstamp=1311861266") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:89] Goto("DAHDI/52-1", "ida_foreground_read_DDI") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Goto (service_nts_nextgen_v2_ntsida,ida08000125245,71)
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:71] NoOp("DAHDI/52-1", "doing foreground DDI read - digits entered=0116254661") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:72] Set("DAHDI/52-1", "entered_digits=0116254661") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:73] Set("DAHDI/52-1", "num_digits_entered=10") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:74] GotoIf("DAHDI/52-1", "1?ida08000125245,check_entered_DDI") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Goto (service_nts_nextgen_v2_ntsida,ida08000125245,90)
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:90] NoOp("DAHDI/52-1", "Checking entered DDI 0116254661 check regex="^(01|02|03|080|0870|075|076|077|078|079)"") in new stack
2011-07-28 14:54:26 | pbx.c: The application delimiter is now the comma, not the pipe.  Did you forget to convert your dialplan?  (NoOp(Checking entered DDI 0116254661 check regex="^(01|02|03|080|0870|075|076|077|078|079)"))
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:91] Set("DAHDI/52-1", "ida_ddi=0116254661") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:92] GotoIf("DAHDI/52-1", "0?badddi") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:93] GotoIf("DAHDI/52-1", "0?badddi") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:94] GotoIf("DAHDI/52-1", "0?badddi") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:95] AGI("DAHDI/52-1", "agi://127.0.0.1:4573/ServiceNTSNextgenV2,mode=ida_ddi,ddi=0116254661,uniqueID=1311861250.12137_6,now=1311861266,CallFlags=,Goto=agiOK,sipstatus="magrathea1=OK (1 ms),magrathea2=OK (1 ms)"") in new stack
2011-07-28 14:54:26 | res_agi.c:  agi://127.0.0.1:4573/ServiceNTSNextgenV2,mode=ida_ddi,ddi=0116254661,uniqueID=1311861250.12137_6,now=1311861266,CallFlags=,Goto=agiOK,sipstatus="magrathea1=OK (1 ms),magrathea2=OK (1 ms)": Route To Number: 44116254661
2011-07-28 14:54:26 | res_agi.c:     -- AGI Script Executing Application: (Goto) Options: (agiOK)
2011-07-28 14:54:26 | pbx.c:     -- Goto (service_nts_nextgen_v2_ntsida,ida08000125245,98)
2011-07-28 14:54:26 | res_agi.c:     -- <dahdi 52-1="">AGI Script agi://127.0.0.1:4573/ServiceNTSNextgenV2 completed, returning 0
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:98] NoOp("DAHDI/52-1", "fetched ida_ddi, item1=0116254661") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:99] Set("DAHDI/52-1", "__extra_call_params=") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:100] ExecIf("DAHDI/52-1", "1?Set(__extra_call_params=&amp;IDA_PIN=3987)") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:101] ExecIf("DAHDI/52-1", "0?Set(__extra_call_params=&amp;IDA_PIN=3987&amp;IDA_badPINs=)") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:102] Set("DAHDI/52-1", "__extra_call_params=IDA_PIN=3987") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:103] Set("DAHDI/52-1", "recowner=caller") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [ida08000125245@service_nts_nextgen_v2_ntsida:104] Goto("DAHDI/52-1", "service_nts_nextgen_v2,08000125245,jumpback") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Goto (service_nts_nextgen_v2,08000125245,28)
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:28] GotoIf("DAHDI/52-1", "0?t,busy") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:29] Set("DAHDI/52-1", "__busy_count=0") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:30] Set("DAHDI/52-1", "__cur_item=1") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:31] Set("DAHDI/52-1", "__phone_number=08000125245") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:32] GotoIf("DAHDI/52-1", "1?route2number") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Goto (service_nts_nextgen_v2,08000125245,39)
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:39] Set("DAHDI/52-1", "__destination=0116254661") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:40] GotoIf("DAHDI/52-1", "0?skiprecording") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:41] Set("DAHDI/52-1", "recwanted=0") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:42] GotoIf("DAHDI/52-1", "0?calleerecording") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:43] Set("DAHDI/52-1", "DYNAMIC_FEATURES=OutPauseMonitor#OutUnpauseMonitor") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:44] Goto("DAHDI/52-1", "skipdynamic") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Goto (service_nts_nextgen_v2,08000125245,46)
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:46] Set("DAHDI/52-1", "FNAME=callrec_14939_1311861250.12137_6") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:47] Set("DAHDI/52-1", "RECARGS=recargs=basename=callrec_14939_1311861250.12137_6 custid=14939 emailfrom=noreply@calltracks.com emailto= dni=08000125245 start_epoch=1311861266 cli=01284755621 callingpres=1 service=nts") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:48] Monitor("DAHDI/52-1", "wav,callrec_14939_1311861250.12137_6_1311861266,b") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:49] GotoIf("DAHDI/52-1", "0?skiprecording") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:50] Set("DAHDI/52-1", "recwanted=1") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:51] ExecIf("DAHDI/52-1", "1?Set(CALLERPRES()=allowed_not_screened)") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:52] Set("DAHDI/52-1", "__carriernum=1") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:53] NoOp("DAHDI/52-1", "") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:54] ExecIf("DAHDI/52-1", "0?Set(CALLERID(num)=8446492660):Set(CALLERID(num)=08446492660)") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:55] ExecIf("DAHDI/52-1", "1?Set(__egress_start_epoch=1311861266)") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:56] Dial("DAHDI/52-1", "SIP/0116254661@magrathea1,,M(service-nts-nextgenv2-register-answer)") in new stack
2011-07-28 14:54:26 | netsock.c:   == Using SIP RTP CoS mark 5
2011-07-28 14:54:26 | app_dial.c:     -- Called 0116254661@magrathea1
2011-07-28 14:54:26 | app_dial.c:   == Everyone is busy/congested at this time (1:0/0/1)
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:57] NoOp("DAHDI/52-1", "1 1 DIALSTATUS=CHANUNAVAIL") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:58] GotoIf("DAHDI/52-1", "0?hangup") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:59] GotoIf("DAHDI/52-1", "0?service_nts_nextgen_v2_endivr_with_ntsida,ivr08000125245,1") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:60] GotoIf("DAHDI/52-1", "0?t,1") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:61] GotoIf("DAHDI/52-1", "0?t,1") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:62] GotoIf("DAHDI/52-1", "0?t,1") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:63] Set("DAHDI/52-1", "__carriernum=2") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:64] Goto("DAHDI/52-1", "dial") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Goto (service_nts_nextgen_v2,08000125245,53)
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:53] NoOp("DAHDI/52-1", "") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:54] ExecIf("DAHDI/52-1", "1?Set(CALLERID(num)=8446492660):Set(CALLERID(num)=08446492660)") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:55] ExecIf("DAHDI/52-1", "0?Set(__egress_start_epoch=1311861266)") in new stack
2011-07-28 14:54:26 | pbx.c:     -- Executing [08000125245@service_nts_nextgen_v2:56] Dial("DAHDI/52-1", "DAHDI/g1/0116254661,,M(service-nts-nextgenv2-register-answer)") in new stack
2011-07-28 14:54:26 | chan_dahdi.c:     -- Requested transfer capability: 0x00 - SPEECH
2011-07-28 14:54:26 | app_dial.c:     -- Called g1/0116254661
2011-07-28 14:54:26 | app_dial.c:     -- DAHDI/10-1 is proceeding passing it to DAHDI/52-1
2011-07-28 14:54:26 | chan_dahdi.c:     -- Hungup 'DAHDI/10-1'
2011-07-28 14:54:26 | app_dial.c:   == Everyone is busy/congested at this time (1:0/0/1)
2011-07-28 14:54:26 | pbx.c:   == Spawn extension (service_nts_nextgen_v2, 08000125245, 56) exited INCOMPLETE on 'DAHDI/52-1'
2011-07-28 14:54:30 | pbx.c:   == CDR updated on DAHDI/52-1
2011-07-28 14:54:30 | pbx.c:     -- Executing [080001252456@service_nts_nextgen_v2:1] Set("DAHDI/52-1", "__channel1=1311861250.12137_6") in new stack
2011-07-28 14:54:30 | pbx.c:     -- Executing [080001252456@service_nts_nextgen_v2:2] Set("DAHDI/52-1", "__CALLSTATUS=NODIAL") in new stack
2011-07-28 14:54:30 | pbx.c:     -- Executing [080001252456@service_nts_nextgen_v2:3] Set("DAHDI/52-1", "__num_hangups=0") in new stack
2011-07-28 14:54:30 | pbx.c:     -- Executing [080001252456@service_nts_nextgen_v2:4] AGI("DAHDI/52-1", "agi://127.0.0.1:4573/ServiceNTSNextgenV2,mode=start,uniqueid=1311861250.12137_6,callerid=8446492660,dnid=080001252456,channel=DAHDI/52-1,callingpres=0,intype=pstn,Goto=AGIStartOK,now=1311861270,sipstatus="magrathea1=OK (1 ms),magrathea2=OK (1 ms)"") in new stack
2011-07-28 14:54:30 | res_agi.c:     -- <dahdi 52-1="">AGI Script agi://127.0.0.1:4573/ServiceNTSNextgenV2 completed, returning 0
2011-07-28 14:54:30 | pbx.c:     -- Executing [080001252456@DEAD_END_HANGUP:1] Hangup("DAHDI/52-1", "") in new stack
2011-07-28 14:54:30 | pbx.c:   == Spawn extension (DEAD_END_HANGUP, 080001252456, 1) exited non-zero on 'DAHDI/52-1'
2011-07-28 14:54:30 | pbx.c:     -- Executing [h@DEAD_END_HANGUP:1] Hangup("DAHDI/52-1", "") in new stack
2011-07-28 14:54:30 | pbx.c:   == Spawn extension (DEAD_END_HANGUP, h, 1) exited non-zero on 'DAHDI/52-1'
2011-07-28 14:54:30 | chan_dahdi.c:     -- Hungup 'DAHDI/52-1'