FreePBX

I see the inbound call, it rings my extension (201) and as I answer it, I see on my extension "Call Ended" but the remote end doesn't see this and stays connected (though silent and useless).

The following is the output of 'asterisk -vvvvvvvvvvr' of an inbound call... I've pressed enter a few times between the extension ringing and being answered...

=========================================================================
  == Parsing '/etc/asterisk/asterisk.conf': Found
Connected to Asterisk 1.4.26.2 currently running on asterisknow (pid = 3248)
Verbosity is at least 10
    -- Executing [611355@from-trunk-sip-AQL:1] Set("SIP/AQL-08c19148", "GROUP()=OUT_2") in new stack
    -- Executing [611355@from-trunk-sip-AQL:2] Goto("SIP/AQL-08c19148", "from-trunk|611355|1") in new stack
    -- Goto (from-trunk,611355,1)
    -- Executing [611355@from-trunk:1] NoOp("SIP/AQL-08c19148", "Catch-All DID Match - Found 611355 - You probably want a DID for this.") in new stack
    -- Executing [611355@from-trunk:2] Goto("SIP/AQL-08c19148", "ext-did|s|1") in new stack
    -- Goto (ext-did,s,1)
    -- Executing [s@ext-did:1] Set("SIP/AQL-08c19148", "__FROM_DID=s") in new stack
    -- Executing [s@ext-did:2] Gosub("SIP/AQL-08c19148", "app-blacklist-check|s|1") in new stack
    -- Executing [s@app-blacklist-check:1] LookupBlacklist("SIP/AQL-08c19148", "") in new stack
    -- Executing [s@app-blacklist-check:2] GotoIf("SIP/AQL-08c19148", "0?blacklisted") in new stack
    -- Executing [s@app-blacklist-check:3] Set("SIP/AQL-08c19148", "CALLED_BLACKLIST=1") in new stack
    -- Executing [s@app-blacklist-check:4] Return("SIP/AQL-08c19148", "") in new stack
    -- Executing [s@ext-did:3] ExecIf("SIP/AQL-08c19148", "0 |Set|CALLERID(name)=anonymous") in new stack
    -- Executing [s@ext-did:4] Set("SIP/AQL-08c19148", "FAX_RX=disabled") in new stack
    -- Executing [s@ext-did:5] Set("SIP/AQL-08c19148", "__CALLINGPRES_SV=allowed_not_screened") in new stack
    -- Executing [s@ext-did:6] SetCallerPres("SIP/AQL-08c19148", "allowed_not_screened") in new stack
    -- Executing [s@ext-did:7] Goto("SIP/AQL-08c19148", "from-did-direct|201|1") in new stack
    -- Goto (from-did-direct,201,1)
    -- Executing [201@from-did-direct:1] Macro("SIP/AQL-08c19148", "exten-vm|201|201") in new stack
    -- Executing [s@macro-exten-vm:1] Macro("SIP/AQL-08c19148", "user-callerid") in new stack
    -- Executing [s@macro-user-callerid:1] Set("SIP/AQL-08c19148", "AMPUSER=anonymous") in new stack
    -- Executing [s@macro-user-callerid:2] GotoIf("SIP/AQL-08c19148", "0?report") in new stack
    -- Executing [s@macro-user-callerid:3] ExecIf("SIP/AQL-08c19148", "1|Set|REALCALLERIDNUM=anonymous") in new stack
    -- Executing [s@macro-user-callerid:4] Set("SIP/AQL-08c19148", "AMPUSER=") in new stack
    -- Executing [s@macro-user-callerid:5] Set("SIP/AQL-08c19148", "AMPUSERCIDNAME=") in new stack
    -- Executing [s@macro-user-callerid:6] GotoIf("SIP/AQL-08c19148", "1?report") in new stack
    -- Goto (macro-user-callerid,s,9)
    -- Executing [s@macro-user-callerid:9] GotoIf("SIP/AQL-08c19148", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:10] Set("SIP/AQL-08c19148", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:11] GotoIf("SIP/AQL-08c19148", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,18)
    -- Executing [s@macro-user-callerid:18] NoOp("SIP/AQL-08c19148", "Using CallerID "anonymous" ") in new stack
    -- Executing [s@macro-exten-vm:2] Set("SIP/AQL-08c19148", "RingGroupMethod=none") in new stack
    -- Executing [s@macro-exten-vm:3] Set("SIP/AQL-08c19148", "VMBOX=201") in new stack
    -- Executing [s@macro-exten-vm:4] Set("SIP/AQL-08c19148", "EXTTOCALL=201") in new stack
    -- Executing [s@macro-exten-vm:5] Set("SIP/AQL-08c19148", "CFUEXT=") in new stack
    -- Executing [s@macro-exten-vm:6] Set("SIP/AQL-08c19148", "CFBEXT=") in new stack
    -- Executing [s@macro-exten-vm:7] Set("SIP/AQL-08c19148", "RT=15") in new stack
    -- Executing [s@macro-exten-vm:8] Macro("SIP/AQL-08c19148", "record-enable|201|IN") in new stack
    -- Executing [s@macro-record-enable:1] GotoIf("SIP/AQL-08c19148", "1?check") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing [s@macro-record-enable:4] AGI("SIP/AQL-08c19148", "recordingcheck|20090928-200458|1254164698.42") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
  recordingcheck|20090928-200458|1254164698.42: Inbound recording not enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing [s@macro-record-enable:5] MacroExit("SIP/AQL-08c19148", "") in new stack
    -- Executing [s@macro-exten-vm:9] Macro("SIP/AQL-08c19148", "dial|15|tr|201") in new stack
    -- Executing [s@macro-dial:1] GotoIf("SIP/AQL-08c19148", "1?dial") in new stack
   -- Goto (macro-dial,s,3)
    -- Executing [s@macro-dial:3] AGI("SIP/AQL-08c19148", "dialparties.agi") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  dialparties.agi: Starting New Dialparties.agi
  == Parsing '/etc/asterisk/manager.conf': Found
  == Parsing '/etc/asterisk/manager_additional.conf': Found
  == Parsing '/etc/asterisk/manager_custom.conf': Found
  == Manager 'admin' logged on from 127.0.0.1
  dialparties.agi: Caller ID name is 'anonymous' number is 'anonymous'
  dialparties.agi: USE_CONFIRMATION:  'FALSE'
  dialparties.agi: RINGGROUP_INDEX:   ''
  dialparties.agi: Methodology of ring is  'none'
    --  dialparties.agi: Added extension 201 to extension map
       >  dialparties.agi: Extension 201 has call screening off
    --  dialparties.agi: Extension 201 cf is disabled
    --  dialparties.agi: Extension 201 do not disturb is disabled
       >  dialparties.agi: extnum 201 has:  cw: 1; hascfb: 0 [] hascfu: 0 []
  dialparties.agi: ExtensionState: 0
    --  dialparties.agi: DbDel CALLTRACE/201 - Caller ID is not defined
    --  dialparties.agi: Filtered ARG3: 201
  == Manager 'admin' logged off from 127.0.0.1
    -- AGI Script dialparties.agi completed, returning 0
    -- Executing [s@macro-dial:7] Dial("SIP/AQL-08c19148", "SIP/201|15|tr") in new stack
    -- Called 201
    -- SIP/201-08c13900 is ringing
