working system fails now

berend

Joined
Feb 6, 2009
Messages
31
Likes
0
Points
0
#1
After a day of trying I finaly got my elastix server working last night. Inbound, Outbound, between exytensions, evreything worked. Took a beer and went to bed. closed the server for the night. This morning when starting things up again, I can't call out and I can't call other extensions. The error log shows: "Call from extension '2000 to ''2001' rejected because extension not found. Both extensions registerd without problem.

Anyone ideas?

Cheers, Berend
 

rafael

Joined
May 14, 2007
Messages
1,454
Likes
1
Points
0
#2
Hi berend and welcome to the Elastix forum,

Can you please give us more information:
- Version of Elastix
- The output of your logs in /var/log/asterisk/full
- What hardware are you using
- When do you get this problem, is just with this extensions or with all

Regards,

Rafael
 

berend

Joined
Feb 6, 2009
Messages
31
Likes
0
Points
0
#3
Hi Rafael,

Thanks for your reaction. I have Elastix 1.3-2 on a dedicated server. I have a Belkin router with firewall and opened ports 5004-5082 for both TCP and UDP and ports 10000-10500 for UDP. For the moment I use xLite 3 as softphone. Receiving calls is no problem.

PROBLEM 1: Calls from one extension to another

Log:
[Feb 10 09:27:55] VERBOSE[2549] logger.c: -- Registered SIP '2000' at 192.168.2.3 port 57302 expires 240
[Feb 10 09:27:55] NOTICE[2549] chan_sip.c: Peer '2000' is now Reachable. (119ms / 2000ms)
[Feb 10 09:28:45] WARNING[2549] rtp.c: Unable to set TOS to 184
[Feb 10 09:28:45] NOTICE[2549] chan_sip.c: Call from '2000' to extension '2001' rejected because extension not found.
[Feb 10 09:29:33] WARNING[2549] rtp.c: Unable to set TOS to 184

Extensions 2000 and 2001 are both registered.

The weird thing is that when I make two new extensions 2050 and 2060 with exactly the same parameters I am able to call the one extension from the other.

Log:

[Feb 10 20:44:01] VERBOSE[2545] logger.c: -- Unregistered SIP '2000'
[Feb 10 20:44:02] VERBOSE[2545] logger.c: -- Registered SIP '2060' at 192.168.2.3 port 2974 expires 3600
[Feb 10 20:44:02] NOTICE[2545] chan_sip.c: Peer '2060' is now Reachable. (10ms / 2000ms)
[Feb 10 20:44:10] WARNING[2545] rtp.c: Unable to set TOS to 184
[Feb 10 20:44:10] VERBOSE[3357] logger.c: -- Executing [2050@from-internal:1] Macro("SIP/2060-b770d530", "exten-vm|novm|2050") in new stack
[Feb 10 20:44:10] VERBOSE[3357] logger.c: -- Executing [s@macro-exten-vm:1] Macro("SIP/2060-b770d530", "user-callerid") in new stack
[Feb 10 20:44:10] VERBOSE[3357] logger.c: -- Executing [s@macro-user-callerid:1] NoOp("SIP/2060-b770d530", "user-callerid: device 2060") in new stack
[Feb 10 20:44:10] DEBUG[3357] app_macro.c: Executed application: Noop
[Feb 10 20:44:10] VERBOSE[3357] logger.c: -- Executing [s@macro-user-callerid:2] Set("SIP/2060-b770d530", "AMPUSER=2060") in new stack
[Feb 10 20:44:10] DEBUG[3357] app_macro.c: Executed application: Set
[Feb 10 20:44:10] VERBOSE[3357] logger.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/2060-b770d530", "0?report") in new stack
[Feb 10 20:44:10] DEBUG[3357] app_macro.c: Executed application: GotoIf
[Feb 10 20:44:10] VERBOSE[3357] logger.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/2060-b770d530", "1|Set|REALCALLERIDNUM=2060") in new stack
[Feb 10 20:44:10] DEBUG[3357] app_macro.c: Executed application: ExecIf
[Feb 10 20:44:10] VERBOSE[3357] logger.c: -- Executing [s@macro-user-callerid:5] NoOp("SIP/2060-b770d530", "REALCALLERIDNUM is 2060") in new stack
[Feb 10 20:44:10] DEBUG[3357] app_macro.c: Executed application: Noop
[Feb 10 20:44:10] VERBOSE[3357] logger.c: -- Executing [s@macro-user-callerid:6] Set("SIP/2060-b770d530", "AMPUSER=2060") in new stack
[Feb 10 20:44:10] DEBUG[3357] app_macro.c: Executed application: Set
[Feb 10 20:44:10] VERBOSE[3357] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/2060-b770d530", "AMPUSERCIDNAME=keimpe") in new stack
[Feb 10 20:44:10] DEBUG[3357] app_macro.c: Executed application: Set
[Feb 10 20:44:10] VERBOSE[3357] logger.c: -- Executing [s@macro-user-callerid:8] GotoIf("SIP/2060-b770d530", "0?report") in new stack
[Feb 10 20:44:10] DEBUG[3357] app_macro.c: Executed application: GotoIf
[Feb 10 20:44:10] VERBOSE[3357] logger.c: -- Executing [s@macro-user-callerid:9] Set("SIP/2060-b770d530", "AMPUSERCID=2060") in new stack
[Feb 10 20:44:10] DEBUG[3357] app_macro.c: Executed application: Set
[Feb 10 20:44:10] VERBOSE[3357] logger.c: -- Executing [s@macro-user-callerid:10] Set("SIP/2060-b770d530", "CALLERID(all)="keimpe" <2060>") in new stack
[Feb 10 20:44:10] DEBUG[3357] app_macro.c: Executed application: Set
[Feb 10 20:44:10] VERBOSE[3357] logger.c: -- Executing [s@macro-user-callerid:11] Set("SIP/2060-b770d530", "REALCALLERIDNUM=2060") in new stack
[Feb 10 20:44:10] DEBUG[3357] app_macro.c: Executed application: Set
[Feb 10 20:44:10] DEBUG[3357] func_db.c: DB: AMPUSER/2060/language not found in database.
[Feb 10 20:44:10] DEBUG[3357] func_db.c: DB: AMPUSER/2060/language not found in database.


