Dahdi trunk not acknowledging incoming calls until they hang up?

rphipps · May 13, 2015, 7:55pm

Hello All,

So this is my first time setting up a FreePBX box with analog lines, and it hasn’t been going too well to be honest.

Anyway, after a couple of reinstalls I have got all my outgoing calls to work (two SIP trunks and the analog landline) as well as my SIP client phone and the analog phone off my card.

However, the analog incoming line (standard UK BT landline) is behaving really oddly. When I call it, it doesn’t seem to process the incoming call, but as soon as I hang up on my mobile (trying to call it) the system kicks into gear and starts ringing the phone like it should for a fraction of a second before giving up because the line has gone dead. As you can understand that isn’t at all helpful, but it cannot be my incoming routes setup (I’m using a DID set on the Dahdi channel DIDs page) because changing which extension the call is routed to seems to work.

Now, those two SIP trunks don’t seem to be accepting calls either, I just get the provider’s voicemail which implies my server isn’t registered properly, but to be honest as long as I can make outgoing calls that is the main thing.

Anyway, I was just wondering whether anybody had experienced any similar problems and how they went about fixing them? If it helps my analog card is an Atcom AX400P with one FXO and one FXS module. I have set every setting I can find in the Dahdi module config to UK and I have also tried a combination of spiral ringing and delay before processing with no success. I shall post up the logs in a moment.

Thanks in advance, I can’t let this box beat me (it has been trying its best!)

Rob

rphipps · May 13, 2015, 8:01pm

Oops, double posted. Deleted this one.

rphipps · May 13, 2015, 8:06pm

So, I just tried to call the landline from my mobile that the analog card is plugged into. The standard phones (not plugged into the PBX) rang, I then hung up and a couple of seconds later the phone connected to the PBX rang a couple of times and gave up.

Below are the logs. Just so you known Dhadi channel 2 is the incoming landline, channel 1 is the analog phone connected to the system (which can dial out and receive internal calls fine).

