Avaya 9650 jeder zweite Call

Moderatoren: rainer, Tim

Antworten
beachballer
Anfänger
Beiträge: 7
Registriert: Di 14. Mai 2013, 15:25

Avaya 9650 jeder zweite Call

Beitrag von beachballer » Di 14. Mai 2013, 15:32

Hallo zusammen,

ich habe eine Asterisk Anlage konfiguriert und bin erstaunt wie gut die Anlage funktioniert. Ich teste aktuell ein Avaya Hardphone in Verbindung mit der Asterisk. Soweit geht alles. Das Hardphone wird von SIP Endgeräten erreicht, kann Queuecalls empfangen unsw.

Nun zu meiner Herrausforderung. Intere Calls sind nur jedes zweite mal erfolgreich. D.h. sofern ich am Hardphone z.b. die Extension 5574 anrufe, bekomme ich jedes zweite mal ein busy Ton zu hören. Die anderen 50% der Calls funktionieren. Habt Ihr eine Idee was dieses Verhalten hervorruft?

Anbei das Debug Log eines fehlerhaften Calls.

Code: Alles auswählen

[2013-05-14 15:14:58] DEBUG[27785]: chan_sip.c:9128 find_call: = Looking for  Call ID: 18_154f6f-5a495ae751a79659_I@10.12.0.66 (Checking From) --From tag 69dbd1d6519246e151a796fc_F123410.12.0.66 --To-tag
[2013-05-14 15:14:58] DEBUG[27785]: logger.c:1302 ast_create_callid: CALL_ID [C-0000002c] created by thread.
[2013-05-14 15:14:58] DEBUG[27785]: acl.c:979 ast_ouraddrfor: For destination '10.12.0.66', our source address is '10.12.1.65'.
[2013-05-14 15:14:58] DEBUG[27785]: chan_sip.c:4026 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 10.12.1.65:5060
[2013-05-14 15:14:58] DEBUG[27785]: chan_sip.c:8731 sip_alloc: Allocating new SIP dialog for 18_154f6f-5a495ae751a79659_I@10.12.0.66 - INVITE (No RTP)
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: logger.c:1332 ast_callid_threadassoc_add: CALL_ID [C-0000002c] bound to thread.
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: chan_sip.c:27928 handle_incoming: **** Received INVITE (5) - Command in SIP INVITE
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: sip/reqresp_parser.c:1603 parse_sip_options: Begin: parsing SIP "Supported: eventlist, 100rel"
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: sip/reqresp_parser.c:1619 parse_sip_options: Found SIP option: -eventlist-
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: sip/reqresp_parser.c:1627 parse_sip_options: Matched SIP option: eventlist
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: sip/reqresp_parser.c:1619 parse_sip_options: Found SIP option: -100rel-
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: sip/reqresp_parser.c:1627 parse_sip_options: Matched SIP option: 100rel
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '10.12.0.66' into...
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '10.12.0.66' and port ''.
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: chan_sip.c:17908 check_via: NAT detected for 10.12.0.66:5060 / 10.12.0.66:1040
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '10.12.1.65' into...
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '10.12.1.65' and port ''.
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: chan_sip.c:3869 __sip_xmit: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.12.0.66:5060
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: logger.c:1354 ast_callid_threadassoc_remove: Call_ID [C-0000002c] being removed from thread.
[2013-05-14 15:14:58] DEBUG[27785]: chan_sip.c:9128 find_call: = Looking for  Call ID: 18_154f6f-5a495ae751a79659_I@10.12.0.66 (Checking From) --From tag 69dbd1d6519246e151a796fc_F123410.12.0.66 --To-tag as3489d03d
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: logger.c:1332 ast_callid_threadassoc_add: CALL_ID [C-0000002c] bound to thread.
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: chan_sip.c:27928 handle_incoming: **** Received ACK (6) - Command in SIP ACK
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: chan_sip.c:4561 __sip_ack: Stopping retransmission on '18_154f6f-5a495ae751a79659_I@10.12.0.66' of Response 24: Match Found
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: logger.c:1354 ast_callid_threadassoc_remove: Call_ID [C-0000002c] being removed from thread.
[2013-05-14 15:14:58] DEBUG[27785]: chan_sip.c:9128 find_call: = Looking for  Call ID: 18_154f6f-5a495ae751a79659_I@10.12.0.66 (Checking From) --From tag 69dbd1d6519246e151a796fc_F123410.12.0.66 --To-tag as3489d03d
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: logger.c:1332 ast_callid_threadassoc_add: CALL_ID [C-0000002c] bound to thread.
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: chan_sip.c:27928 handle_incoming: **** Received ACK (6) - Command in SIP ACK
[2013-05-14 15:14:58] DEBUG[27785][C-0000002c]: logger.c:1354 ast_callid_threadassoc_remove: Call_ID [C-0000002c] being removed from thread.
[2013-05-14 15:15:02] DEBUG[27785]: chan_sip.c:4417 __sip_autodestruct: Auto destroying SIP dialog '2d863a8653fddcff0dfcd3ef01f8d99a@10.12.1.65:5060'
[2013-05-14 15:15:02] DEBUG[27785]: chan_sip.c:6822 sip_destroy: Destroying SIP dialog 2d863a8653fddcff0dfcd3ef01f8d99a@10.12.1.65:5060
[2013-05-14 15:15:02] DEBUG[27785]: rtp_engine.c:226 instance_destructor: Destroyed RTP instance '0x7f8fb8200008'
localhost*CLI>
Bitte gebt mir Bescheid sofern mehr Infos benötigt.

