Hello,
I am new to FreePBX and after 3 days fighting I could get Freebox to (almost) work
on an OpenSuse 10 server.
Softphones can register, can call outbound, have audio, save message
to voicemail…
But, very annoying, extensions cannot be reached.
When an extension calls another extension the phone does
not ring and X-Lite displays “Call failed: Declined”. It is not even
routed to the voicemail as expected according to the extension
configuration.
With the announcement set to an extension
when I call 7777 I get the same symptom: call failed,
no voicemail.
With the announcement set to IVR, I get the IVR scenario,
as long as it does not route to an extension.
I copied an extract of the full file below.
I mention that my FreeBox server is connected directly to Internet
(no NAT) and my softphones are behind a router using NAT.
Since I can register my softphones and since I have audio,
I assume that it is not a firewall/router issue.
Aside question:
Since I havn’t added any forwarding rules in the router
I wonder by what magic it does work (sip registration, dial and audio) ?
My understanding is that SIP uses UDP, how these UDP
packets send by the FreePBX server do reach my softphone
across the router ?
I am puzzled. Is my router very clever ?
The second symptom is that FOP remain empty (except for parkings).
Any help would be greatly appreciated
Best Regards
Phil Ten
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: Set
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Executing [s@macro-user-callerid:8] Set(“SIP/1001-40a02dc8”, “AMPUSERCIDNAME=PHIL1”) in new stack
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: Set
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Executing [s@macro-user-callerid:9] GotoIf(“SIP/1001-40a02dc8”, “0?report”) in new stack
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: GotoIf
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Executing [s@macro-user-callerid:10] Set(“SIP/1001-40a02dc8”, “AMPUSERCID=1001”) in new stack
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: Set
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Executing [s@macro-user-callerid:11] Set(“SIP/1001-40a02dc8”, “CALLERID(all)=“PHIL1” <1001>”) in new stack
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: Set
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Executing [s@macro-user-callerid:12] Set(“SIP/1001-40a02dc8”, “REALCALLERIDNUM=1001”) in new stack
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: Set
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Executing [s@macro-user-callerid:13] NoOp(“SIP/1001-40a02dc8”, “TTL: 64 ARG1: SKIPTTL”) in new stack
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: Noop
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Executing [s@macro-user-callerid:14] GotoIf(“SIP/1001-40a02dc8”, “1?continue”) in new stack
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Goto (macro-user-callerid,s,23)
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: GotoIf
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Executing [s@macro-user-callerid:23] NoOp(“SIP/1001-40a02dc8”, “Using CallerID “PHIL1” <1001>”) in new stack
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: NoOp
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: Macro
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Executing [s@macro-vm:2] Set(“SIP/1001-40a02dc8”, “VMGAIN=”"") in new stack
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: Set
[Nov 26 09:41:13] WARNING[25019] func_db.c: DB requires an argument, DB(/)
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Executing [s@macro-vm:3] GotoIf(“SIP/1001-40a02dc8”, “1?vmx|1”) in new stack
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Goto (macro-vm,vmx,1)
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: GotoIf
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Executing [vmx@macro-vm:1] GotoIf(“SIP/1001-40a02dc8”, “0?s-|1”) in new stack
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: GotoIf
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Executing [vmx@macro-vm:2] Set(“SIP/1001-40a02dc8”, “MODE=unavail”) in new stack
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: Set
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Executing [vmx@macro-vm:3] GotoIf(“SIP/1001-40a02dc8”, “1?notdirect”) in new stack
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Goto (macro-vm,vmx,5)
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: GotoIf
[Nov 26 09:41:13] DEBUG[25019] func_db.c: DB: AMPUSER/1000/vmx/unavail/state not found in database.
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Executing [vmx@macro-vm:5] NoOp(“SIP/1001-40a02dc8”, "Checking if ext 1000 is enabled: ") in new stack
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: Noop
[Nov 26 09:41:13] DEBUG[25019] func_db.c: DB: AMPUSER/1000/vmx/unavail/state not found in database.
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Executing [vmx@macro-vm:6] GotoIf(“SIP/1001-40a02dc8”, “1?s-|1”) in new stack
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Goto (macro-vm,s-,1)
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: GotoIf
[Nov 26 09:41:13] DEBUG[25019] app_macro.c: Executed application: Macro
[Nov 26 09:41:13] VERBOSE[25019] logger.c: – Executing [1000@from-did-direct:2] Hangup(“SIP/1001-40a02dc8”, “”) in new stack
[Nov 26 09:41:13] VERBOSE[25019] logger.c: == Spawn extension (from-did-direct, 1000, 2) exited non-zero on ‘SIP/1001-40a02dc8’
[Nov 26 09:41:13] NOTICE[25019] cdr.c: CDR on channel ‘SIP/1001-40a02dc8’ not posted
[Nov 26 09:44:16] VERBOSE[25072] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [Nov 26 09:44:16] VERBOSE[25072] logger.c: Found
[Nov 26 09:44:16] VERBOSE[25072] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[Nov 26 09:44:16] VERBOSE[25072] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[Nov 26 09:44:17] VERBOSE[25074] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [Nov 26 09:44:17] VERBOSE[25074] logger.c: Found