i've configured the Asterisk Now distro to do some tests.
I've a SIP line from OVH configured as the SIP trunk.
The internal calls are ok. The incoming calls from the external are OK.
But it's impossible to call to the external.
When i use my softphone, i've a blank 30-40 seconds after that, it rings. But no answer. I test to my job so i must have the IVR.
Can i have your help ?
Here the logs from the call :
- Code: Select all
[2015-09-12 13:53:00] VERBOSE[13010] pbx_spool.c: -- Attempting call on Local/s@tc-maint for application NoCDR() (Retry 1)
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: -- Executing [s@tc-maint:1] NoCDR("Local/s@tc-maint-00000bbe;2", "") in new stack
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: -- Executing [s@tc-maint:2] Set("Local/s@tc-maint-00000bbe;2", "TCMAINT=RETURN") in new stack
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: -- Executing [s@tc-maint:3] Gosub("Local/s@tc-maint-00000bbe;2", "timeconditions,1,1()") in new stack
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: -- Executing [1@timeconditions:1] Set("Local/s@tc-maint-00000bbe;2", "DB(TC/1/INUSESTATE)=INUSE") in new stack
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: -- Executing [1@timeconditions:2] Set("Local/s@tc-maint-00000bbe;2", "DB(TC/1/NOT_INUSESTATE)=NOT_INUSE") in new stack
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: -- Executing [1@timeconditions:3] GotoIfTime("Local/s@tc-maint-00000bbe;2", "20:01-07:59,mon-sun,1-31,jan-dec?truestate") in new stack
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: -- Executing [1@timeconditions:4] GotoIf("Local/s@tc-maint-00000bbe;2", "0?truegoto") in new stack
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: -- Executing [1@timeconditions:5] ExecIf("Local/s@tc-maint-00000bbe;2", "0?Set(DB(TC/1)=)") in new stack
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: -- Executing [1@timeconditions:6] Set("Local/s@tc-maint-00000bbe;2", "DEVICE_STATE(Custom:TC1)=INUSE") in new stack
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: -- Executing [1@timeconditions:7] ExecIf("Local/s@tc-maint-00000bbe;2", "0?Set(NOT_INUSE)") in new stack
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: -- Executing [1@timeconditions:8] GotoIf("Local/s@tc-maint-00000bbe;2", "0?ivr-1,s,1") in new stack
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: -- Executing [1@timeconditions:9] Set("Local/s@tc-maint-00000bbe;2", "TCSTATE=false") in new stack
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: -- Executing [1@timeconditions:10] Set("Local/s@tc-maint-00000bbe;2", "TCOVERRIDE=false") in new stack
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: -- Executing [1@timeconditions:11] Return("Local/s@tc-maint-00000bbe;2", "") in new stack
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: -- Executing [s@tc-maint:4] System("Local/s@tc-maint-00000bbe;2", "/var/lib/asterisk/bin/schedtc.php 60 /var/spool/asterisk/outgoing 1") in new stack
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: -- Executing [s@tc-maint:5] Answer("Local/s@tc-maint-00000bbe;2", "") in new stack
[2015-09-12 13:53:00] NOTICE[13010] pbx_spool.c: Call completed to Local/s@tc-maint
[2015-09-12 13:53:00] VERBOSE[13012][C-000012d2] pbx.c: == Spawn extension (tc-maint, s, 5) exited non-zero on 'Local/s@tc-maint-00000bbe;2'
[2015-09-12 13:53:03] VERBOSE[1904][C-000012d3] netsock2.c: == Using SIP RTP TOS bits 184
[2015-09-12 13:53:03] VERBOSE[1904][C-000012d3] netsock2.c: == Using SIP RTP CoS mark 5
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [081554459@from-internal:1] Macro("SIP/500-00000711", "user-callerid,LIMIT") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/500-00000711", "TOUCH_MONITOR=1442058783.7823") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:2] Set("SIP/500-00000711", "AMPUSER=500") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/500-00000711", "0?report") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/500-00000711", "1?Set(REALCALLERIDNUM=500)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/500-00000711", "AMPUSER=500") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/500-00000711", "0?limit") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/500-00000711", "AMPUSERCIDNAME=nij") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:8] GotoIf("SIP/500-00000711", "0?report") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:9] Set("SIP/500-00000711", "AMPUSERCID=500") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:10] Set("SIP/500-00000711", "__DIAL_OPTIONS=Ttr") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:11] Set("SIP/500-00000711", "CALLERID(all)="nij" <500>") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/500-00000711", "0?limit") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:13] ExecIf("SIP/500-00000711", "1?Set(GROUP(concurrency_limit)=500)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:14] GosubIf("SIP/500-00000711", "7?sub-ccss,s,1(from-internal,081554459)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-ccss:1] ExecIf("SIP/500-00000711", "0?Return()") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-ccss:2] Set("SIP/500-00000711", "CCSS_SETUP=TRUE") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-ccss:3] GosubIf("SIP/500-00000711", "0?monitor_config,1(from-internal,081554459):monitor_default,1(from-internal,081554459)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/500-00000711", "0?is_exten") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [monitor_default@sub-ccss:2] StackPop("SIP/500-00000711", "") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [monitor_default@sub-ccss:3] Return("SIP/500-00000711", "FALSE") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:15] ExecIf("SIP/500-00000711", "0?Set(CHANNEL(language)=)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:16] GotoIf("SIP/500-00000711", "1?continue") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Goto (macro-user-callerid,s,30)
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:30] Set("SIP/500-00000711", "CALLERID(number)=500") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:31] Set("SIP/500-00000711", "CALLERID(name)=nij") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:32] Set("SIP/500-00000711", "CDR(cnum)=500") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:33] Set("SIP/500-00000711", "CDR(cnam)=nij") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-user-callerid:34] Set("SIP/500-00000711", "CHANNEL(language)=en") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [081554459@from-internal:2] Set("SIP/500-00000711", "ROUTEUSER=500") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [081554459@from-internal:3] GotoIf("SIP/500-00000711", "1?notblind") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Goto (from-internal,081554459,6)
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [081554459@from-internal:6] GotoIf("SIP/500-00000711", "1?restrictedroute-c4ca4238a0b923820dcc509a6f75849b,081554459,2:outbound-allroutes,081554459,2") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Goto (restrictedroute-c4ca4238a0b923820dcc509a6f75849b,081554459,2)
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [081554459@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:2] Gosub("SIP/500-00000711", "sub-record-check,s,1(out,081554459,dontcare)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("SIP/500-00000711", "0?initialized") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-record-check:2] Set("SIP/500-00000711", "__REC_STATUS=INITIALIZED") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-record-check:3] Set("SIP/500-00000711", "NOW=1442058783") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-record-check:4] Set("SIP/500-00000711", "__DAY=12") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-record-check:5] Set("SIP/500-00000711", "__MONTH=09") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-record-check:6] Set("SIP/500-00000711", "__YEAR=2015") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/500-00000711", "__TIMESTR=20150912-135303") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-record-check:8] Set("SIP/500-00000711", "__FROMEXTEN=500") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-record-check:9] Set("SIP/500-00000711", "__MON_FMT=wav") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-record-check:10] NoOp("SIP/500-00000711", "Recordings initialized") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("SIP/500-00000711", "0?Set(ARG3=dontcare)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-record-check:12] Set("SIP/500-00000711", "REC_POLICY_MODE_SAVE=") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("SIP/500-00000711", "0?Set(REC_STATUS=NO)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-record-check:14] GotoIf("SIP/500-00000711", "3?checkaction") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Goto (sub-record-check,s,17)
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@sub-record-check:17] GotoIf("SIP/500-00000711", "1?sub-record-check,out,1") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Goto (sub-record-check,out,1)
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [out@sub-record-check:1] NoOp("SIP/500-00000711", "Outbound Recording Check from 500 to 081554459") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [out@sub-record-check:2] Set("SIP/500-00000711", "RECMODE=dontcare") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [out@sub-record-check:3] ExecIf("SIP/500-00000711", "1?Goto(routewins)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Goto (sub-record-check,out,7)
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [out@sub-record-check:7] Gosub("SIP/500-00000711", "recordcheck,1(dontcare,out,081554459)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/500-00000711", "Starting recording check against dontcare") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("SIP/500-00000711", "dontcare") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Goto (sub-record-check,recordcheck,3)
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [recordcheck@sub-record-check:3] Return("SIP/500-00000711", "") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [out@sub-record-check:8] Return("SIP/500-00000711", "") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [081554459@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:3] ExecIf("SIP/500-00000711", "0 ?Set(CDR(accountcode)=)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [081554459@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:4] Set("SIP/500-00000711", "MOHCLASS=none") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [081554459@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:5] ExecIf("SIP/500-00000711", "1?Set(TRUNKCIDOVERRIDE=081980975)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [081554459@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:6] Set("SIP/500-00000711", "_NODEST=") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [081554459@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:7] Macro("SIP/500-00000711", "dialout-trunk,2,081554459,,off") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/500-00000711", "DIAL_TRUNK=2") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/500-00000711", "0?sub-pincheck,s,1()") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/500-00000711", "0?disabletrunk,1") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/500-00000711", "DIAL_NUMBER=081554459") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/500-00000711", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/500-00000711", "OUTBOUND_GROUP=OUT_2") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/500-00000711", "1?nomax") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Goto (macro-dialout-trunk,s,9)
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/500-00000711", "0?skipoutcid") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/500-00000711", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/500-00000711", "outbound-callerid,2") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/500-00000711", "0?Set(CALLERPRES()=)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/500-00000711", "0?Set(REALCALLERIDNUM=500)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/500-00000711", "1?normcid") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Goto (macro-outbound-callerid,s,6)
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/500-00000711", "USEROUTCID=") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/500-00000711", "EMERGENCYCID=") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/500-00000711", "TRUNKOUTCID=003281980975") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/500-00000711", "1?trunkcid") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Goto (macro-outbound-callerid,s,14)
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/500-00000711", "1?Set(CALLERID(all)=003281980975)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/500-00000711", "0?Set(CALLERID(all)=)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-outbound-callerid:16] ExecIf("SIP/500-00000711", "1?Set(CALLERID(all)=081980975)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-outbound-callerid:17] ExecIf("SIP/500-00000711", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-outbound-callerid:18] Set("SIP/500-00000711", "CDR(outbound_cnum)=081980975") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-outbound-callerid:19] Set("SIP/500-00000711", "CDR(outbound_cnam)=") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/500-00000711", "0?sub-flp-2,s,1()") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/500-00000711", "OUTNUM=081554459") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/500-00000711", "custom=SIP/OVH") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/500-00000711", "1?Set(DIAL_TRUNK_OPTIONS=M(setmusic^none)Tt)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/500-00000711", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^none)TtM(confirm))") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:17] Macro("SIP/500-00000711", "dialout-trunk-predial-hook,") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/500-00000711", "") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/500-00000711", "0?bypass,1") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:19] ExecIf("SIP/500-00000711", "1?Set(CONNECTEDLINE(num,i)=081554459)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/500-00000711", "1?Set(CONNECTEDLINE(name,i)=CID:081980975)") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/500-00000711", "0?customtrunk") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] pbx.c: -- Executing [s@macro-dialout-trunk:22] Dial("SIP/500-00000711", "SIP/OVH/081554459,300,M(setmusic^none)Tt") in new stack
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] netsock2.c: == Using SIP RTP TOS bits 184
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] netsock2.c: == Using SIP RTP CoS mark 5
[2015-09-12 13:53:03] VERBOSE[13023][C-000012d3] app_dial.c: -- Called SIP/OVH/081554459
[2015-09-12 13:53:07] VERBOSE[1904][C-000012d4] netsock2.c: == Using SIP RTP TOS bits 184
[2015-09-12 13:53:07] VERBOSE[1904][C-000012d4] netsock2.c: == Using SIP RTP CoS mark 5
[2015-09-12 13:53:07] VERBOSE[13026][C-000012d4] pbx.c: -- Executing [170048222084974@from-sip-external:1] NoOp("SIP/192.168.1.54-00000713", "Received incoming SIP connection from unknown peer to 170048222084974") in new stack
[2015-09-12 13:53:07] VERBOSE[13026][C-000012d4] pbx.c: -- Executing [170048222084974@from-sip-external:2] Set("SIP/192.168.1.54-00000713", "DID=170048222084974") in new stack
[2015-09-12 13:53:07] VERBOSE[13026][C-000012d4] pbx.c: -- Executing [170048222084974@from-sip-external:3] Goto("SIP/192.168.1.54-00000713", "s,1") in new stack
[2015-09-12 13:53:07] VERBOSE[13026][C-000012d4] pbx.c: -- Goto (from-sip-external,s,1)
[2015-09-12 13:53:07] VERBOSE[13026][C-000012d4] pbx.c: -- Executing [s@from-sip-external:1] GotoIf("SIP/192.168.1.54-00000713", "0?checklang:noanonymous") in new stack
[2015-09-12 13:53:07] VERBOSE[13026][C-000012d4] pbx.c: -- Goto (from-sip-external,s,5)
[2015-09-12 13:53:07] VERBOSE[13026][C-000012d4] pbx.c: -- Executing [s@from-sip-external:5] Set("SIP/192.168.1.54-00000713", "TIMEOUT(absolute)=15") in new stack
[2015-09-12 13:53:07] VERBOSE[13026][C-000012d4] func_timeout.c: -- Channel will hangup at 2015-09-12 13:53:22.070 CEST.
[2015-09-12 13:53:07] VERBOSE[13026][C-000012d4] pbx.c: -- Executing [s@from-sip-external:6] Log("SIP/192.168.1.54-00000713", "WARNING,"Rejecting unknown SIP connection from 192.151.159.122"") in new stack
[2015-09-12 13:53:07] WARNING[13026][C-000012d4] Ext. s: "Rejecting unknown SIP connection from 192.151.159.122"
[2015-09-12 13:53:07] VERBOSE[13026][C-000012d4] pbx.c: -- Executing [s@from-sip-external:7] Answer("SIP/192.168.1.54-00000713", "") in new stack
[2015-09-12 13:53:07] VERBOSE[13026][C-000012d4] pbx.c: -- Executing [s@from-sip-external:8] Wait("SIP/192.168.1.54-00000713", "2") in new stack
[2015-09-12 13:53:09] VERBOSE[13026][C-000012d4] pbx.c: -- Executing [s@from-sip-external:9] Playback("SIP/192.168.1.54-00000713", "ss-noservice") in new stack
[2015-09-12 13:53:09] VERBOSE[13026][C-000012d4] file.c: -- <SIP/192.168.1.54-00000713> Playing 'ss-noservice.ulaw' (language 'en')
[2015-09-12 13:53:14] VERBOSE[13026][C-000012d4] pbx.c: -- Executing [s@from-sip-external:10] PlayTones("SIP/192.168.1.54-00000713", "congestion") in new stack
[2015-09-12 13:53:14] VERBOSE[13026][C-000012d4] pbx.c: -- Executing [s@from-sip-external:11] Congestion("SIP/192.168.1.54-00000713", "5") in new stack
[2015-09-12 13:53:19] VERBOSE[13026][C-000012d4] pbx.c: == Spawn extension (from-sip-external, s, 11) exited non-zero on 'SIP/192.168.1.54-00000713'
[2015-09-12 13:53:19] VERBOSE[13026][C-000012d4] pbx.c: -- Executing [h@from-sip-external:1] Hangup("SIP/192.168.1.54-00000713", "") in new stack
[2015-09-12 13:53:19] VERBOSE[13026][C-000012d4] pbx.c: == Spawn extension (from-sip-external, h, 1) exited non-zero on 'SIP/192.168.1.54-00000713'
[2015-09-12 13:53:39] WARNING[1904] chan_sip.c: Retransmission timeout reached on transmission 610121a6e08b4f4cfc898f6b0c3d48a2 for seqno 1 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 31999ms with no response
[2015-09-12 13:54:00] VERBOSE[13053] pbx_spool.c: -- Attempting call on Local/s@tc-maint for application NoCDR() (Retry 1)
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: -- Executing [s@tc-maint:1] NoCDR("Local/s@tc-maint-00000bbf;2", "") in new stack
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: -- Executing [s@tc-maint:2] Set("Local/s@tc-maint-00000bbf;2", "TCMAINT=RETURN") in new stack
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: -- Executing [s@tc-maint:3] Gosub("Local/s@tc-maint-00000bbf;2", "timeconditions,1,1()") in new stack
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: -- Executing [1@timeconditions:1] Set("Local/s@tc-maint-00000bbf;2", "DB(TC/1/INUSESTATE)=INUSE") in new stack
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: -- Executing [1@timeconditions:2] Set("Local/s@tc-maint-00000bbf;2", "DB(TC/1/NOT_INUSESTATE)=NOT_INUSE") in new stack
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: -- Executing [1@timeconditions:3] GotoIfTime("Local/s@tc-maint-00000bbf;2", "20:01-07:59,mon-sun,1-31,jan-dec?truestate") in new stack
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: -- Executing [1@timeconditions:4] GotoIf("Local/s@tc-maint-00000bbf;2", "0?truegoto") in new stack
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: -- Executing [1@timeconditions:5] ExecIf("Local/s@tc-maint-00000bbf;2", "0?Set(DB(TC/1)=)") in new stack
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: -- Executing [1@timeconditions:6] Set("Local/s@tc-maint-00000bbf;2", "DEVICE_STATE(Custom:TC1)=INUSE") in new stack
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: -- Executing [1@timeconditions:7] ExecIf("Local/s@tc-maint-00000bbf;2", "0?Set(NOT_INUSE)") in new stack
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: -- Executing [1@timeconditions:8] GotoIf("Local/s@tc-maint-00000bbf;2", "0?ivr-1,s,1") in new stack
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: -- Executing [1@timeconditions:9] Set("Local/s@tc-maint-00000bbf;2", "TCSTATE=false") in new stack
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: -- Executing [1@timeconditions:10] Set("Local/s@tc-maint-00000bbf;2", "TCOVERRIDE=false") in new stack
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: -- Executing [1@timeconditions:11] Return("Local/s@tc-maint-00000bbf;2", "") in new stack
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: -- Executing [s@tc-maint:4] System("Local/s@tc-maint-00000bbf;2", "/var/lib/asterisk/bin/schedtc.php 60 /var/spool/asterisk/outgoing 0") in new stack
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: -- Executing [s@tc-maint:5] Answer("Local/s@tc-maint-00000bbf;2", "") in new stack
[2015-09-12 13:54:00] NOTICE[13053] pbx_spool.c: Call completed to Local/s@tc-maint
[2015-09-12 13:54:00] VERBOSE[13054][C-000012d5] pbx.c: == Spawn extension (tc-maint, s, 5) exited non-zero on 'Local/s@tc-maint-00000bbf;2'