asterisknow*CLI> 
asterisknow*CLI> 
asterisknow*CLI> 
asterisknow*CLI> 
asterisknow*CLI> 
asterisknow*CLI> 
asterisknow*CLI> 
    -- SIP/201-08c13900 answered SIP/AQL-08c19148
    -- Executing [h@macro-dial:1] Macro("SIP/AQL-08c19148", "hangupcall") in new stack
    -- Executing [s@macro-hangupcall:1] GotoIf("SIP/AQL-08c19148", "1?skiprg") in new stack
    -- Goto (macro-hangupcall,s,4)
    -- Executing [s@macro-hangupcall:4] GotoIf("SIP/AQL-08c19148", "1?skipblkvm") in new stack
    -- Goto (macro-hangupcall,s,7)
    -- Executing [s@macro-hangupcall:7] GotoIf("SIP/AQL-08c19148", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,9)
    -- Executing [s@macro-hangupcall:9] Hangup("SIP/AQL-08c19148", "") in new stack
  == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/AQL-08c19148' in macro 'hangupcall'
  == Spawn h extension (macro-dial, h, 1) exited non-zero on 'SIP/AQL-08c19148'
  == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/AQL-08c19148' in macro 'dial'
  == Spawn extension (macro-exten-vm, s, 9) exited non-zero on 'SIP/AQL-08c19148' in macro 'exten-vm'
  == Spawn extension (from-did-direct, 201, 1) exited non-zero on 'SIP/AQL-08c19148'
asterisknow*CLI>