Nachtrag: Selbst wenn ich nur mit der Asterisk kommuniziere. (Z.b: *60 ==> Uhrzeit ansagen) geht nur jeder zweite Call! Was kann das Problem mit dem Hardpone sein? Exakt jeder zweite Call geht...

==> Bei einem Softphone geht jeder Call!


Liebe Grüße

Beachballer

beachballer
Anfänger
Beiträge: 7
Registriert: Di 14. Mai 2013, 15:25

UPDATE: Avaya 9650 jeder zweite Call

Beitrag von beachballer » Do 16. Mai 2013, 18:54

Hallo zusammen,

ich habe gemeinsam mit einem Kollegen den Fehler gefunden, leider aber keine Ahnung was ich tun kann um diesen zu beheben. Der Fehler resultiert aus der falschen Portzuordnung.

Jeder funktionierende Call wird nicht genattet:

Code: Alles auswählen

[2013-05-16 18:20:08] DEBUG[808][C-0000002a]: chan_sip.c:17908 check_via: NAT detected for 10.12.0.66:5060 / 10.12.0.66:1046
Sending to 10.12.0.66:5060 (no NAT)
Jeder nicht funktionierende Call wird genattet:

Code: Alles auswählen

[2013-05-16 18:18:38] DEBUG[808][C-00000029]: chan_sip.c:17908 check_via: NAT detected for 10.12.0.66:5060 / 10.12.0.66:1046
Sending to 10.12.0.66:1046 (NAT)
Was kann ich tun das Nat für diese Extension (oder allgemein) abgestellt wird?

Liebe Grüße

Beachballer

beachballer
Anfänger
Beiträge: 7
Registriert: Di 14. Mai 2013, 15:25

Re: Avaya 9650 jeder zweite Call

Beitrag von beachballer » Di 21. Mai 2013, 09:51

Wieso antwortet eingeltich keiner? Ist die Frage zu ungenau? :oops:

Benutzeravatar
rainer
Profi
Beiträge: 183
Registriert: Di 17. Nov 2009, 20:18
Wohnort: Bonn
Kontaktdaten:

Re: Avaya 9650 jeder zweite Call

Beitrag von rainer » Di 21. Mai 2013, 17:41

Hi Beachballer,

- welche Asterisk-Version ist installiert? Die 11.4.0 ?
- ist im Avaya 9650 ein STUN Server konfiguriert?
- ist in der sip.conf das Konto vom Avaya 9650 mit nat=no eingetragen?
- steht in der sip.conf unter [general] nat=no oder localnet=10.12.0.0/255.255.255.0 oder localnet=10.12.0.0/255.255.0.0

Gruß
Rainer
Rainer Piper - Bonn - 0228 97167161 or SIP-URI: sip:7000@sip.soho-piper.de:5072
Software: kamailio 4.2.0 -> Asterisk 13.0.1 mit pjsip stack
Hardware: 2x QuadCore, 128GB ECC-RAM, 4x HDD Raid 10

beachballer
Anfänger
Beiträge: 7
Registriert: Di 14. Mai 2013, 15:25

Re: Avaya 9650 jeder zweite Call

Beitrag von beachballer » Mi 22. Mai 2013, 15:17

Hallo Rainer,

herzlichen Dank für die Antwort. Habe mich sehr gefreut das du geantwortet hast. Anbei die Antworten auf deine Fragen.

