Log output attached…
/START LOG
[2013-06-10 10:47:15] VERBOSE[14752][C-0000002b] pbx.c: – Executing [9166256568@from-pstn:1] NoOp(“SIP/fpbx-1-351a933a-00000034”, “Catch-All DID Match - Found 9166256568 - You probably want a DID for this.”) in new stack
[2013-06-10 10:47:15] VERBOSE[14752][C-0000002b] pbx.c: – Executing [9166256568@from-pstn:2] Goto(“SIP/fpbx-1-351a933a-00000034”, “ext-did,s,1”) in new stack
[2013-06-10 10:47:15] VERBOSE[14752][C-0000002b] pbx.c: – Goto (ext-did,s,1)
[2013-06-10 10:47:15] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ext-did:1] ExecIf(“SIP/fpbx-1-351a933a-00000034”, “1?Set(__FROM_DID=s)”) in new stack
[2013-06-10 10:47:15] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ext-did:2] Gosub(“SIP/fpbx-1-351a933a-00000034”, “app-blacklist-check,s,1()”) in new stack
[2013-06-10 10:47:15] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@app-blacklist-check:1] GotoIf(“SIP/fpbx-1-351a933a-00000034”, “0?blacklisted”) in new stack
[2013-06-10 10:47:15] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@app-blacklist-check:2] Set(“SIP/fpbx-1-351a933a-00000034”, “CALLED_BLACKLIST=1”) in new stack
[2013-06-10 10:47:15] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@app-blacklist-check:3] Return(“SIP/fpbx-1-351a933a-00000034”, “”) in new stack
[2013-06-10 10:47:15] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ext-did:3] Set(“SIP/fpbx-1-351a933a-00000034”, “CDR(did)=s”) in new stack
[2013-06-10 10:47:15] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ext-did:4] Gosub(“SIP/fpbx-1-351a933a-00000034”, “cidlookup,cidlookup_2,1()”) in new stack
[2013-06-10 10:47:15] VERBOSE[14752][C-0000002b] pbx.c: – Executing [cidlookup_2@cidlookup:1] Set(“SIP/fpbx-1-351a933a-00000034”, “CURLOPT(httptimeout)=7”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [cidlookup_2@cidlookup:2] Set(“SIP/fpbx-1-351a933a-00000034”, “CALLERID(name)=”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [cidlookup_2@cidlookup:3] Set(“SIP/fpbx-1-351a933a-00000034”, “current_hour=2013-06-10 10”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [cidlookup_2@cidlookup:4] Set(“SIP/fpbx-1-351a933a-00000034”, “last_query_hour=2013-06-10 10”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [cidlookup_2@cidlookup:5] Set(“SIP/fpbx-1-351a933a-00000034”, “total_hourly_queries=2”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [cidlookup_2@cidlookup:6] ExecIf(“SIP/fpbx-1-351a933a-00000034”, “0?Set(DB(cidlookup/opencnam_total_hourly_queries)=0)”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [cidlookup_2@cidlookup:7] ExecIf(“SIP/fpbx-1-351a933a-00000034”, “0?Set(DB(cidlookup/opencnam_total_hourly_queries)=0)”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [cidlookup_2@cidlookup:8] Set(“SIP/fpbx-1-351a933a-00000034”, “DB(cidlookup/opencnam_total_hourly_queries)=3”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [cidlookup_2@cidlookup:9] ExecIf(“SIP/fpbx-1-351a933a-00000034”, “0?System(/var/lib/asterisk/bin/opencnam-alert.php)”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [cidlookup_2@cidlookup:10] Set(“SIP/fpbx-1-351a933a-00000034”, “DB(cidlookup/opencnam_last_query_hour)=2013-06-10 10”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [cidlookup_2@cidlookup:11] Return(“SIP/fpbx-1-351a933a-00000034”, “”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ext-did:5] ExecIf(“SIP/fpbx-1-351a933a-00000034”, “1 ?Set(CALLERID(name)=19162517761)”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ext-did:6] Set(“SIP/fpbx-1-351a933a-00000034”, “__CALLINGPRES_SV=prohib_passed_screen”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ext-did:7] Set(“SIP/fpbx-1-351a933a-00000034”, “CALLERPRES()=allowed_not_screened”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ext-did:8] Goto(“SIP/fpbx-1-351a933a-00000034”, “ivr-1,s,1”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Goto (ivr-1,s,1)
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ivr-1:1] Set(“SIP/fpbx-1-351a933a-00000034”, “TIMEOUT_LOOPCOUNT=0”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ivr-1:2] Set(“SIP/fpbx-1-351a933a-00000034”, “INVALID_LOOPCOUNT=0”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ivr-1:3] Set(“SIP/fpbx-1-351a933a-00000034”, “_IVR_CONTEXT_ivr-1=”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ivr-1:4] Set(“SIP/fpbx-1-351a933a-00000034”, “_IVR_CONTEXT=ivr-1”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ivr-1:5] Set(“SIP/fpbx-1-351a933a-00000034”, “__IVR_RETVM=RETURN”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ivr-1:6] GotoIf(“SIP/fpbx-1-351a933a-00000034”, “0?skip”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ivr-1:7] Answer(“SIP/fpbx-1-351a933a-00000034”, “”) in new stack
[2013-06-10 10:47:16] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ivr-1:8] Wait(“SIP/fpbx-1-351a933a-00000034”, “1”) in new stack
[2013-06-10 10:47:17] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ivr-1:9] Set(“SIP/fpbx-1-351a933a-00000034”, “IVR_MSG=custom/arenaivr”) in new stack
[2013-06-10 10:47:17] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ivr-1:10] Set(“SIP/fpbx-1-351a933a-00000034”, “TIMEOUT(digit)=3”) in new stack
[2013-06-10 10:47:17] VERBOSE[14752][C-0000002b] func_timeout.c: – Digit timeout set to 3.000
[2013-06-10 10:47:17] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ivr-1:11] ExecIf(“SIP/fpbx-1-351a933a-00000034”, “1?Background(custom/arenaivr)”) in new stack
[2013-06-10 10:47:17] VERBOSE[14752][C-0000002b] file.c: – <SIP/fpbx-1-351a933a-00000034> Playing ‘custom/arenaivr.slin’ (language ‘en’)
[2013-06-10 10:47:45] VERBOSE[14752][C-0000002b] pbx.c: – Executing [s@ivr-1:12] WaitExten(“SIP/fpbx-1-351a933a-00000034”, “10,”) in new stack
[2013-06-10 10:47:47] NOTICE[1786] chan_sip.c: Disconnecting call ‘SIP/fpbx-1-351a933a-00000034’ for lack of RTP activity in 31 seconds
[2013-06-10 10:47:47] VERBOSE[14752][C-0000002b] pbx.c: == Spawn extension (ivr-1, s, 12) exited non-zero on ‘SIP/fpbx-1-351a933a-00000034’
[2013-06-10 10:47:47] VERBOSE[14752][C-0000002b] pbx.c: – Executing [h@ivr-1:1] Hangup(“SIP/fpbx-1-351a933a-00000034”, “”) in new stack
[2013-06-10 10:47:47] VERBOSE[14752][C-0000002b] pbx.c: == Spawn extension (ivr-1, h, 1) exited non-zero on ‘SIP/fpbx-1-351a933a-00000034’
END LOG\