Hi everyone,
I’ve a problem with external calls:
when customers call the office the phone starts ringing, but I can’t answer and, what is worst, the caller still hears the phone ringing… as if the office was empty. 
This problem began 2 weeks ago.
Do you have any advice?
If you need any additional information just ask and I’ll reply. 
Thank you very much,
Emanuele
What do you see on Asterisk CLI when call is made and answered by your phone?
What technology do you use to receive external calls and for your phone?
–Satish Barot
From Asterisk CLI:
[quote][2015-04-30 09:49:45] WARNING[2396][C-00000004]: func_callerid.c:910 callerpres_read: CALLERPRES is deprecated. Use CALLERID(name-pres) or CALLERID(num-pres) instead.
[2015-04-30 09:49:45] WARNING[2396][C-00000004]: res_musiconhold.c:846 set_moh_exec: SetMusicOnHold application is deprecated and will be removed. Use Set(CHANNEL(musicclass)=…) instead
[/quote]
Emanuele
Increase the verbosity to at least 5. You may need to enable other debugging options later.
This is the log file with verbosity 5:
[quote][2015-04-30 11:41:07] VERBOSE[1894] chan_iax2.c: – Accepting UNAUTHENTICATED call from 192.168.14.221:
ü – > requested format = ulaw,
ü – > requested prefs = (ulaw|slin|alaw),
ü – > actual format = ulaw,
ü – > host prefs = (gsm|ulaw|alaw|h263),
ü – > priority = mine
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@from-pstn:1] Goto(“IAX2/acege14-66”, “ext-did,14000,1”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (ext-did,14000,1)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@ext-did:1] Set(“IAX2/acege14-66”, “__FROM_DID=14000”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@ext-did:2] Set(“IAX2/acege14-66”, “CDR(did)=14000”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@ext-did:3] ExecIf(“IAX2/acege14-66”, “0 ?Set(CALLERID(name)=3491331316)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@ext-did:4] Set(“IAX2/acege14-66”, “CHANNEL(musicclass)=default”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@ext-did:5] Set(“IAX2/acege14-66”, “__MOHCLASS=default”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@ext-did:6] Set(“IAX2/acege14-66”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@ext-did:7] Set(“IAX2/acege14-66”, “CALLERPRES()=allowed_not_screened”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@ext-did:8] Goto(“IAX2/acege14-66”, “timeconditions,1,1”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (timeconditions,1,1)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [1@timeconditions:1] GotoIfTime(“IAX2/acege14-66”, “14:00-18:00,mon-fri,1-31,jan-dec?truestate”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [1@timeconditions:2] GotoIfTime(“IAX2/acege14-66”, “08:00-12:00,mon-fri,1-31,jan-dec?truestate”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (timeconditions,1,10)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [1@timeconditions:10] GotoIf(“IAX2/acege14-66”, “0?falsegoto”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [1@timeconditions:11] ExecIf(“IAX2/acege14-66”, “0?Set(DB(TC/1)=)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [1@timeconditions:12] Set(“IAX2/acege14-66”, “DEVICE_STATE(Custom:TC1)=NOT_INUSE”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [1@timeconditions:13] ExecIf(“IAX2/acege14-66”, “0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [1@timeconditions:14] GotoIf(“IAX2/acege14-66”, “1?ext-group,14001,1”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (ext-group,14001,1)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:1] Macro(“IAX2/acege14-66”, “user-callerid,”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:1] Set(“IAX2/acege14-66”, “TOUCH_MONITOR=1430386867.78”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:2] Set(“IAX2/acege14-66”, “AMPUSER=3491331316”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:3] GotoIf(“IAX2/acege14-66”, “0?report”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:4] ExecIf(“IAX2/acege14-66”, “1?Set(REALCALLERIDNUM=3491331316)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:5] Set(“IAX2/acege14-66”, “AMPUSER=”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“IAX2/acege14-66”, “0?limit”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:7] Set(“IAX2/acege14-66”, “AMPUSERCIDNAME=”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:8] GotoIf(“IAX2/acege14-66”, “1?report”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (macro-user-callerid,s,15)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:15] GotoIf(“IAX2/acege14-66”, “0?continue”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:16] ExecIf(“IAX2/acege14-66”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:17] Set(“IAX2/acege14-66”, “__TTL=64”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:18] GotoIf(“IAX2/acege14-66”, “1?continue”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (macro-user-callerid,s,29)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:29] Set(“IAX2/acege14-66”, “CALLERID(number)=3491331316”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:30] Set(“IAX2/acege14-66”, “CALLERID(name)=orgdab”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:31] Set(“IAX2/acege14-66”, “CDR(cnum)=3491331316”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:32] Set(“IAX2/acege14-66”, “CDR(cnam)=orgdab”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:33] Set(“IAX2/acege14-66”, “CHANNEL(language)=it”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:2] Macro(“IAX2/acege14-66”, “blkvm-setifempty,”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-blkvm-setifempty:1] GotoIf(“IAX2/acege14-66”, “1?init”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (macro-blkvm-setifempty,s,4)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-blkvm-setifempty:4] Set(“IAX2/acege14-66”, “__BLKVM_CHANNEL=IAX2/acege14-66”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-blkvm-setifempty:5] Set(“IAX2/acege14-66”, “SHARED(BLKVM,IAX2/acege14-66)=TRUE”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-blkvm-setifempty:6] Set(“IAX2/acege14-66”, “GOSUB_RETVAL=TRUE”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-blkvm-setifempty:7] MacroExit(“IAX2/acege14-66”, “”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:3] GotoIf(“IAX2/acege14-66”, “1?skipov”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (ext-group,14001,6)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:6] Set(“IAX2/acege14-66”, “RRNODEST=”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:7] Set(“IAX2/acege14-66”, “__NODEST=14001”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:8] GosubIf(“IAX2/acege14-66”, “0?sub-rgsetcid,s,1()”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:9] Gosub(“IAX2/acege14-66”, “sub-record-check,s,1(rg,14001,dontcare)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:1] Set(“IAX2/acege14-66”, “REC_POLICY_MODE_SAVE=”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:2] GotoIf(“IAX2/acege14-66”, “1?check”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (sub-record-check,s,7)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:7] Set(“IAX2/acege14-66”, “__MON_FMT=wav”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:8] GotoIf(“IAX2/acege14-66”, “1?next”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (sub-record-check,s,11)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:11] ExecIf(“IAX2/acege14-66”, “0?Return()”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:12] ExecIf(“IAX2/acege14-66”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:13] GotoIf(“IAX2/acege14-66”, “0?rg,1”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:14] Set(“IAX2/acege14-66”, “__REC_STATUS=INITIALIZED”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:15] Set(“IAX2/acege14-66”, “NOW=1430386867”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:16] Set(“IAX2/acege14-66”, “__DAY=30”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:17] Set(“IAX2/acege14-66”, “__MONTH=04”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:18] Set(“IAX2/acege14-66”, “__YEAR=2015”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:19] Set(“IAX2/acege14-66”, “__TIMESTR=20150430-114107”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:20] Set(“IAX2/acege14-66”, “__FROMEXTEN=3491331316”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:21] Set(“IAX2/acege14-66”, “__CALLFILENAME=rg-14001-3491331316-20150430-114107-1430386867.78”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:22] Goto(“IAX2/acege14-66”, “rg,1”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (sub-record-check,rg,1)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [rg@sub-record-check:1] GosubIf(“IAX2/acege14-66”, “0?record,1(rg,dontcare,3491331316)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [rg@sub-record-check:2] Return(“IAX2/acege14-66”, “”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:10] Set(“IAX2/acege14-66”, “RingGroupMethod=ringall”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:11] Macro(“IAX2/acege14-66”, “dial,60,m(default)Tt,1441-1460-1461-1451”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-dial:1] GotoIf(“IAX2/acege14-66”, “0?dial”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-dial:2] SetMusicOnHold(“IAX2/acege14-66”, “default”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-dial:3] AGI(“IAX2/acege14-66”, “dialparties.agi”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: dialparties.agi: Caller ID name is ‘orgdab’ number is ‘3491331316’
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: dialparties.agi: Methodology of ring is ‘ringall’
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Added extension 1441 to extension map
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Added extension 1460 to extension map
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Added extension 1461 to extension map
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Added extension 1451 to extension map
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Extension 1441 cf is disabled
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Extension 1460 cf is disabled
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Extension 1461 cf is disabled
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Extension 1451 cf is disabled
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Extension 1441 do not disturb is disabled
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Extension 1460 do not disturb is disabled
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Extension 1461 do not disturb is disabled
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Extension 1451 do not disturb is disabled
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: dbset CALLTRACE/1441 to 3491331316
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: dbset CALLTRACE/1460 to 3491331316
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: dbset CALLTRACE/1461 to 3491331316
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: dbset CALLTRACE/1451 to 3491331316
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Filtered ARG3: 1441-1460-1461-1451
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – <IAX2/acege14-66>AGI Script dialparties.agi completed, returning 0
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-dial:7] Dial(“IAX2/acege14-66”, “SIP/1441&SIP/1460&SIP/1461&SIP/1451,60,m(default)TtM(auto-blkvm)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] netsock2.c: == Using SIP RTP TOS bits 184
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] netsock2.c: == Using SIP RTP CoS mark 5
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] netsock2.c: == Using SIP RTP TOS bits 184
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] netsock2.c: == Using SIP RTP CoS mark 5
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] netsock2.c: == Using SIP RTP TOS bits 184
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] netsock2.c: == Using SIP RTP CoS mark 5
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] netsock2.c: == Using SIP RTP TOS bits 184
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] netsock2.c: == Using SIP RTP CoS mark 5
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Ringing for Notify User 1460
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Ringing for Notify User 1441
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Ringing for Notify User 1451
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – Called SIP/1441
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Ringing for Notify User 1460
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Ringing for Notify User 1451
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Ringing for Notify User 1441
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – Called SIP/1460
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – Called SIP/1461
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Ringing for Notify User 1460
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Ringing for Notify User 1451
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Ringing for Notify User 1441
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – Called SIP/1451
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_musiconhold.c: – Started music on hold, class ‘default’, on IAX2/acege14-66
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1451-0000003f connected line has changed. Saving it until answer for IAX2/acege14-66
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1461-0000003e connected line has changed. Saving it until answer for IAX2/acege14-66
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1460-0000003d connected line has changed. Saving it until answer for IAX2/acege14-66
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1441-0000003c connected line has changed. Saving it until answer for IAX2/acege14-66
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Ringing for Notify User 1460
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Ringing for Notify User 1441 (queued)
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Ringing for Notify User 1451
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1441-0000003c is ringing
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1461-0000003e is ringing
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Ringing for Notify User 1460 (queued)
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Ringing for Notify User 1451
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Ringing for Notify User 1441 (queued)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1460-0000003d is ringing
[2015-04-30 11:41:08] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Ringing for Notify User 1460 (queued)
[2015-04-30 11:41:08] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Ringing for Notify User 1451 (queued)
[2015-04-30 11:41:08] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Ringing for Notify User 1441 (queued)
[2015-04-30 11:41:08] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1451-0000003f is ringing
[2015-04-30 11:41:08] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1441-0000003c is ringing
[2015-04-30 11:41:08] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1451-0000003f is ringing
[2015-04-30 11:41:08] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1451-0000003f is ringing
[2015-04-30 11:41:08] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1460-0000003d is ringing
[2015-04-30 11:41:09] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1441-0000003c is ringing
[2015-04-30 11:41:09] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1451-0000003f is ringing
[2015-04-30 11:41:09] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1460-0000003d is ringing
[2015-04-30 11:41:11] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1441-0000003c is ringing
[2015-04-30 11:41:11] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1451-0000003f is ringing
[2015-04-30 11:41:11] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1460-0000003d is ringing
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state InUse for Notify User 1460
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state InUse for Notify User 1451
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state InUse for Notify User 1441
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1451-0000003f connected line has changed. Saving it until answer for IAX2/acege14-66
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1451-0000003f answered IAX2/acege14-66
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Idle for Notify User 1460
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Idle for Notify User 1441
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Idle for Notify User 1451
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Idle for Notify User 1460
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Idle for Notify User 1451
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Idle for Notify User 1441
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:1] Set(“SIP/1451-0000003f”, “__MACRO_RESULT=”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:2] Set(“SIP/1451-0000003f”, “CFIGNORE=”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:3] Set(“SIP/1451-0000003f”, “MASTER_CHANNEL(CFIGNORE)=”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:4] Set(“SIP/1451-0000003f”, “FORWARD_CONTEXT=from-internal”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:5] Set(“SIP/1451-0000003f”, “MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:6] Macro(“SIP/1451-0000003f”, “blkvm-clr,”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-blkvm-clr:1] Set(“SIP/1451-0000003f”, “SHARED(BLKVM,IAX2/acege14-66)=”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-blkvm-clr:2] Set(“SIP/1451-0000003f”, “GOSUB_RETVAL=”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-blkvm-clr:3] MacroExit(“SIP/1451-0000003f”, “”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:7] ExecIf(“SIP/1451-0000003f”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=1451)”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:8] ExecIf(“SIP/1451-0000003f”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=Tecnici ORG-DAB)”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] res_musiconhold.c: – Stopped music on hold on IAX2/acege14-66
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] pbx.c: – Executing [h@macro-dial:1] Macro(“IAX2/acege14-66”, “hangupcall”) in new stack
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“IAX2/acege14-66”, “1?theend”) in new stack
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] pbx.c: – Goto (macro-hangupcall,s,3)
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-hangupcall:3] ExecIf(“IAX2/acege14-66”, “0?Set(CDR(recordingfile)=)”) in new stack
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-hangupcall:4] Hangup(“IAX2/acege14-66”, “”) in new stack
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘IAX2/acege14-66’ in macro ‘hangupcall’
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] pbx.c: == Spawn extension (macro-dial, h, 1) exited non-zero on ‘IAX2/acege14-66’
[2015-04-30 11:41:19] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Idle for Notify User 1460
[2015-04-30 11:41:19] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Idle for Notify User 1451
[2015-04-30 11:41:19] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Idle for Notify User 1441
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on ‘IAX2/acege14-66’ in macro ‘dial’
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] pbx.c: == Spawn extension (ext-group, 14001, 11) exited non-zero on ‘IAX2/acege14-66’
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] chan_iax2.c: – Hungup ‘IAX2/acege14-66’[/quote]
This looks like GUI generated dialplan. We do not support any GUI on this board.
You have rung three devices in parallel. One of them has answered, and then hung up after 7 seconds. There are no obvious signs that anything went wrong. If that is not what happened with the phones, you will need to enable SIP debugging.
He is using a ring group with methodology of ring is ‘ringall’, You should disable that ring group and route call directly to an extension and make a trace of call
[quote]david55:
You have rung three devices in parallel. One of them has answered, and then hung up after 7 seconds. There are no obvious signs that anything went wrong. If that is not what happened with the phones, you will need to enable SIP debugging.[/quote]
Yes, that isn’t what happened. I’ll try to enable SIP debugging and post the log file.
[quote]ambiorixg12:
You should disable that ring group and route call directly to an extension and make a trace of call[/quote]
How can I make a trace of the call?