TDM410P - Call waiting not working

General Help
1

I want to make the 2 POT lines available at remote location.

I am currently running FreePBX 14.0.1.20 at my home location. My local server is using a TDM410P with 2 FXO cards, connect to the 2 POT lines (215XXXXXXX and 215YYYYYYY).

I then have created 2 trunks:

Name Tech CallerID Status
Business_Line_Trunk dahdi 215XXXXXXX Enabled
House_Line_Trunk dahdi 215YYYYYYY Enabled

and 2 Inbound Routes

DID CID Destination
215YYYYYYY Any House Line Ring Groups: 2000 Home
215XXXXXXX Any Business Line Ring Groups: 2001 Work

2 Extensions

600 - Assigned to Ring Group 2000
601 - Assigned to Ring Group 2001

and finally 2 Outbound Routes
HouseLine assigned to House_Line_Trunk
The dial pattern looks like this:
prepend: empty
prefix: empty
match pattern: 1NXXNXXXXXX
callerID: 600

BusinessLine assigned to Business_Line_Trunk
The dial pattern looks like this:
prepend: empty
prefix: empty
match pattern: 1NXXNXXXXXX
callerID: 601

============ Remote Location ============
I use the OBI202 connected to a 2 line phone. Line 1 is registered to extension 600 and Line 2 is registered to extension 601.

Everything works. When I receive a call at the primary location (on either line 1 or 2) the corresponding line on the phone at the remote location rings as well.
Similarly, I can initiate a call at the remote location (from either line) and the actual call will originate from my primary location.

Here is the problem.
Both my POT lines have call waiting. i cannot get call waiting to work at the remote location.

Here is the content of the chan_dahdi.conf
[general]

; generated by module
#include chan_dahdi_general.conf

; for user additions not provided by module
#include chan_dahdi_general_custom.conf

[channels]
language=en
busydetect=yes
busycount=10
usecallerid=yes
callwaiting=yes
usecallingpres=yes
threewaycalling=yes
transfer=yes
cancallforward=yes
callreturn=yes
echocancel=yes
echocancelwhenbridged=no
echotraining=yes
immediate=no
faxdetect=no
rxgain=0.0
txgain=0.0

Any suggestions on how to fix the call waiting problem?

Thanks
Renato

2

show logs please.

3

James

Thanks for the reply. I will be at the remote location this weekend and I will be able to test then.

Sorry for the questions. What logs are you exactly looking for?

Renato

4

Here is the output of tail -f /var/log/asterisk/full while a tested the call waiting feature.

I had my wife call my work number (215AAABBBB) from her cell phone (215xxxyyyy).

I then called my work number from my cell phone. I hit the “talk” button on the landline but all I heard was a dialtone.

When I switched back to my wife, she told me she heard music while she was on hold.

Additionally, the caller ID of my landline did not display the number of the second call (i.e. from my cell). All I heard was just the beep of call waiting.

…