[2015-05-13 20:58:24] VERBOSE[10594][C-00000014] sig_analog.c: -- Starting simple switch on 'DAHDI/2-1'
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@from-analog:1] NoOp("DAHDI/2-1", "Entering from-dahdi with DID == ") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@from-analog:2] Ringing("DAHDI/2-1", "") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@from-analog:3] Set("DAHDI/2-1", "DID=s") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@from-analog:4] NoOp("DAHDI/2-1", "DID is now s") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@from-analog:5] GotoIf("DAHDI/2-1", "1?dahdiok:checkzap") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Goto (from-analog,s,9)
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@from-analog:9] NoOp("DAHDI/2-1", "Is a DAHDi Channel") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@from-analog:10] Set("DAHDI/2-1", "CHAN=2-1") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@from-analog:11] Set("DAHDI/2-1", "CHAN=2") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@from-analog:12] Macro("DAHDI/2-1", "from-dahdi-2,s,1") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-from-dahdi-2:1] NoOp("DAHDI/2-1", "Entering macro-from-dahdi-2 with DID = s and setting to: 01483417769") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-from-dahdi-2:2] Set("DAHDI/2-1", "__FROM_DID=01483417769") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-from-dahdi-2:3] Goto("DAHDI/2-1", "from-trunk,01483417769,1") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Goto (from-trunk,01483417769,1)
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] app_macro.c: == Channel 'DAHDI/2-1' jumping out of macro 'from-dahdi-2'
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [01483417769@from-trunk:1] Set("DAHDI/2-1", "__FROM_DID=01483417769") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [01483417769@from-trunk:2] Gosub("DAHDI/2-1", "sub-record-check,s,1(in,01483417769,dontcare)") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("DAHDI/2-1", "0?initialized") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:2] Set("DAHDI/2-1", "__REC_STATUS=INITIALIZED") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:3] Set("DAHDI/2-1", "NOW=1431547114") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:4] Set("DAHDI/2-1", "__DAY=13") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:5] Set("DAHDI/2-1", "__MONTH=05") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:6] Set("DAHDI/2-1", "__YEAR=2015") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:7] Set("DAHDI/2-1", "__TIMESTR=20150513-205834") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:8] Set("DAHDI/2-1", "__FROMEXTEN=unknown") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:9] Set("DAHDI/2-1", "__MON_FMT=wav") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:10] NoOp("DAHDI/2-1", "Recordings initialized") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("DAHDI/2-1", "0?Set(ARG3=dontcare)") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:12] Set("DAHDI/2-1", "REC_POLICY_MODE_SAVE=") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("DAHDI/2-1", "0?Set(REC_STATUS=NO)") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:14] GotoIf("DAHDI/2-1", "2?checkaction") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Goto (sub-record-check,s,17)
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:17] GotoIf("DAHDI/2-1", "1?sub-record-check,in,1") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Goto (sub-record-check,in,1)
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [in@sub-record-check:1] NoOp("DAHDI/2-1", "Inbound Recording Check to 01483417769") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [in@sub-record-check:2] Set("DAHDI/2-1", "FROMEXTEN=unknown") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [in@sub-record-check:3] ExecIf("DAHDI/2-1", "0?Set(FROMEXTEN=)") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [in@sub-record-check:4] Gosub("DAHDI/2-1", "recordcheck,1(dontcare,in,01483417769)") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("DAHDI/2-1", "Starting recording check against dontcare") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("DAHDI/2-1", "dontcare") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Goto (sub-record-check,recordcheck,3)
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [recordcheck@sub-record-check:3] Return("DAHDI/2-1", "") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [in@sub-record-check:5] Return("DAHDI/2-1", "") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [01483417769@from-trunk:3] Gosub("DAHDI/2-1", "app-blacklist-check,s,1()") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("DAHDI/2-1", "0?blacklisted") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@app-blacklist-check:2] Set("DAHDI/2-1", "CALLED_BLACKLIST=1") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@app-blacklist-check:3] Return("DAHDI/2-1", "") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [01483417769@from-trunk:4] Set("DAHDI/2-1", "CDR(did)=01483417769") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [01483417769@from-trunk:5] ExecIf("DAHDI/2-1", "1 ?Set(CALLERID(name)=)") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [01483417769@from-trunk:6] Set("DAHDI/2-1", "CHANNEL(musicclass)=default") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [01483417769@from-trunk:7] Set("DAHDI/2-1", "__MOHCLASS=default") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [01483417769@from-trunk:8] Ringing("DAHDI/2-1", "") in new stack
[2015-05-13 20:58:34] WARNING[10594][C-00000014] func_channel.c: Unknown or unavailable item requested: 'reversecharge'
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [01483417769@from-trunk:9] GotoIf("DAHDI/2-1", "0?macro-hangupcall") in new stack
[2015-05-13 20:58:34] VERBOSE[10594][C-00000014] pbx.c: -- Executing [01483417769@from-trunk:10] Wait("DAHDI/2-1", "2") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [01483417769@from-trunk:11] Set("DAHDI/2-1", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [01483417769@from-trunk:12] Set("DAHDI/2-1", "CALLERPRES()=allowed_not_screened") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [01483417769@from-trunk:13] Macro("DAHDI/2-1", "prepend-cid,LL: ") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-prepend-cid:1] GotoIf("DAHDI/2-1", "1?REPCID") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Goto (macro-prepend-cid,s,5)
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-prepend-cid:5] Set("DAHDI/2-1", "_RGPREFIX=LL: ") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-prepend-cid:6] Set("DAHDI/2-1", "CALLERID(name)=LL: ") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [01483417769@from-trunk:14] Goto("DAHDI/2-1", "from-did-direct,100,1") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Goto (from-did-direct,100,1)
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [100@from-did-direct:1] GotoIf("DAHDI/2-1", "1?ext-local,100,1") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Goto (ext-local,100,1)
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [100@ext-local:1] Set("DAHDI/2-1", "__RINGTIMER=15") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [100@ext-local:2] Macro("DAHDI/2-1", "exten-vm,novm,100,0,0,0") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-exten-vm:1] Macro("DAHDI/2-1", "user-callerid,") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:1] Set("DAHDI/2-1", "TOUCH_MONITOR=1431547104.14") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:2] Set("DAHDI/2-1", "AMPUSER=") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("DAHDI/2-1", "0?report") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("DAHDI/2-1", "1?Set(REALCALLERIDNUM=)") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:5] Set("DAHDI/2-1", "AMPUSER=") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("DAHDI/2-1", "0?limit") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:7] Set("DAHDI/2-1", "AMPUSERCIDNAME=") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:8] GotoIf("DAHDI/2-1", "1?report") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Goto (macro-user-callerid,s,16)
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:16] GotoIf("DAHDI/2-1", "0?continue") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:17] ExecIf("DAHDI/2-1", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:18] Set("DAHDI/2-1", "__TTL=64") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:19] GotoIf("DAHDI/2-1", "1?continue") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Goto (macro-user-callerid,s,30)
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:30] Set("DAHDI/2-1", "CALLERID(number)=") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:31] Set("DAHDI/2-1", "CALLERID(name)=LL:") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:32] Set("DAHDI/2-1", "CDR(cnum)=") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:33] Set("DAHDI/2-1", "CDR(cnam)=LL:") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-user-callerid:34] Set("DAHDI/2-1", "CHANNEL(language)=en") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-exten-vm:2] Set("DAHDI/2-1", "RingGroupMethod=none") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-exten-vm:3] Set("DAHDI/2-1", "__EXTTOCALL=100") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-exten-vm:4] Set("DAHDI/2-1", "__PICKUPMARK=100") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-exten-vm:5] Set("DAHDI/2-1", "RT=") in new stack
[2015-05-13 20:58:36] WARNING[10594][C-00000014] chan_sip.c: This function can only be used on SIP channels.
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-exten-vm:6] ExecIf("DAHDI/2-1", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2015-05-13 20:58:36] WARNING[10594][C-00000014] chan_sip.c: This function can only be used on SIP channels.
[2015-05-13 20:58:36] WARNING[10594][C-00000014] chan_sip.c: This function can only be used on SIP channels.
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-exten-vm:7] ExecIf("DAHDI/2-1", "0?MacroExit()") in new stack
[2015-05-13 20:58:36] WARNING[10594][C-00000014] chan_sip.c: This function can only be used on SIP channels.
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-exten-vm:8] Gosub("DAHDI/2-1", "sub-record-check,s,1(exten,100,dontcare)") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("DAHDI/2-1", "7?initialized") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Goto (sub-record-check,s,10)
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:10] NoOp("DAHDI/2-1", "Recordings initialized") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("DAHDI/2-1", "0?Set(ARG3=dontcare)") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:12] Set("DAHDI/2-1", "REC_POLICY_MODE_SAVE=") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("DAHDI/2-1", "0?Set(REC_STATUS=NO)") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:14] GotoIf("DAHDI/2-1", "5?checkaction") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Goto (sub-record-check,s,17)
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@sub-record-check:17] GotoIf("DAHDI/2-1", "1?sub-record-check,exten,1") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Goto (sub-record-check,exten,1)
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [exten@sub-record-check:1] NoOp("DAHDI/2-1", "Exten Recording Check between unknown and 100") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [exten@sub-record-check:2] Set("DAHDI/2-1", "CALLTYPE=external") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [exten@sub-record-check:3] ExecIf("DAHDI/2-1", "0?Set(CALLTYPE=)") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [exten@sub-record-check:4] Set("DAHDI/2-1", "CALLEE=dontcare") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [exten@sub-record-check:5] ExecIf("DAHDI/2-1", "0?Set(CALLEE=dontcare)") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [exten@sub-record-check:6] GotoIf("DAHDI/2-1", "1?callee") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Goto (sub-record-check,exten,11)
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [exten@sub-record-check:11] Gosub("DAHDI/2-1", "recordcheck,1(dontcare,external,100)") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("DAHDI/2-1", "Starting recording check against dontcare") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("DAHDI/2-1", "dontcare") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Goto (sub-record-check,recordcheck,3)
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [recordcheck@sub-record-check:3] Return("DAHDI/2-1", "") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [exten@sub-record-check:12] Return("DAHDI/2-1", "") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-exten-vm:9] Macro("DAHDI/2-1", "dial-one,,Ttr,100") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:1] Set("DAHDI/2-1", "DEXTEN=100") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:2] Set("DAHDI/2-1", "DIALSTATUS_CW=") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:3] GosubIf("DAHDI/2-1", "0?screen,1()") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:4] GosubIf("DAHDI/2-1", "0?cf,1()") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:5] GotoIf("DAHDI/2-1", "1?skip1") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Goto (macro-dial-one,s,8)
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:8] GotoIf("DAHDI/2-1", "0?nodial") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:9] GotoIf("DAHDI/2-1", "0?continue") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:10] Set("DAHDI/2-1", "EXTHASCW=ENABLED") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:11] GotoIf("DAHDI/2-1", "0?next1:cwinusebusy") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Goto (macro-dial-one,s,23)
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:23] GotoIf("DAHDI/2-1", "1?next3:continue") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Goto (macro-dial-one,s,24)
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:24] ExecIf("DAHDI/2-1", "0?Set(DIALSTATUS_CW=BUSY)") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:25] GotoIf("DAHDI/2-1", "0?nodial") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:26] GosubIf("DAHDI/2-1", "1?dstring,1():dlocal,1()") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [dstring@macro-dial-one:1] Set("DAHDI/2-1", "DSTRING=") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [dstring@macro-dial-one:2] Set("DAHDI/2-1", "DEVICES=100") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [dstring@macro-dial-one:3] ExecIf("DAHDI/2-1", "0?Return()") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [dstring@macro-dial-one:4] ExecIf("DAHDI/2-1", "0?Set(DEVICES=00)") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [dstring@macro-dial-one:5] Set("DAHDI/2-1", "LOOPCNT=1") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [dstring@macro-dial-one:6] Set("DAHDI/2-1", "ITER=1") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [dstring@macro-dial-one:7] Set("DAHDI/2-1", "THISDIAL=DAHDI/1") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [dstring@macro-dial-one:8] GosubIf("DAHDI/2-1", "1?zap2dahdi,1()") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("DAHDI/2-1", "0?Return()") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [zap2dahdi@macro-dial-one:2] Set("DAHDI/2-1", "NEWDIAL=") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [zap2dahdi@macro-dial-one:3] Set("DAHDI/2-1", "LOOPCNT2=1") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [zap2dahdi@macro-dial-one:4] Set("DAHDI/2-1", "ITER2=1") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [zap2dahdi@macro-dial-one:5] Set("DAHDI/2-1", "THISPART2=DAHDI/1") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("DAHDI/2-1", "0?Set(THISPART2=DAHDIDI/1)") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [zap2dahdi@macro-dial-one:7] Set("DAHDI/2-1", "NEWDIAL=DAHDI/1&") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [zap2dahdi@macro-dial-one:8] Set("DAHDI/2-1", "ITER2=2") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("DAHDI/2-1", "0?begin2") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [zap2dahdi@macro-dial-one:10] Set("DAHDI/2-1", "THISDIAL=DAHDI/1") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [zap2dahdi@macro-dial-one:11] Return("DAHDI/2-1", "") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [dstring@macro-dial-one:9] GotoIf("DAHDI/2-1", "1?doset") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Goto (macro-dial-one,dstring,12)
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [dstring@macro-dial-one:12] Set("DAHDI/2-1", "DSTRING=DAHDI/1&") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [dstring@macro-dial-one:13] Set("DAHDI/2-1", "ITER=2") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [dstring@macro-dial-one:14] GotoIf("DAHDI/2-1", "0?begin") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [dstring@macro-dial-one:15] Set("DAHDI/2-1", "DSTRING=DAHDI/1") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [dstring@macro-dial-one:16] Return("DAHDI/2-1", "") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:27] GotoIf("DAHDI/2-1", "0?nodial") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:28] GotoIf("DAHDI/2-1", "0?skiptrace") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:29] GosubIf("DAHDI/2-1", "0?ctset,1():ctclear,1()") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [ctclear@macro-dial-one:1] NoOp("DAHDI/2-1", "Deleting: CALLTRACE/100 ") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [ctclear@macro-dial-one:2] Return("DAHDI/2-1", "") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:30] Set("DAHDI/2-1", "D_OPTIONS=Ttr") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:31] ExecIf("DAHDI/2-1", "0?SIPAddHeader(Alert-Info: )") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:32] ExecIf("DAHDI/2-1", "0?SIPAddHeader()") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:33] ExecIf("DAHDI/2-1", "1?Set(CHANNEL(musicclass)=default)") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:34] GosubIf("DAHDI/2-1", "0?qwait,1()") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:35] Set("DAHDI/2-1", "__CWIGNORE=") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:36] Set("DAHDI/2-1", "__KEEPCID=TRUE") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:37] GotoIf("DAHDI/2-1", "0?usegoto,1") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:38] GotoIf("DAHDI/2-1", "1?godial") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Goto (macro-dial-one,s,43)
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:43] Macro("DAHDI/2-1", "dialout-one-predial-hook,") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dialout-one-predial-hook:1] MacroExit("DAHDI/2-1", "") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-dial-one:44] Dial("DAHDI/2-1", "DAHDI/1,,Ttr") in new stack
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] app_dial.c: -- Called DAHDI/1
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] app_dial.c: -- DAHDI/1-1 is ringing
[2015-05-13 20:58:36] VERBOSE[10594][C-00000014] app_dial.c: -- DAHDI/1-1 is ringing
[2015-05-13 20:58:37] VERBOSE[10594][C-00000014] app_dial.c: -- DAHDI/1-1 is ringing
[2015-05-13 20:58:37] NOTICE[2198] chan_sip.c: Received SIP subscribe for peer without mailbox: 110
[2015-05-13 20:58:39] VERBOSE[10594][C-00000014] app_dial.c: -- DAHDI/1-1 is ringing
[2015-05-13 20:58:40] VERBOSE[10594][C-00000014] app_dial.c: -- DAHDI/1-1 is ringing
[2015-05-13 20:58:42] VERBOSE[10594][C-00000014] sig_analog.c: -- Hanging up on 'DAHDI/1-1'
[2015-05-13 20:58:42] VERBOSE[10594][C-00000014] chan_dahdi.c: -- Hungup 'DAHDI/1-1'
[2015-05-13 20:58:42] VERBOSE[10594][C-00000014] app_macro.c: == Spawn extension (macro-dial-one, s, 44) exited non-zero on 'DAHDI/2-1' in macro 'dial-one'
[2015-05-13 20:58:42] VERBOSE[10594][C-00000014] app_macro.c: == Spawn extension (macro-exten-vm, s, 9) exited non-zero on 'DAHDI/2-1' in macro 'exten-vm'
[2015-05-13 20:58:42] VERBOSE[10594][C-00000014] pbx.c: == Spawn extension (ext-local, 100, 2) exited non-zero on 'DAHDI/2-1'
[2015-05-13 20:58:42] VERBOSE[10594][C-00000014] pbx.c: -- Executing [h@ext-local:1] Macro("DAHDI/2-1", "hangupcall,") in new stack
[2015-05-13 20:58:42] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-hangupcall:1] ExecIf("DAHDI/2-1", "0?Set(CDR(recordingfile)=.wav)") in new stack
[2015-05-13 20:58:42] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-hangupcall:2] GotoIf("DAHDI/2-1", "1?theend") in new stack
[2015-05-13 20:58:42] VERBOSE[10594][C-00000014] pbx.c: -- Goto (macro-hangupcall,s,4)
[2015-05-13 20:58:42] VERBOSE[10594][C-00000014] pbx.c: -- Executing [s@macro-hangupcall:4] Hangup("DAHDI/2-1", "") in new stack
[2015-05-13 20:58:42] VERBOSE[10594][C-00000014] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'DAHDI/2-1' in macro 'hangupcall'
[2015-05-13 20:58:42] VERBOSE[10594][C-00000014] pbx.c: == Spawn extension (ext-local, h, 1) exited non-zero on 'DAHDI/2-1'
[2015-05-13 20:58:42] VERBOSE[10594][C-00000014] sig_analog.c: -- Hanging up on 'DAHDI/2-1'
[2015-05-13 20:58:42] VERBOSE[10594][C-00000014] chan_dahdi.c: -- Hungup 'DAHDI/2-1'

Cheers.

dicko · May 13, 2015, 10:04pm

I would check that you have your CallerID settings correct for your phone line from BT (if you have subscribed to it ). The defaults might well make your line behave much as you describe

rphipps · May 14, 2015, 10:52am

Fantastic! Yup, we don’t have caller display here so I have turned it off on the Dahdi Config --> Global Settings window and it is working perfectly. It does seem to have stopped the card from sending out caller ID info to the analog phone, but we are trying to get TT to set up caller display on our line, so I expect it will work with call ID turned on once we have got that. Is it possible to set this per channel from the FreePBX UI or would I have to delve into the config files?

I guess it was waiting for caller ID before processing the call but it wasn’t receiving any so it didn’t process it or something?

Anyway thanks for your help, I would never have worked that out.

Rob