- Ist die Version 11.3.0
- Es ist kein Stun Server aufgesetzt und anbei die Avaya 9650 Konfig. Dort sind nur wenige Einträge gemacht. Such einfach nach 10.12.1.65 oder 10.12.0.66. http://nopaste.info/e6ddf3e738.html
- Anbei die Config vom 9650:

Code: Alles auswählen

  * Name       : 7997
  Description  :
  Secret       : <Set>
  MD5Secret    : <Not set>
  Remote Secret: <Not set>
  Context      : from-internal
  Record On feature : automon
  Record Off feature : automon
  Subscr.Cont. : <Not set>
  Language     :
  Tonezone     : <Not set>
  AMA flags    : Unknown
  Transfer mode: open
  CallingPres  : Presentation Allowed, Not Screened
  Callgroup    :
  Pickupgroup  :
  Named Callgr :
  Nam. Pickupgr:
  MOH Suggest  :
  Mailbox      : 7997@default
  VM Extension : *97
  LastMsgsSent : 2/0
  Call limit   : 2147483647
  Max forwards : 0
  Dynamic      : Yes
  Callerid     : "Tomas S" <7997>
  MaxCallBR    : 384 kbps
  Expire       : 670
  Insecure     : no
  Force rport  : No
  Symmetric RTP: No
  ACL          : Yes
  DirectMedACL : No
  T.38 support : No
  T.38 EC mode : Unknown
  T.38 MaxDtgrm: -1
  DirectMedia  : No
  PromiscRedir : No
  User=Phone   : No
  Video Support: No
  Text Support : No
  Ign SDP ver  : No
  Trust RPID   : Yes
  Send RPID    : No
  Subscriptions: Yes
  Overlap dial : Yes
  DTMFmode     : rfc2833
  Timer T1     : 500
  Timer B      : 32000
  ToHost       :
  Addr->IP     : 10.12.0.66:5060
  Defaddr->IP  : (null)
  Prim.Transp. : UDP
  Allowed.Trsp : UDP
  Def. Username: 7997
  SIP Options  : (none)
  Codecs       : (ulaw|alaw)
  Codec Order  : (alaw:20,ulaw:20)
  Auto-Framing :  No
  Status       : OK (10 ms)
  Useragent    : Avaya one-X Deskphone
  Reg. Contact : sip:7997@10.12.0.66;avaya-sc-enabled;transport=udp
  Qualify Freq : 60000 ms
  Keepalive    : 0 ms
  Sess-Timers  : Accept
  Sess-Refresh : uas
  Sess-Expires : 1800 secs
  Min-Sess     : 90 secs
  RTP Engine   : asterisk
  Parkinglot   :
  Use Reason   : No
  Encryption   : No
- [general] sollte nat=no sein. Anbei die Config:

Code: Alles auswählen

Global Settings:
----------------
  UDP Bindaddress:        0.0.0.0:5060
  TCP SIP Bindaddress:    Disabled
  TLS SIP Bindaddress:    Disabled
  Videosupport:           No
  Textsupport:            No
  Ignore SDP sess. ver.:  No
  AutoCreate Peer:        Off
  Match Auth Username:    No
  Allow unknown access:   Yes
  Allow subscriptions:    Yes
  Allow overlap dialing:  Yes
  Allow promisc. redir:   No
  Enable call counters:   No
  SIP domain support:     No
  Realm. auth:            No
  Our auth realm          asterisk
  Use domains as realms:  No
  Call to non-local dom.: Yes
  URI user is phone no:   No
  Always auth rejects:    Yes
  Direct RTP setup:       No
  User Agent:             2.11.0beta2(11.3.0)
  SDP Session Name:       Asterisk PBX 11.3.0
  SDP Owner Name:         root
  Reg. context:           (not set)
  Regexten on Qualify:    No
  Trust RPID:             No
  Send RPID:              No
  Legacy userfield parse: No
  Send Diversion:         Yes
  Caller ID:              Unknown
  From: Domain:
  Record SIP history:     Off
  Call Events:            Off
  Auth. Failure Events:   Off
  T.38 support:           No
  T.38 EC mode:           Unknown
  T.38 MaxDtgrm:          -1
  SIP realtime:           Disabled
  Qualify Freq :          60000 ms
  Q.850 Reason header:    No
  Store SIP_CAUSE:        No