PROBLEM 2: Outgoing calls

Below the log of my first succesfull outgoing call

[Feb 9 22:12:36] VERBOSE[14740] logger.c: == Parsing '/etc/asterisk/manager.conf': [Feb 9 22:12:36] VERBOSE[14740] logger.c: Found
[Feb 9 22:12:36] VERBOSE[14740] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': [Feb 9 22:12:36] VERBOSE[14740] logger.c: Found
[Feb 9 22:12:36] VERBOSE[14740] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': [Feb 9 22:12:36] VERBOSE[14740] logger.c: Found
[Feb 9 22:12:36] WARNING[14740] config.c: Unknown directive '#permit=127.0.0.1/255.255.255.0' at line 18 of /etc/asterisk/manager_custom.conf
[Feb 9 22:12:36] VERBOSE[14740] logger.c: == Manager 'admin' logged on from 127.0.0.1
[Feb 9 22:12:36] WARNING[2538] rtp.c: Unable to set TOS to 184
[Feb 9 22:12:36] VERBOSE[14741] logger.c: -- Executing [0566600262@from-sip:1] Dial("SIP/2000-b7606ef0", "SIP/0566600262@Belcentrale") in new stack
[Feb 9 22:12:36] WARNING[14741] rtp.c: Unable to set TOS to 184
[Feb 9 22:12:36] VERBOSE[14741] logger.c: -- Called 0566600262@Belcentrale
[Feb 9 22:12:37] VERBOSE[14741] logger.c: -- SIP/Belcentrale-0a13b400 is ringing
[Feb 9 22:12:38] VERBOSE[14741] logger.c: -- SIP/Belcentrale-0a13b400 is making progress passing it to SIP/2000-b7606ef0
[Feb 9 22:12:45] VERBOSE[14741] logger.c: == Spawn extension (from-sip, 0566600262, 1) exited non-zero on 'SIP/2000-b7606ef0'


The next day when rebooting my server I could not make outside calls anymore.

Log of my first call the next day:

[Feb 10 09:29:33] WARNING[2549] rtp.c: Unable to set TOS to 184
[Feb 10 09:29:33] VERBOSE[2726] logger.c: -- Executing [0566600262@from-sip:1] Dial("SIP/2000-09452f58", "SIP/0566600262@Belcentrale") in new stack
[Feb 10 09:29:33] WARNING[2726] rtp.c: Unable to set TOS to 184
[Feb 10 09:29:33] VERBOSE[2726] logger.c: -- Called 0566600262@Belcentrale
[Feb 10 09:29:33] WARNING[2549] chan_sip.c: Received response: "Forbidden" from '"device" <sip:2000@192.168.2.100>;tag=as523787f8'
[Feb 10 09:29:33] VERBOSE[2726] logger.c: -- SIP/Belcentrale-09456ed0 is circuit-busy
[Feb 10 09:29:33] VERBOSE[2726] logger.c: == Everyone is busy/congested at this time (1:0/1/0)
[Feb 10 09:29:33] VERBOSE[2726] logger.c: -- Executing [0566600262@from-sip:2] Congestion("SIP/2000-09452f58", "") in new stack
[Feb 10 09:29:33] VERBOSE[2726] logger.c: == Spawn extension (from-sip, 0566600262, 2) exited non-zero on 'SIP/2000-09452f58


My VSP says that they need the Caller Id for outgoing calls to be equal to my username. The say that the username has to be inserted in the CallerId for each extension. Since all their usernames are in the form 0000*000 that username can
 

Members online

No members online now.

Latest posts

Forum statistics

Threads
30,966
Messages
131,025
Members
17,676
Latest member
Ruddy
Top