[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:3] ExecIf(“DAHDI/2-1”, “0?Set(ALERT_INFO=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:4] ExecIf(“DAHDI/2-1”, “0?Set(ALERT_INFO=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:5] ExecIf(“DAHDI/2-1”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:6] AGI(“DAHDI/2-1”, “dialparties.agi”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Caller ID name is ‘Work - Lastname Firstname (Cell)’ number is ‘215xxxyyyy’
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: CW Ignore is:
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: CF Ignore is:
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Methodology of ring is ‘ringall’
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Added extension 100 to extension map
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Added extension 120 to extension map
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Added extension 200 to extension map
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Added extension 206 to extension map
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Added extension 300 to extension map
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Added extension 304 to extension map
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Added extension 400 to extension map
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Added extension 421 to extension map
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Added extension 402 to extension map
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Added extension 601 to extension map
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Added extension 602 to extension map
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 100 cf is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 120 cf is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 200 cf is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 206 cf is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 300 cf is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 304 cf is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 400 cf is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 421 cf is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 402 cf is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 601 cf is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 602 cf is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 100 do not disturb is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 120 do not disturb is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 200 do not disturb is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 206 do not disturb is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 300 do not disturb is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 304 do not disturb is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 400 do not disturb is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 421 do not disturb is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 402 do not disturb is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 601 do not disturb is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Extension 602 do not disturb is disabled
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Discovered PJSIP Endpoint PJSIP/100
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Ended up with real PJSIP Dial string PJSIP/100/sip:[email protected]:6100
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: dbset CALLTRACE/100 to 215xxxyyyy
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Discovered PJSIP Endpoint PJSIP/120
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Ended up with no PJSIP contacts
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: dbset CALLTRACE/120 to 215xxxyyyy
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Discovered PJSIP Endpoint PJSIP/200
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Ended up with real PJSIP Dial string PJSIP/200/sip:[email protected]:53811;rinstance=e780ac84c84c4a8c
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: dbset CALLTRACE/200 to 215xxxyyyy
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Discovered PJSIP Endpoint PJSIP/206
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Ended up with no PJSIP contacts
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: dbset CALLTRACE/206 to 215xxxyyyy
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Discovered PJSIP Endpoint PJSIP/300
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Ended up with no PJSIP contacts
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: dbset CALLTRACE/300 to 215xxxyyyy
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Discovered PJSIP Endpoint PJSIP/304
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Ended up with no PJSIP contacts
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: dbset CALLTRACE/304 to 215xxxyyyy
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Discovered PJSIP Endpoint PJSIP/400
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Ended up with no PJSIP contacts
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: dbset CALLTRACE/400 to 215xxxyyyy
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Discovered PJSIP Endpoint PJSIP/421
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Ended up with real PJSIP Dial string PJSIP/421/sip:[email protected]:5061;user=phone
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: dbset CALLTRACE/421 to 215xxxyyyy
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Discovered PJSIP Endpoint PJSIP/402
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Ended up with real PJSIP Dial string PJSIP/402/sip:[email protected]:56548
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: dbset CALLTRACE/402 to 215xxxyyyy
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Discovered PJSIP Endpoint PJSIP/601
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Ended up with real PJSIP Dial string PJSIP/601/sip:[email protected]:5601
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: dbset CALLTRACE/601 to 215xxxyyyy
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Discovered PJSIP Endpoint PJSIP/602
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Ended up with no PJSIP contacts
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: dbset CALLTRACE/602 to 215xxxyyyy
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: Filtered ARG3: 100-120-200-206-300-304-400-421-402-601-602
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: RVOL_MODE ‘’
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: RVOL is:
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: dialparties.agi: RVOLPARENT is:
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] res_agi.c: <DAHDI/2-1>AGI Script dialparties.agi completed, returning 0
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:9] NoOp(“DAHDI/2-1”, “Returned from dialparties with groups to dial”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:10] NoOp(“DAHDI/2-1”, "ringall array ") in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:11] Set(“DAHDI/2-1”, “__FMGL_DIAL=”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:12] Set(“DAHDI/2-1”, “LOOPCNT=11”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:13] Set(“DAHDI/2-1”, “ITER=1”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:14] Set(“DAHDI/2-1”, “EXTTOCALL=100”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:15] NoOp(“DAHDI/2-1”, “Working with 100”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:16] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:17] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:18] Set(“DAHDI/2-1”, “ITER=2”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:19] GotoIf(“DAHDI/2-1”, “1?ndloopbegin”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx_builtins.c: Goto (macro-dial,s,14)
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:14] Set(“DAHDI/2-1”, “EXTTOCALL=120”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:15] NoOp(“DAHDI/2-1”, “Working with 120”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:16] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:17] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:18] Set(“DAHDI/2-1”, “ITER=3”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:19] GotoIf(“DAHDI/2-1”, “1?ndloopbegin”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx_builtins.c: Goto (macro-dial,s,14)
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:14] Set(“DAHDI/2-1”, “EXTTOCALL=200”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:15] NoOp(“DAHDI/2-1”, “Working with 200”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:16] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:17] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:18] Set(“DAHDI/2-1”, “ITER=4”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:19] GotoIf(“DAHDI/2-1”, “1?ndloopbegin”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx_builtins.c: Goto (macro-dial,s,14)
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:14] Set(“DAHDI/2-1”, “EXTTOCALL=206”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:15] NoOp(“DAHDI/2-1”, “Working with 206”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:16] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:17] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:18] Set(“DAHDI/2-1”, “ITER=5”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:19] GotoIf(“DAHDI/2-1”, “1?ndloopbegin”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx_builtins.c: Goto (macro-dial,s,14)
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:14] Set(“DAHDI/2-1”, “EXTTOCALL=300”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:15] NoOp(“DAHDI/2-1”, “Working with 300”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:16] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:17] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:18] Set(“DAHDI/2-1”, “ITER=6”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:19] GotoIf(“DAHDI/2-1”, “1?ndloopbegin”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx_builtins.c: Goto (macro-dial,s,14)
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:14] Set(“DAHDI/2-1”, “EXTTOCALL=304”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:15] NoOp(“DAHDI/2-1”, “Working with 304”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:16] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:17] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:18] Set(“DAHDI/2-1”, “ITER=7”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:19] GotoIf(“DAHDI/2-1”, “1?ndloopbegin”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx_builtins.c: Goto (macro-dial,s,14)
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:14] Set(“DAHDI/2-1”, “EXTTOCALL=400”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:15] NoOp(“DAHDI/2-1”, “Working with 400”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:16] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:17] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:18] Set(“DAHDI/2-1”, “ITER=8”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:19] GotoIf(“DAHDI/2-1”, “1?ndloopbegin”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx_builtins.c: Goto (macro-dial,s,14)
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:14] Set(“DAHDI/2-1”, “EXTTOCALL=421”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:15] NoOp(“DAHDI/2-1”, “Working with 421”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:16] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:17] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:18] Set(“DAHDI/2-1”, “ITER=9”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:19] GotoIf(“DAHDI/2-1”, “1?ndloopbegin”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx_builtins.c: Goto (macro-dial,s,14)
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:14] Set(“DAHDI/2-1”, “EXTTOCALL=402”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:15] NoOp(“DAHDI/2-1”, “Working with 402”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:16] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:17] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:18] Set(“DAHDI/2-1”, “ITER=10”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:19] GotoIf(“DAHDI/2-1”, “1?ndloopbegin”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx_builtins.c: Goto (macro-dial,s,14)
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:14] Set(“DAHDI/2-1”, “EXTTOCALL=601”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:15] NoOp(“DAHDI/2-1”, “Working with 601”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:16] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:17] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:18] Set(“DAHDI/2-1”, “ITER=11”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:19] GotoIf(“DAHDI/2-1”, “1?ndloopbegin”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx_builtins.c: Goto (macro-dial,s,14)
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:14] Set(“DAHDI/2-1”, “EXTTOCALL=602”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:15] NoOp(“DAHDI/2-1”, “Working with 602”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:16] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:17] ExecIf(“DAHDI/2-1”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:18] Set(“DAHDI/2-1”, “ITER=12”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:19] GotoIf(“DAHDI/2-1”, “0?ndloopbegin”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:20] Macro(“DAHDI/2-1”, “dial-ringall-predial-hook,”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit(“DAHDI/2-1”, “”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-dial:21] Dial(“DAHDI/2-1”, “PJSIP/100/sip:[email protected]:6100&PJSIP/200/sip:[email protected]:53811;rinstance=e780ac84c84c4a8c&PJSIP/421/sip:[email protected]:5061;user=phone&PJSIP/402/sip:[email protected]:56548&PJSIP/601/sip:[email protected]:5601,30,trM(auto-blkvm)b(func-apply-sipheaders^s^1),”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_stack.c: PJSIP/100-000000ba Internal Gosub(func-apply-sipheaders,s,1) start
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“PJSIP/100-000000ba”, “Applying SIP Headers to channel”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“PJSIP/100-000000ba”, “SIPHEADERKEYS=”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:3] While(“PJSIP/100-000000ba”, “0”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_while.c: Jumping to priority 7
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:8] Return(“PJSIP/100-000000ba”, “”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_stack.c: Spawn extension (from-internal, 2001, 1) exited non-zero on ‘PJSIP/100-000000ba’
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_stack.c: PJSIP/100-000000ba Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_stack.c: PJSIP/200-000000bb Internal Gosub(func-apply-sipheaders,s,1) start
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“PJSIP/200-000000bb”, “Applying SIP Headers to channel”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“PJSIP/200-000000bb”, “SIPHEADERKEYS=”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:3] While(“PJSIP/200-000000bb”, “0”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_while.c: Jumping to priority 7
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:8] Return(“PJSIP/200-000000bb”, “”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_stack.c: Spawn extension (from-internal, 2001, 1) exited non-zero on ‘PJSIP/200-000000bb’
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_stack.c: PJSIP/200-000000bb Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_stack.c: PJSIP/421-000000bc Internal Gosub(func-apply-sipheaders,s,1) start
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“PJSIP/421-000000bc”, “Applying SIP Headers to channel”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“PJSIP/421-000000bc”, “SIPHEADERKEYS=”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:3] While(“PJSIP/421-000000bc”, “0”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_while.c: Jumping to priority 7
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:8] Return(“PJSIP/421-000000bc”, “”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_stack.c: Spawn extension (from-internal, 2001, 1) exited non-zero on ‘PJSIP/421-000000bc’
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_stack.c: PJSIP/421-000000bc Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_stack.c: PJSIP/402-000000bd Internal Gosub(func-apply-sipheaders,s,1) start
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“PJSIP/402-000000bd”, “Applying SIP Headers to channel”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“PJSIP/402-000000bd”, “SIPHEADERKEYS=”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:3] While(“PJSIP/402-000000bd”, “0”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_while.c: Jumping to priority 7
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:8] Return(“PJSIP/402-000000bd”, “”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_stack.c: Spawn extension (from-internal, 2001, 1) exited non-zero on ‘PJSIP/402-000000bd’
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_stack.c: PJSIP/402-000000bd Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_stack.c: PJSIP/601-000000be Internal Gosub(func-apply-sipheaders,s,1) start
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“PJSIP/601-000000be”, “Applying SIP Headers to channel”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“PJSIP/601-000000be”, “SIPHEADERKEYS=”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:3] While(“PJSIP/601-000000be”, “0”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_while.c: Jumping to priority 7
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] pbx.c: Executing [s@func-apply-sipheaders:8] Return(“PJSIP/601-000000be”, “”) in new stack
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_stack.c: Spawn extension (from-internal, 2001, 1) exited non-zero on ‘PJSIP/601-000000be’
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_stack.c: PJSIP/601-000000be Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_dial.c: Called PJSIP/100/sip:[email protected]:6100
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_dial.c: Called PJSIP/200/sip:[email protected]:53811;rinstance=e780ac84c84c4a8c
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_dial.c: Called PJSIP/421/sip:[email protected]:5061;user=phone
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_dial.c: Called PJSIP/402/sip:[email protected]:56548
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_dial.c: Called PJSIP/601/sip:[email protected]:5601
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_dial.c: PJSIP/200-000000bb connected line has changed. Saving it until answer for DAHDI/2-1
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_dial.c: PJSIP/100-000000ba connected line has changed. Saving it until answer for DAHDI/2-1
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_dial.c: PJSIP/402-000000bd connected line has changed. Saving it until answer for DAHDI/2-1
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_dial.c: PJSIP/421-000000bc connected line has changed. Saving it until answer for DAHDI/2-1
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_dial.c: PJSIP/601-000000be connected line has changed. Saving it until answer for DAHDI/2-1
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_dial.c: PJSIP/100-000000ba is ringing
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_dial.c: PJSIP/601-000000be is ringing
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_dial.c: PJSIP/402-000000bd is ringing
[2017-12-18 21:13:21] VERBOSE[9867][C-00000028] app_dial.c: PJSIP/421-000000bc is ringing
[2017-12-18 21:13:22] VERBOSE[9867][C-00000028] app_dial.c: PJSIP/200-000000bb is ringing
[2017-12-18 21:13:24] VERBOSE[9867][C-00000028] app_dial.c: PJSIP/601-000000be answered DAHDI/2-1
[2017-12-18 21:13:24] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-auto-blkvm:1] Set(“PJSIP/601-000000be”, “__MACRO_RESULT=”) in new stack
[2017-12-18 21:13:24] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-auto-blkvm:2] Set(“PJSIP/601-000000be”, “CFIGNORE=”) in new stack
[2017-12-18 21:13:24] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-auto-blkvm:3] Set(“PJSIP/601-000000be”, “MASTER_CHANNEL(CFIGNORE)=”) in new stack
[2017-12-18 21:13:24] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-auto-blkvm:4] Set(“PJSIP/601-000000be”, “FORWARD_CONTEXT=from-internal”) in new stack
[2017-12-18 21:13:24] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-auto-blkvm:5] Set(“PJSIP/601-000000be”, “MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal”) in new stack
[2017-12-18 21:13:24] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-auto-blkvm:6] Macro(“PJSIP/601-000000be”, “blkvm-clr,”) in new stack
[2017-12-18 21:13:24] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-blkvm-clr:1] Set(“PJSIP/601-000000be”, “SHARED(BLKVM,DAHDI/2-1)=”) in new stack
[2017-12-18 21:13:24] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-blkvm-clr:2] Set(“PJSIP/601-000000be”, “GOSUB_RETVAL=”) in new stack
[2017-12-18 21:13:24] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit(“PJSIP/601-000000be”, “”) in new stack
[2017-12-18 21:13:24] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-auto-blkvm:7] ExecIf(“PJSIP/601-000000be”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=601/sip:[email protected]:5601)”) in new stack
[2017-12-18 21:13:24] VERBOSE[9867][C-00000028] pbx.c: Executing [s@macro-auto-blkvm:8] ExecIf(“PJSIP/601-000000be”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=)”) in new stack
[2017-12-18 21:13:24] VERBOSE[9924][C-00000028] bridge_channel.c: Channel PJSIP/601-000000be joined ‘simple_bridge’ basic-bridge
[2017-12-18 21:13:24] VERBOSE[9867][C-00000028] bridge_channel.c: Channel DAHDI/2-1 joined ‘simple_bridge’ basic-bridge
[2017-12-18 21:13:58] VERBOSE[9867][C-00000028] res_musiconhold.c: Started music on hold, class ‘default’, on channel ‘DAHDI/2-1’
[2017-12-18 21:14:03] VERBOSE[9867][C-00000028] res_musiconhold.c: Stopped music on hold on DAHDI/2-1
[2017-12-18 21:14:10] VERBOSE[9867][C-00000028] res_musiconhold.c: Started music on hold, class ‘default’, on channel ‘DAHDI/2-1’
[2017-12-18 21:14:15] VERBOSE[9867][C-00000028] res_musiconhold.c: Stopped music on hold on DAHDI/2-1

5

indeed, it is in MOH. please check the setting.

6

Does the log indicate why the call waiting feature is not working?

Renato