working system fails now

Discussion in 'General' started by berend, Feb 10, 2009.

  1. berend

    Joined:
    Feb 6, 2009
    Messages:
    31
    Likes Received:
    0
    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
     
  2. rafael

    Joined:
    May 14, 2007
    Messages:
    1,454
    Likes Received:
    1
    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
     
  3. berend

    Joined:
    Feb 6, 2009
    Messages:
    31
    Likes Received:
    0
    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
     

Share This Page