Network QoS Settings:
---------------------------
  IP ToS SIP:             CS3
  IP ToS RTP audio:       EF
  IP ToS RTP video:       AF41
  IP ToS RTP text:        CS0
  802.1p CoS SIP:         4
  802.1p CoS RTP audio:   5
  802.1p CoS RTP video:   6
  802.1p CoS RTP text:    5
  Jitterbuffer enabled:   No

Network Settings:
---------------------------
  SIP address remapping:  Enabled using externaddr
  Externhost:             <none>
  Externaddr:             0.0.0.0:0
  Externrefresh:          10
  Localnet:               10.12.0.0/255.255.255.0
                          10.12.1.0/255.255.255.0
                          10.12.2.0/255.255.255.0
                          10.12.3.0/255.255.255.0

Global Signalling Settings:
---------------------------
  Codecs:                 (ulaw|alaw)
  Codec Order:            alaw:20,ulaw:20
  Relax DTMF:             No
  RFC2833 Compensation:   No
  Symmetric RTP:          No
  Compact SIP headers:    No
  RTP Keepalive:          0 (Disabled)
  RTP Timeout:            30
  RTP Hold Timeout:       300
  MWI NOTIFY mime type:   application/simple-message-summary
  DNS SRV lookup:         No
  Pedantic SIP support:   Yes
  Reg. min duration       60 secs
  Reg. max duration:      3600 secs
  Reg. default duration:  120 secs
  Sub. min duration       60 secs
  Sub. max duration:      3600 secs
  Outbound reg. timeout:  20 secs
  Outbound reg. attempts: 5
  Notify ringing state:   Yes
    Include CID:          No
  Notify hold state:      Yes
  SIP Transfer mode:      open
  Max Call Bitrate:       384 kbps
  Auto-Framing:           No
  Outb. proxy:            <not set>
  Session Timers:         Accept
  Session Refresher:      uas
  Session Expires:        1800 secs
  Session Min-SE:         90 secs
  Timer T1:               500
  Timer T1 minimum:       100
  Timer B:                32000
  No premature media:     Yes
  Max forwards:           70

Default Settings:
-----------------
  Allowed transports:     UDP
  Outbound transport:     UDP
  Context:                from-sip-external
  Record on feature:      automon
  Record off feature:     automon
  Force rport:            No
  DTMF:                   rfc2833
  Qualify:                0
  Keepalive:              0
  Use ClientCode:         No
  Progress inband:        Never
  Language:
  Tone zone:              <Not set>
  MOH Interpret:          default
  MOH Suggest:
  Voice Mail Extension:   *97

----
Liebe Grüße

Gregor

Benutzeravatar
rainer
Profi
Beiträge: 183
Registriert: Di 17. Nov 2009, 20:18
Wohnort: Bonn
Kontaktdaten:

Re: Avaya 9650 jeder zweite Call

Beitrag von rainer » Mi 22. Mai 2013, 18:15

Hi Gregor,

ja, bei dir ist nat=no.
Das kann man an Force rport: No erkennen.

Kannst du mal nur für die Extention 7997 das

Code: Alles auswählen

nat=force_rport,comedia
eintragen und testen.

Code: Alles auswählen

Context:                from-sip-external
Ist das in FreePBX WEB Interface auf dem asterisk?

Gruß
Rainer
Rainer Piper - Bonn - 0228 97167161 or SIP-URI: sip:7000@sip.soho-piper.de:5072
Software: kamailio 4.2.0 -> Asterisk 13.0.1 mit pjsip stack
Hardware: 2x QuadCore, 128GB ECC-RAM, 4x HDD Raid 10

Benutzeravatar
rainer
Profi
Beiträge: 183
Registriert: Di 17. Nov 2009, 20:18
Wohnort: Bonn
Kontaktdaten:

Re: Avaya 9650 jeder zweite Call

Beitrag von rainer » Mi 22. Mai 2013, 18:29

Das ist die FreePBX 2.11.0beta2 Version ... kann das sein?

Code: Alles auswählen

User Agent:             2.11.0beta2(11.3.0)
Gruß
Rainer
Rainer Piper - Bonn - 0228 97167161 or SIP-URI: sip:7000@sip.soho-piper.de:5072
Software: kamailio 4.2.0 -> Asterisk 13.0.1 mit pjsip stack
Hardware: 2x QuadCore, 128GB ECC-RAM, 4x HDD Raid 10

Benutzeravatar
rainer
Profi
Beiträge: 183
Registriert: Di 17. Nov 2009, 20:18
Wohnort: Bonn
Kontaktdaten:

Re: Avaya 9650 jeder zweite Call

Beitrag von rainer » Do 23. Mai 2013, 09:06

Hi

Das sollte so eigentlich richtig sein ...

Code: Alles auswählen

Localnet:                10.12.0.0/255.255.255.0
                          10.12.1.0/255.255.255.0
                          10.12.2.0/255.255.255.0
                          10.12.3.0/255.255.255.0
kannst du aber doch mal auf ... 255.255.252.0

Code: Alles auswählen

Localnet:            10.12.0.0/255.255.252.0

einstellen und testen.

Gruß
Rainer
Rainer Piper - Bonn - 0228 97167161 or SIP-URI: sip:7000@sip.soho-piper.de:5072
Software: kamailio 4.2.0 -> Asterisk 13.0.1 mit pjsip stack
Hardware: 2x QuadCore, 128GB ECC-RAM, 4x HDD Raid 10

Benutzeravatar
rainer
Profi
Beiträge: 183
Registriert: Di 17. Nov 2009, 20:18
Wohnort: Bonn
Kontaktdaten:

Re: Avaya 9650 jeder zweite Call

Beitrag von rainer » Do 23. Mai 2013, 09:41

Leider kann ich nur work-a-rounds anbieten :?

du könntest auch der Netzwerkkarte des Asterisk-Servers aus jedem Netz eine IP geben und das Avaya mit der IP 10.12.1.65 registriert sich dann am 10.12.1.66 :roll:
In der Variante muss Direct RTP setup auf NO stehen bleiben da alle RTP Pakete über den Asterisk gehen müssen.

z.B.

Code: Alles auswählen

iface eth0:0 inet static
name Ethernet alias LAN card
address 10.12.0.66
netmask 255.255.255.0
broadcast 10.12.0.255
network 10.12.0.0

iface eth0:1 inet static
name Ethernet alias LAN card
address 10.12.1.66
netmask 255.255.255.0
broadcast 10.12.1.255
network 10.12.1.0

iface eth0:2 inet static
name Ethernet alias LAN card
address 10.12.2.66
netmask 255.255.255.0
broadcast 10.12.2.255
network 10.12.2.0

iface eth0:3 inet static
name Ethernet alias LAN card
address 10.12.3.66
netmask 255.255.255.0
broadcast 10.12.3.255
network 10.12.3.0
Rainer Piper - Bonn - 0228 97167161 or SIP-URI: sip:7000@sip.soho-piper.de:5072
Software: kamailio 4.2.0 -> Asterisk 13.0.1 mit pjsip stack
Hardware: 2x QuadCore, 128GB ECC-RAM, 4x HDD Raid 10

Benutzeravatar
rainer
Profi
Beiträge: 183
Registriert: Di 17. Nov 2009, 20:18
Wohnort: Bonn
Kontaktdaten:

Re: Avaya 9650 jeder zweite Call

Beitrag von rainer » Do 23. Mai 2013, 13:47

Hi,

kannst du das Asterisk Konto 7997 einmal auf

Code: Alles auswählen

insecure=port
einstellen und testen


hier die Erklärung ...
insecure=port ; Allow matching of peer by IP address without matching port number !!!!!!!!

Code: Alles auswählen

insecure=port ; Allow matching of peer by IP address without matching port number
insecure=invite ; Do not require authentication of incoming INVITEs
insecure=port,invite ; (both)
Das Avaya sendet mit unterschiedlichen SIP Ports.
Das este INVITE/REGISTER kommt vom Avaya mit dem richtigen SIP Port 6060 der in der Avaya Config eingetragen ist.
Nach dem ersten INVITE/REGISTER sendet das Avaya plötzlich vom SIP Port 1024 plus.
Der Asterisk antwortet mit "401 Unauthorized Autorisierung ist fehlerhaft" weil der Avaya Sende-Port 1047 ungleich dem Avaya Registrierungs-Port 6060 ist.

Ein ..

Code: Alles auswählen

ngrep "7997" -qtW byline port 5060 -d any 
auf dem asterisk, hat das Problem aufgedeckt. :D


Gruß
Rainer
Rainer Piper - Bonn - 0228 97167161 or SIP-URI: sip:7000@sip.soho-piper.de:5072
Software: kamailio 4.2.0 -> Asterisk 13.0.1 mit pjsip stack
Hardware: 2x QuadCore, 128GB ECC-RAM, 4x HDD Raid 10

Antworten