PDA

View Full Version : Remote Extension problem!



acs@kems.net
06-09-2008, 06:30 AM
Hi All! I need help with an issue with remote extension feature.
I have two sites, first site with Q 4x, second with Q2x. All extensions register successfully on sip.epygi.com. In first site, I enabled extension 31, and I am using X-Lite phone from Internet to connect to the Q 4x as remote extension. I can call the analog phones connected to Q 4x using X-lite with no problem, but I cannot call analog phones connected in second site (Q 2x), it keeps playing message "Number dialled temorarily unavailable". When I dial using analog phones connected to 4x towards analog phones connected to 2x, I get it successfully. So my current problem is calling using the Soft phone remote extension (which is part of Q4x) to analog phones of Q2x. Calls from Q4x analog towards Q2x analog completes successfully! What could be the problem? software used (Boot loader: 2.3.23, Firmware Version: 3.1.12)

davrays
06-09-2008, 12:12 PM
Hello !
First of all, you are using very old firmware. Nobody even remembers, which problems we had with that firmware version, and whether we had them :)
It seems to me your problem may be connected with SIP negotiations during transfer (which is done by Quadro automatically in such cases). Can you try to set the "RTP Proxy" checkbox on the Remote Extension Settings (on ext 34). This could help.

Best regards,
David

acs@kems.net
06-10-2008, 03:40 AM
Yes I agree firmware is old, actually I follow the Principle "If things are working fine do not change them!" :) . I also doubted that the RTP peer-to-peer is the problem, and I have checked the RTP Proxy on the Remote extension, but problem persists! Any other ideas? Where can I download latest stable firmware?

acs@kems.net
06-10-2008, 03:59 AM
One more thing to add: When checking "System > Status > SIP Registration Status", I see that the Remote Extension I am using "31" is Registered, but when checking "Lines Status", I find that IP-Line-1 (corresponding to my Remote Extension) has Registration State "Not Registered". Is this normal? Is it because there is no Registered IP phone on the LAN side of the Quadro?

davrays
06-10-2008, 11:00 AM
Well, the latest stable firmware for 4x is 4.1.58. You can get in in the download section (under "IP PBX Products" -> "Quadro4x" -> "Software"). It worth to try that, as I don't remember if there was such bug in 3.1.12 or not.
Another option is to copy and paste here the parts of SIP UA logs from the Quadro4x, so we can see what is happening there. You can find them in "Diagnostics"->"Show System Logs". You can use "Mark all logs" button to make it easier tro find the appropriate call in the log.

davrays
06-10-2008, 11:01 AM
One more thing to add: When checking "System > Status > SIP Registration Status", I see that the Remote Extension I am using "31" is Registered, but when checking "Lines Status", I find that IP-Line-1 (corresponding to my Remote Extension) has Registration State "Not Registered". Is this normal? Is it because there is no Registered IP phone on the LAN side of the Quadro?

Thats OK. The Status of "IP Line 1" is showing only the status of "IP LIne 1", thus the status of remote extension is not visible there :)

acs@kems.net
06-11-2008, 06:30 AM
------------------- Application Log Started At 2008/06/11 10:36:07 -------------------

10:36:07 Init SIP UA
10:36:07 UAConfFileReader::CheckingSipLogLevel # LogLevel section is changed
Log Level=0
10:36:08 UAConfFileReader::CheckingGlobalLogLevel # LogLevel section is changed
Log Level=1
10:36:09 IP address 151.151.X.X is UP, add to working list
10:36:09 IP address A.A.A.A is UP, add to working list
10:36:09 Run SIP UA
10:36:11 SIP UA is ready
10:36:11 Waiting CM
10:36:11 Waiting RemExt
10:36:11 Got users list from PUA # Allow unknown users: false
10:36:31 RemExt has connected
10:36:31 CM has connected
10:48:12 EV --> UA # Subscribe # from: paPUA, to: sipphone@A.A.B.B:5382, event: dialog: (empty), allowEvents (empty) # SID: 22380816882356614
10:48:13 Send SIP message # (11/06/2008 07:48:13:021 GMT) # UDP # 560 bytes # from: A.A.A.A:5060 # to: A.A.B.B:5382

***************************** SIP message buffer start *****************************
SUBSCRIBE sip:sipphone@A.A.B.B:5382 SIP/2.0
Via: SIP/2.0/UDP A.A.A.A:5060;rport;branch=z9hG4bKEPSVBUSce3d0350-ebdc-46b8-bee4-bebd6aa3914d
To: <sip:sipphone@A.A.B.B>
From: <sip: paPUA@A.A.A.A>;tag=1213169767668b95f7-0b94-46e3-88b8-8881d166a777
CSeq: 627 SUBSCRIBE
Call-ID: f90b445a-abcf-4465-a112-56dcf117ad60@FTZ
Accept: application/dialog-info+xml
Contact: <sip: paPUA@A.A.A.A:5060>
Expires: 3600
User-Agent: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
Max-Forwards: 70
Event: dialog
Content-Length: 0

***************************** SIP message buffer end ******************************

10:48:13 Send SIP message # (11/06/2008 07:48:13:033 GMT) # UDP # 628 bytes # from: A.A.A.A:5060 # to: A.A.B.B:5382

***************************** SIP message buffer start *****************************
NOTIFY sip:sipphone@A.A.B.B:5382;rinstance=dcbea20699c9ca f6 SIP/2.0
Via: SIP/2.0/UDP A.A.A.A:5060;rport;branch=z9hG4bKEPSVBUS34e4f5c7-7bfd-440b-835a-21749ffa8b37
To: <sip:sipphone@A.A.A.A>
From: <sip:*0@A.A.A.A>;tag=12131697672c017e91-b0bf-4df2-ba79-a4213944c4b2
CSeq: 799 NOTIFY
Call-ID: b40e2bf2-55ae-4122-95fa-46c6a7a23c09@FTZ
Contact: <sip:*0@A.A.A.A:5060>
Content-Type: application/simple-message-summary
User-Agent: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
Max-Forwards: 70
Event: message-summary
Content-Length: 42

Messages-Waiting: no
Voice-Message: 0/0
***************************** SIP message buffer end ******************************

10:48:13 Receive SIP message # (11/06/2008 07:48:13:370 GMT) # UDP # 422 bytes # from: A.A.B.B:5382 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
SIP/2.0 489 Event Package Not Supported
Via: SIP/2.0/UDP A.A.A.A:5060;rport=5060;branch=z9hG4bKEPSVBUSce3d0 350-ebdc-46b8-bee4-bebd6aa3914d
To: <sip:sipphone@A.A.B.B>;tag=1961176a
From: <sip: paPUA@A.A.A.A>;tag=1213169767668b95f7-0b94-46e3-88b8-8881d166a777
Call-ID: f90b445a-abcf-4465-a112-56dcf117ad60@FTZ
CSeq: 627 SUBSCRIBE
User-Agent: X-Lite release 1002tx stamp 29712
Content-Length: 0

***************************** SIP message buffer end ******************************

10:48:13 UA --> EV # ReportError # event: dialog: (empty), error: UnsupportedEvent # SID: 22380816882356614
10:48:13 Send SIP message # (11/06/2008 07:48:13:541 GMT) # UDP # 628 bytes # from: A.A.A.A:5060 # to: A.A.B.B:5382

***************************** SIP message buffer start *****************************
NOTIFY sip:sipphone@A.A.B.B:5382;rinstance=dcbea20699c9ca f6 SIP/2.0
Via: SIP/2.0/UDP A.A.A.A:5060;rport;branch=z9hG4bKEPSVBUS34e4f5c7-7bfd-440b-835a-21749ffa8b37
To: <sip:sipphone@A.A.A.A>
From: <sip:*0@A.A.A.A>;tag=12131697672c017e91-b0bf-4df2-ba79-a4213944c4b2
CSeq: 799 NOTIFY
Call-ID: b40e2bf2-55ae-4122-95fa-46c6a7a23c09@FTZ
Contact: <sip:*0@A.A.A.A:5060>
Content-Type: application/simple-message-summary
User-Agent: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
Max-Forwards: 70
Event: message-summary
Content-Length: 42

Messages-Waiting: no
Voice-Message: 0/0
***************************** SIP message buffer end ******************************

10:48:13 Receive SIP message # (11/06/2008 07:48:13:939 GMT) # UDP # 428 bytes # from: A.A.B.B:5382 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
SIP/2.0 200 OK
Via: SIP/2.0/UDP A.A.A.A:5060;rport=5060;branch=z9hG4bKEPSVBUS34e4f 5c7-7bfd-440b-835a-21749ffa8b37
Contact: <sip:A.A.B.B:5382>
To: <sip:sipphone@A.A.A.A>;tag=8467232c
From: <sip:*0@A.A.A.A>;tag=12131697672c017e91-b0bf-4df2-ba79-a4213944c4b2
Call-ID: b40e2bf2-55ae-4122-95fa-46c6a7a23c09@FTZ
CSeq: 799 NOTIFY
User-Agent: X-Lite release 1002tx stamp 29712
Content-Length: 0

***************************** SIP message buffer end ******************************
10:48:14 Receive SIP message # (11/06/2008 07:48:14:254 GMT) # UDP # 629 bytes # from: A.A.B.B:5382 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
SUBSCRIBE sip:66@A.A.A.A SIP/2.0
Via: SIP/2.0/UDP A.A.B.B:5382;branch=z9hG4bK-d87543-6d758b3b8b046654-1--d87543-;rport
Max-Forwards: 70
Contact: <sip:sipphone@A.A.B.B:5382>
To: <sip:66@A.A.A.A>
From: "Af"<sip:sipphone@A.A.A.A>;tag=de385f72
Call-ID: d92aaa3613324321MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
CSeq: 1 SUBSCRIBE
Expires: 300
Accept: multipart/related, application/rlmi+xml, application/pidf+xml
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: X-Lite release 1002tx stamp 29712
Event: presence
Content-Length: 0

***************************** SIP message buffer end ******************************

10:48:14 Send SIP message # (11/06/2008 07:48:14:261 GMT) # UDP # 485 bytes # from: A.A.A.A:5060 # to: A.A.B.B:5382

***************************** SIP message buffer start *****************************
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP A.A.B.B:5382;rport=5382;branch=z9hG4bK-d87543-6d758b3b8b046654-1--d87543-
To: <sip:66@A.A.A.A>
From: "Af" <sip:sipphone@A.A.A.A>;tag=de385f72
CSeq: 1 SUBSCRIBE
Call-ID: d92aaa3613324321MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
Server: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
WWW-Authenticate: Digest realm="FTZ",nonce="4589a791ca78d9639d4b3edca0def7d8",opaque="1213170494"
Content-Length: 0

***************************** SIP message buffer end ******************************

10:48:14 Receive SIP message # (11/06/2008 07:48:14:599 GMT) # UDP # 833 bytes # from: A.A.B.B:5382 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
SUBSCRIBE sip:66@A.A.A.A SIP/2.0
Via: SIP/2.0/UDP A.A.B.B:5382;branch=z9hG4bK-d87543-913177563e56087f-1--d87543-;rport
Max-Forwards: 70
Contact: <sip:sipphone@A.A.B.B:5382>
To: <sip:66@A.A.A.A>
From: "Af"<sip:sipphone@A.A.A.A>;tag=de385f72
Call-ID: d92aaa3613324321MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
CSeq: 2 SUBSCRIBE
Expires: 300
Accept: multipart/related, application/rlmi+xml, application/pidf+xml
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: X-Lite release 1002tx stamp 29712
Event: presence
Content-Length: 0

***************************** SIP message buffer end ******************************

10:48:14 Send SIP message # (11/06/2008 07:48:14:261 GMT) # UDP # 485 bytes # from: A.A.A.A:5060 # to: A.A.B.B:5382

***************************** SIP message buffer start *****************************
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP A.A.B.B:5382;rport=5382;branch=z9hG4bK-d87543-6d758b3b8b046654-1--d87543-
To: <sip:66@A.A.A.A>
From: "Af" <sip:sipphone@A.A.A.A>;tag=de385f72
CSeq: 1 SUBSCRIBE
Call-ID: d92aaa3613324321MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
Server: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
WWW-Authenticate: Digest realm="FTZ",nonce="4589a791ca78d9639d4b3edca0def7d8",opaque="1213170494"
Content-Length: 0

***************************** SIP message buffer end ******************************

10:48:14 Receive SIP message # (11/06/2008 07:48:14:599 GMT) # UDP # 833 bytes # from: A.A.B.B:5382 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
SUBSCRIBE sip:66@A.A.A.A SIP/2.0
Via: SIP/2.0/UDP A.A.B.B:5382;branch=z9hG4bK-d87543-913177563e56087f-1--d87543-;rport
Max-Forwards: 70
Contact: <sip:sipphone@A.A.B.B:5382>
To: <sip:66@A.A.A.A>
From: "Af"<sip:sipphone@A.A.A.A>;tag=de385f72
Call-ID: d92aaa3613324321MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
CSeq: 2 SUBSCRIBE
Expires: 300
Accept: multipart/related, application/rlmi+xml, application/pidf+xml
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: X-Lite release 1002tx stamp 29712
Authorization: Digest username="sipphone",realm="FTZ",nonce="4589a791ca78d9639d4b3edca0def7d8",uri="sip:66@A.A.A.A",response="1e854e4d3f112e40a99396c9bcb14a02",algorithm=MD5,opaque="1213170494"
Event: presence
Content-Length: 0

***************************** SIP message buffer end ******************************

10:48:14 UA --> EV # Subscribe # from: sipphone@A.A.A.A: (empty), to: 66, event: presence: (empty), allowEvents (empty) # SID: 5210527596202766625
10:48:14 EV --> UA # Accept # event: presence: (empty), ready: false # SID: 5210527596202766625
10:48:14 Send SIP message # (11/06/2008 07:48:14:666 GMT) # UDP # 487 bytes # from: A.A.A.A:5060 # to: A.A.B.B:5382

***************************** SIP message buffer start *****************************
SIP/2.0 202 Accepted
Via: SIP/2.0/UDP A.A.B.B:5382;rport=5382;branch=z9hG4bK-d87543-913177563e56087f-1--d87543-
To: <sip:66@A.A.A.A>;tag=12131697675d57ddb8-c3a4-48c7-aa38-cfe8c2318bed
From: "Af" <sip:sipphone@A.A.A.A>;tag=de385f72
CSeq: 2 SUBSCRIBE
Call-ID: d92aaa3613324321MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
Contact: <sip:66@A.A.A.A:5060>
Expires: 300
Server: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
Content-Length: 0

***************************** SIP message buffer end ******************************

10:48:14 EV --> UA # ReportError # event: presence: (empty), error: NoResource # SID: 5210527596202766625
10:48:14 Send SIP message # (11/06/2008 07:48:14:678 GMT) # UDP # 593 bytes # from: A.A.A.A:5060 # to: A.A.B.B:5382

***************************** SIP message buffer start *****************************
NOTIFY sip:sipphone@A.A.B.B:5382 SIP/2.0
Via: SIP/2.0/UDP A.A.A.A:5060;rport;branch=z9hG4bKEPSVBUSb52523b2-bc74-4f6c-a9c5-993bf595b10a
To: "Af" <sip:sipphone@A.A.A.A>;tag=de385f72
From: <sip:66@A.A.A.A>;tag=12131697675d57ddb8-c3a4-48c7-aa38-cfe8c2318bed
CSeq: 274 NOTIFY
Call-ID: d92aaa3613324321MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
Contact: <sip:66@A.A.A.A:5060>
User-Agent: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
Max-Forwards: 70
Event: presence
Subscription-State: terminated;reason=noresource
Content-Length: 0

***************************** SIP message buffer end ******************************

10:48:15 Receive SIP message # (11/06/2008 07:48:15:068 GMT) # UDP # 466 bytes # from: A.A.B.B:5382 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
SIP/2.0 200 OK
Via: SIP/2.0/UDP A.A.A.A:5060;rport=5060;branch=z9hG4bKEPSVBUSb5252 3b2-bc74-4f6c-a9c5-993bf595b10a
Contact: <sip:sipphone@A.A.B.B:5382>
To: "Af"<sip:sipphone@A.A.A.A>;tag=de385f72
From: <sip:66@A.A.A.A>;tag=12131697675d57ddb8-c3a4-48c7-aa38-cfe8c2318bed
Call-ID: d92aaa3613324321MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
CSeq: 274 NOTIFY
User-Agent: X-Lite release 1002tx stamp 29712
Content-Length: 0

***************************** SIP message buffer end ******************************

10:49:55 Receive SIP message # (11/06/2008 07:49:55:958 GMT) # UDP # 843 bytes # from: A.A.B.B:5382 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
INVITE sip:66@A.A.A.A SIP/2.0
Via: SIP/2.0/UDP A.A.B.B:5382;branch=z9hG4bK-d87543-261c8d7948583d72-1--d87543-;rport
Max-Forwards: 70
Contact: <sip:sipphone@A.A.B.B:5382>
To: "HR"<sip:66@A.A.A.A>
From: "Af"<sip:sipphone@A.A.A.A>;tag=10710209
Call-ID: 25120814ac1a6d39MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
CSeq: 1 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
Content-Type: application/sdp
User-Agent: X-Lite release 1002tx stamp 29712
Content-Length: 278

v=0
o=- 0 2 IN IP4 192.168.8.X
s=<CounterPath eyeBeam 1.5>
c=IN IP4 A.A.B.B
t=0 0
m=audio 5936 RTP/AVP 107 119 0 98 8 3 101
a=fmtp:101 0-15
a=rtpmap:107 BV32/16000
a=rtpmap:119 BV32-FEC/16000
a=rtpmap:98 iLBC/8000
a=rtpmap:101 telephone-event/8000
a=sendrecv
***************************** SIP message buffer end ******************************

10:49:55 Send SIP message # (11/06/2008 07:49:55:962 GMT) # UDP # 328 bytes # from: A.A.A.A:5060 # to: A.A.B.B:5382

***************************** SIP message buffer start *****************************
SIP/2.0 100 Trying
Via: SIP/2.0/UDP A.A.B.B:5382;rport=5382;branch=z9hG4bK-d87543-261c8d7948583d72-1--d87543-
To: "HR" <sip:66@A.A.A.A>
From: "Af" <sip:sipphone@A.A.A.A>;tag=10710209
CSeq: 1 INVITE
Call-ID: 25120814ac1a6d39MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
Content-Length: 0

***************************** SIP message buffer end ******************************

10:49:55 Send SIP message # (11/06/2008 07:49:55:971 GMT) # UDP # 494 bytes # from: A.A.A.A:5060 # to: A.A.B.B:5382

***************************** SIP message buffer start *****************************
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP A.A.B.B:5382;rport=5382;branch=z9hG4bK-d87543-261c8d7948583d72-1--d87543-
To: "HR" <sip:66@A.A.A.A>
From: "Af" <sip:sipphone@A.A.A.A>;tag=10710209
CSeq: 1 INVITE
Call-ID: 25120814ac1a6d39MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
Server: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
WWW-Authenticate: Digest realm="FTZ",nonce="3406251b08ad06d32e176eac979d1b9b",opaque="1213170595"
Content-Length: 0

***************************** SIP message buffer end ******************************

10:49:56 Receive SIP message # (11/06/2008 07:49:56:041 GMT) # UDP # 335 bytes # from: A.A.B.B:5382 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
ACK sip:66@A.A.A.A SIP/2.0
Via: SIP/2.0/UDP A.A.B.B:5382;branch=z9hG4bK-d87543-261c8d7948583d72-1--d87543-;rport
To: "HR" <sip:66@A.A.A.A>
From: "Af"<sip:sipphone@A.A.A.A>;tag=10710209
Call-ID: 25120814ac1a6d39MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
CSeq: 1 ACK
Content-Length: 0

***************************** SIP message buffer end ******************************

10:49:56 Receive SIP message # (11/06/2008 07:49:56:197 GMT) # UDP # 1047 bytes # from: A.A.B.B:5382 # to: A.A.A.A:5060
***************************** SIP message buffer start *****************************
INVITE sip:66@A.A.A.A SIP/2.0
Via: SIP/2.0/UDP A.A.B.B:5382;branch=z9hG4bK-d87543-a547b055543bc76c-1--d87543-;rport
Max-Forwards: 70
Contact: <sip:sipphone@A.A.B.B:5382>
To: "HR"<sip:66@A.A.A.A>
From: "Af"<sip:sipphone@A.A.A.A>;tag=10710209
Call-ID: 25120814ac1a6d39MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
CSeq: 2 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
Content-Type: application/sdp
User-Agent: X-Lite release 1002tx stamp 29712
Authorization: Digest username="sipphone",realm="FTZ",nonce="3406251b08ad06d32e176eac979d1b9b",uri="sip:66@A.A.A.A",response="ed0b3e38bf0045a7a2e33b3bbf277447",algorithm=MD5,opaque="1213170595"
Content-Length: 278

v=0
o=- 0 2 IN IP4 192.168.8.X
s=<CounterPath eyeBeam 1.5>
c=IN IP4 A.A.B.B
t=0 0
m=audio 5936 RTP/AVP 107 119 0 98 8 3 101
a=fmtp:101 0-15
a=rtpmap:107 BV32/16000
a=rtpmap:119 BV32-FEC/16000
a=rtpmap:98 iLBC/8000
a=rtpmap:101 telephone-event/8000
a=sendrecv
***************************** SIP message buffer end ******************************

10:49:56 Send SIP message # (11/06/2008 07:49:56:202 GMT) # UDP # 328 bytes # from: A.A.A.A:5060 # to: A.A.B.B:5382

***************************** SIP message buffer start *****************************
SIP/2.0 100 Trying
Via: SIP/2.0/UDP A.A.B.B:5382;rport=5382;branch=z9hG4bK-d87543-a547b055543bc76c-1--d87543-
To: "HR" <sip:66@A.A.A.A>
From: "Af" <sip:sipphone@A.A.A.A>;tag=10710209
CSeq: 2 INVITE
Call-ID: 25120814ac1a6d39MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
Content-Length: 0

***************************** SIP message buffer end ******************************

10:49:56 UA --> CM # MakeCall # from: sipphone@A.A.A.A:, to: 66, child: (empty), media exist, GUID: (empty) # ContactInfo: A.A.B.B # SID: 5210528034289033854
10:49:57 CM --> UA # MakeCall # from: XXXXX731, to: XXXXX911@sip.epygi.com:5060, media exist # replace not exist # privacy: 0 # AddInfo: [NP#0:60] #OID: 121 # SID: 22381263558457509
10:49:57 Send SIP message # (11/06/2008 07:49:57:619 GMT) # UDP # 830 bytes # from: A.A.A.A:5060 # to: 74.54.85.200:5060

***************************** SIP message buffer start *****************************
INVITE sip:XXXXX911@sip.epygi.com SIP/2.0
Via: SIP/2.0/UDP A.A.A.A:5060;rport;branch=z9hG4bKEPSVBUSc69fd9df-c8e5-40f9-95bd-97682e465225
To: <sip:XXXXX911@sip.epygi.com>
From: "XXXXX731" <sip:XXXXX731@sip.epygi.com>;tag=1213169767b39a1720-8ff0-4981-9cbe-c6f562bbb80e
CSeq: 497 INVITE
Call-ID: 97cd9921-4bdc-4846-8f74-3ed23301ab60@FTZ
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, UPDATE
Contact: "XXXXX731" <sip:XXXXX731@A.A.A.A:5060>
Content-Type: application/sdp
Supported: replaces
User-Agent: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
Max-Forwards: 70
Content-Length: 177

v=0
o=XXXXX731 1844957 11844957 IN IP4 A.A.A.A
s=-
c=IN IP4 A.A.A.A
t=0 0
m=audio 6002 RTP/AVP 0 8 101
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
***************************** SIP message buffer end ******************************

10:49:57 Receive SIP message # (11/06/2008 07:49:57:898 GMT) # UDP # 400 bytes # from: 74.54.85.200:5060 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
SIP/2.0 100 Giving a try
Via: SIP/2.0/UDP A.A.A.A:5060;rport=5060;branch=z9hG4bKEPSVBUSc69fd 9df-c8e5-40f9-95bd-97682e465225
To: <sip:XXXXX911@sip.epygi.com>
From: "XXXXX731" <sip:XXXXX731@sip.epygi.com>;tag=1213169767b39a1720-8ff0-4981-9cbe-c6f562bbb80e
CSeq: 497 INVITE
Call-ID: 97cd9921-4bdc-4846-8f74-3ed23301ab60@FTZ
Server: OpenSER (1.2.2-notls (i386/linux))
Content-Length: 0

***************************** SIP message buffer end ******************************

10:49:58 Receive SIP message # (11/06/2008 07:49:58:239 GMT) # UDP # 476 bytes # from: 74.54.85.200:5060 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
SIP/2.0 480 Temporarily not available
Via: SIP/2.0/UDP A.A.A.A:5060;rport=5060;branch=z9hG4bKEPSVBUSc69fd 9df-c8e5-40f9-95bd-97682e465225
To: <sip:XXXXX911@sip.epygi.com>;tag=121290264166bc957e-e374-46b8-ae29-48e3c15bea8f
From: "XXXXX731" <sip:XXXXX731@sip.epygi.com>;tag=1213169767b39a1720-8ff0-4981-9cbe-c6f562bbb80e
CSeq: 497 INVITE
Call-ID: 97cd9921-4bdc-4846-8f74-3ed23301ab60@FTZ
Server: Epygi Quadro SIP User Agent/v3.1.7 (QUADRO-2X)
Content-Length: 0

***************************** SIP message buffer end ******************************

10:49:58 Send SIP message # (11/06/2008 07:49:58:244 GMT) # UDP # 496 bytes # from: A.A.A.A:5060 # to: 74.54.85.200:5060

***************************** SIP message buffer start *****************************
ACK sip:XXXXX911@sip.epygi.com SIP/2.0
Via: SIP/2.0/UDP A.A.A.A:5060;rport;branch=z9hG4bKEPSVBUSc69fd9df-c8e5-40f9-95bd-97682e465225
To: <sip:XXXXX911@sip.epygi.com>;tag=121290264166bc957e-e374-46b8-ae29-48e3c15bea8f
From: "XXXXX731" <sip:XXXXX731@sip.epygi.com>;tag=1213169767b39a1720-8ff0-4981-9cbe-c6f562bbb80e
CSeq: 497 ACK
Call-ID: 97cd9921-4bdc-4846-8f74-3ed23301ab60@FTZ
User-Agent: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
Max-Forwards: 70
Content-Length: 0

***************************** SIP message buffer end ******************************

10:49:58 UA --> CM # ReportError # Error: TemporaryUnavailable # SID: 22381263558457509
10:49:58 CM --> UA # OnAccept # Media exist # OID: 120 # SID: 5210528034289033854
10:49:58 Send SIP message # (11/06/2008 07:49:58:530 GMT) # UDP # 848 bytes # from: A.A.A.A:5060 # to: A.A.B.B:5382

***************************** SIP message buffer start *****************************
SIP/2.0 200 OK
Via: SIP/2.0/UDP A.A.B.B:5382;rport=5382;branch=z9hG4bK-d87543-a547b055543bc76c-1--d87543-
To: "HR" <sip:66@A.A.A.A>;tag=12131697675d8f59f4-57db-45d8-996d-817a9e34cce7
From: "Af" <sip:sipphone@A.A.A.A>;tag=10710209
CSeq: 2 INVITE
Call-ID: 25120814ac1a6d39MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, UPDATE
Contact: "HR" <sip:66@A.A.A.A:5060>
Content-Type: application/sdp
Supported: replaces
Server: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
Content-Length: 215

v=0
o=66 4244958 14244958 IN IP4 A.A.A.A
s=-
c=IN IP4 A.A.A.A
t=0 0
m=audio 6004 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
***************************** SIP message buffer end ******************************

10:49:59 Receive SIP message # (11/06/2008 07:49:59:022 GMT) # UDP # 703 bytes # from: A.A.B.B:5382 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
ACK sip:66@A.A.A.A:5060 SIP/2.0
Via: SIP/2.0/UDP A.A.B.B:5382;branch=z9hG4bK-d87543-5616c81239618d1a-1--d87543-;rport
Max-Forwards: 70
Contact: <sip:sipphone@A.A.B.B:5382>
To: "HR"<sip:66@A.A.A.A>;tag=12131697675d8f59f4-57db-45d8-996d-817a9e34cce7
From: "Af"<sip:sipphone@A.A.A.A>;tag=10710209
Call-ID: 25120814ac1a6d39MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
CSeq: 2 ACK
User-Agent: X-Lite release 1002tx stamp 29712
Authorization: Digest username="sipphone",realm="FTZ",nonce="3406251b08ad06d32e176eac979d1b9b",uri="sip:66@A.A.A.A",response="ed0b3e38bf0045a7a2e33b3bbf277447",algorithm=MD5,opaque="1213170595"
Content-Length: 0

***************************** SIP message buffer end ******************************

10:49:59 UA --> CM # Done # media not exist # SID: 5210528034289033854
10:50:01 CM --> UA # OnCloseCall # OID: 120 # SID: 5210528034289033854
10:50:01 Send SIP message # (11/06/2008 07:50:01:066 GMT) # UDP # 492 bytes # from: A.A.A.A:5060 # to: A.A.B.B:5382

***************************** SIP message buffer start *****************************
BYE sip:sipphone@A.A.B.B:5382 SIP/2.0
Via: SIP/2.0/UDP A.A.A.A:5060;rport;branch=z9hG4bKEPSVBUS1a6d56f8-4dcc-4170-ad59-9ddf1e15dee1
To: "Af" <sip:sipphone@A.A.A.A>;tag=10710209
From: "HR" <sip:66@A.A.A.A>;tag=12131697675d8f59f4-57db-45d8-996d-817a9e34cce7
CSeq: 345 BYE
Call-ID: 25120814ac1a6d39MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
User-Agent: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
Max-Forwards: 70
Content-Length: 0

***************************** SIP message buffer end ******************************

10:50:01 Receive SIP message # (11/06/2008 07:50:01:267 GMT) # UDP # 474 bytes # from: A.A.B.B:5382 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
SIP/2.0 200 OK
Via: SIP/2.0/UDP A.A.A.A:5060;rport=5060;branch=z9hG4bKEPSVBUS1a6d5 6f8-4dcc-4170-ad59-9ddf1e15dee1
Contact: <sip:sipphone@A.A.B.B:5382>
To: "Af"<sip:sipphone@A.A.A.A>;tag=10710209
From: "HR"<sip:66@A.A.A.A>;tag=12131697675d8f59f4-57db-45d8-996d-817a9e34cce7
Call-ID: 25120814ac1a6d39MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
CSeq: 345 BYE
User-Agent: X-Lite release 1002tx stamp 29712
Content-Length: 0

***************************** SIP message buffer end ******************************

10:53:15 Receive SIP message # (11/06/2008 07:53:15:107 GMT) # UDP # 629 bytes # from: A.A.B.B:5382 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
SUBSCRIBE sip:66@A.A.A.A SIP/2.0
Via: SIP/2.0/UDP A.A.B.B:5382;branch=z9hG4bK-d87543-342a1f0ea269a816-1--d87543-;rport
Max-Forwards: 70
Contact: <sip:sipphone@A.A.B.B:5382>
To: <sip:66@A.A.A.A>
From: "Af"<sip:sipphone@A.A.A.A>;tag=c3476547
Call-ID: 313a340958367850MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
CSeq: 1 SUBSCRIBE
Expires: 300
Accept: multipart/related, application/rlmi+xml, application/pidf+xml
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: X-Lite release 1002tx stamp 29712
Event: presence
Content-Length: 0

***************************** SIP message buffer end ******************************

10:53:15 Send SIP message # (11/06/2008 07:53:15:113 GMT) # UDP # 485 bytes # from: A.A.A.A:5060 # to: A.A.B.B:5382

***************************** SIP message buffer start *****************************
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP A.A.B.B:5382;rport=5382;branch=z9hG4bK-d87543-342a1f0ea269a816-1--d87543-
To: <sip:66@A.A.A.A>
From: "Af" <sip:sipphone@A.A.A.A>;tag=c3476547
CSeq: 1 SUBSCRIBE
Call-ID: 313a340958367850MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
Server: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
WWW-Authenticate: Digest realm="FTZ",nonce="5c022ac7a22977d022249db03af4ac03",opaque="1213170795"
Content-Length: 0

***************************** SIP message buffer end ******************************

10:53:15 Receive SIP message # (11/06/2008 07:53:15:607 GMT) # UDP # 629 bytes # from: A.A.B.B:5382 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
SUBSCRIBE sip:66@A.A.A.A SIP/2.0
Via: SIP/2.0/UDP A.A.B.B:5382;branch=z9hG4bK-d87543-342a1f0ea269a816-1--d87543-;rport
Max-Forwards: 70
Contact: <sip:sipphone@A.A.B.B:5382>
To: <sip:66@A.A.A.A>
From: "Af"<sip:sipphone@A.A.A.A>;tag=c3476547
Call-ID: 313a340958367850MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
CSeq: 1 SUBSCRIBE
Expires: 300
Accept: multipart/related, application/rlmi+xml, application/pidf+xml
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: X-Lite release 1002tx stamp 29712
Event: presence
Content-Length: 0

***************************** SIP message buffer end ******************************

10:53:15 Send SIP message # (11/06/2008 07:53:15:609 GMT) # UDP # 485 bytes # from: A.A.A.A:5060 # to: A.A.B.B:5382

***************************** SIP message buffer start *****************************
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP A.A.B.B:5382;rport=5382;branch=z9hG4bK-d87543-342a1f0ea269a816-1--d87543-
To: <sip:66@A.A.A.A>
From: "Af" <sip:sipphone@A.A.A.A>;tag=c3476547
CSeq: 1 SUBSCRIBE
Call-ID: 313a340958367850MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
Server: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
WWW-Authenticate: Digest realm="FTZ",nonce="5c022ac7a22977d022249db03af4ac03",opaque="1213170795"
Content-Length: 0

***************************** SIP message buffer end ******************************

10:53:16 Receive SIP message # (11/06/2008 07:53:16:200 GMT) # UDP # 833 bytes # from: A.A.B.B:5382 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
SUBSCRIBE sip:66@A.A.A.A SIP/2.0
Via: SIP/2.0/UDP A.A.B.B:5382;branch=z9hG4bK-d87543-7f7bf5326a31873a-1--d87543-;rport
Max-Forwards: 70
Contact: <sip:sipphone@A.A.B.B:5382>
To: <sip:66@A.A.A.A>
From: "Af"<sip:sipphone@A.A.A.A>;tag=c3476547
Call-ID: 313a340958367850MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
CSeq: 2 SUBSCRIBE
Expires: 300
Accept: multipart/related, application/rlmi+xml, application/pidf+xml
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: X-Lite release 1002tx stamp 29712
Authorization: Digest username="sipphone",realm="FTZ",nonce="5c022ac7a22977d022249db03af4ac03",uri="sip:66@A.A.A.A",response="f7da2398c538b18847b35834b2b16192",algorithm=MD5,opaque="1213170795"
Event: presence
Content-Length: 0

***************************** SIP message buffer end ******************************

10:53:16 UA --> EV # Subscribe # from: sipphone@A.A.A.A: (empty), to: 66, event: presence: (empty), allowEvents (empty) # SID: 5210528893282490992
10:53:16 EV --> UA # Accept # event: presence: (empty), ready: false # SID: 5210528893282490992
10:53:16 Send SIP message # (11/06/2008 07:53:16:231 GMT) # UDP # 487 bytes # from: A.A.A.A:5060 # to: A.A.B.B:5382

***************************** SIP message buffer start *****************************
SIP/2.0 202 Accepted
Via: SIP/2.0/UDP A.A.B.B:5382;rport=5382;branch=z9hG4bK-d87543-7f7bf5326a31873a-1--d87543-
To: <sip:66@A.A.A.A>;tag=1213169767f4b62130-7020-4d7d-b2b6-aeb1a50cde5b
From: "Af" <sip:sipphone@A.A.A.A>;tag=c3476547
CSeq: 2 SUBSCRIBE
Call-ID: 313a340958367850MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
Contact: <sip:66@A.A.A.A:5060>
Expires: 300
Server: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
Content-Length: 0

***************************** SIP message buffer end ******************************

10:53:16 EV --> UA # ReportError # event: presence: (empty), error: NoResource # SID: 5210528893282490992
10:53:16 Send SIP message # (11/06/2008 07:53:16:241 GMT) # UDP # 593 bytes # from: A.A.A.A:5060 # to: A.A.B.B:5382

***************************** SIP message buffer start *****************************
NOTIFY sip:sipphone@A.A.B.B:5382 SIP/2.0
Via: SIP/2.0/UDP A.A.A.A:5060;rport;branch=z9hG4bKEPSVBUS36baa1b1-f8ed-49a6-98e6-e48e9ca234ec
To: "Af" <sip:sipphone@A.A.A.A>;tag=c3476547
From: <sip:66@A.A.A.A>;tag=1213169767f4b62130-7020-4d7d-b2b6-aeb1a50cde5b
CSeq: 448 NOTIFY
Call-ID: 313a340958367850MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
Contact: <sip:66@A.A.A.A:5060>
User-Agent: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
Max-Forwards: 70
Event: presence
Subscription-State: terminated;reason=noresource
Content-Length: 0

***************************** SIP message buffer end ******************************

10:53:16 Receive SIP message # (11/06/2008 07:53:16:485 GMT) # UDP # 466 bytes # from: A.A.B.B:5382 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
SIP/2.0 200 OK
Via: SIP/2.0/UDP A.A.A.A:5060;rport=5060;branch=z9hG4bKEPSVBUS36baa 1b1-f8ed-49a6-98e6-e48e9ca234ec
Contact: <sip:sipphone@A.A.B.B:5382>
To: "Af"<sip:sipphone@A.A.A.A>;tag=c3476547
From: <sip:66@A.A.A.A>;tag=1213169767f4b62130-7020-4d7d-b2b6-aeb1a50cde5b
Call-ID: 313a340958367850MzA5Y2QzODAwYWFjYmQwMDNhYzM2MTk5Zm E1MzcyOTU.
CSeq: 448 NOTIFY
User-Agent: X-Lite release 1002tx stamp 29712
Content-Length: 0

***************************** SIP message buffer end ******************************

10:53:33 CGI has connected.
10:53:33 CGI has died.

acs@kems.net
06-11-2008, 06:38 AM
Sorry for long post! Here is the coded data:

A.A.A.A ----> Public IP address of Quadro 4x
A.A.B.B ----> Public IP address of PC running X-Lite soft phone
66 ---------> Digits in Quadro4x routing table translated to XXXXX911@sip.epygi.com
XXXXX731 -> Registered address on sip.epygi.com for Extension "31"
Extension "31" is the Remote Extension, with username "sipphone"

KSComs
06-11-2008, 09:00 AM
Out of interest why is the softphone at A.A.B.B using SIP port 5382 instead of 5060 ?

Is there another sip device at AABB ?

"Subscription-State: terminated;reason=noresource"

Interesting, try with the Softphone using sip port 5060 does it happen to work then ?

Regards

Kevin

davrays
06-11-2008, 09:17 AM
The only assumption I can make looking on this log, is that something is misconfigured at the destination Quadro2x. Can we look at the SIP and Call Manager logs of the 2x, taken after such failed call? Are you sure you don't have any service enabled at the destination extension "xxxxx911" - callerid based one, like call blocking, or forwarding to non-existent destination?

acs@kems.net
06-12-2008, 03:54 AM
The issue is that when I call XXXXX911 from analog phone connected to Quadro4x, call successfully establishes (Thus eleminating any assumption of misconfiguration on the Quadro 2x). Problem only happens when using the IP phone extension. I doubt it may be incompatibility of negotiated Audio Codecs, Does the log show any such event?? (because other than that I can not see what is the difference between analog extension and IP extension connected to the same Quadro4x and making call to the same XXXXX911 destination). By the way, I also tried calling XXXXX900 (auto-attendant at the Quadro2x) as well as XXXXX912 (the other analog extension at the Quadro2x), with same results "Number dialled temporarily unavailable".

acs@kems.net
06-12-2008, 04:00 AM
Out of interest why is the softphone at A.A.B.B using SIP port 5382 instead of 5060 ?

Is there another sip device at AABB ?

"Subscription-State: terminated;reason=noresource"

Interesting, try with the Softphone using sip port 5060 does it happen to work then ?

Regards

Kevin

Actually the PC with softphone installed has IP of "192.168.8.241", then it accesses Internet via a NAT router which has a public IP of A.A.B.B. Maybe there will be somebody already using the 5060 as a source port. Is it mandatory in SIP protocol that both Source and Destination ports be at "5060"?

acs@kems.net
06-12-2008, 06:07 AM
The only assumption I can make looking on this log, is that something is misconfigured at the destination Quadro2x. Can we look at the SIP and Call Manager logs of the 2x, taken after such failed call? Are you sure you don't have any service enabled at the destination extension "xxxxx911" - callerid based one, like call blocking, or forwarding to non-existent destination?
I think by examining the log I have posted, I gain some knowledge in tracing the SIP conversation! I agree with you Davrays, there should be something on the Quadro2x!
Maybe it allows calls from XXXXX711 and XXXXX712 (the two analog phones on Quadro4x), but not from XXXXX731 (IP extension on Quadro4x). The problem is that the Quadr2x is in another country, and I have no access to it currently (it is not connected to Internet on a public IP). All I remember is that I did not configure anything to block calls by CallerID!
A question come to my mind now: What will be the default behavior of the Quadro in that regard? Will it by default reject calls from Unknown to it addresses (even if they are registered to sip.epygi.com)?
I will try to arrange remote access to the Quadro2x, in the meantime, any comments are really appreciated!

KSComs
06-12-2008, 06:45 AM
Do you have some sort of caller ID blocking in the call routing of the 2X ...


Heres a quick diagnostic tip.. swap the extensions 11 and 31 around... and try calling from the swapped remote extension number to see if you have the same problem.

If you can gain access to the remote 2X extension then you have somethoing at the 2x that is rejecting extension 31.

I hope this bit of analysis helps you in your endeavours.

Regards

Kevin

acs@kems.net
06-12-2008, 07:17 AM
Nice idea for troubleshooting! But how to swap extensions? I think I should swap the SIP registration settings, but I do not know the passwords since they come by default hidden! Any suggestions?

acs@kems.net
06-12-2008, 07:35 AM
I found passwords in the system log files. I managed to make my remote extension "31" have the registered XXXXX711@sip.epygi.com. and tried to make a call, but strangely same problem result "Dialled number temporarily unavailable"!

KSComs
06-12-2008, 07:36 AM
Temporarily make inactive the line setting of extension 11 and edit the remote extension line setting from 31 to 11 I take it you have remote management access o this quadro...

Regards,

Kevin

Ps Papua New Guinea ?

davrays
06-12-2008, 01:31 PM
Temporarily make inactive the line setting of extension 11 and edit the remote extension line setting from 31 to 11 I take it you have remote management access o this quadro...


Yep. Kevin is right. This kind of test will be more informative: instead of swapping the SIP account, you can swap the Remote Extension registration - just register your X-Lite with different usename/password as a remote extension on ext 11. This way we can see if the extension settings on ext 31, are the reason of problem.
Though I doubt that it is the case... We need the logs from 2x. They will tell everything... at least to me :)

Best regards,
David

P.S. You are getting pretty advanced, acs@kems.net, if you found the password from the logs :) Most of resellers don't like looking into logs at all :)

KSComs
06-12-2008, 07:25 PM
Wow... you have remote extensions on the analogue - FXS ports of the Epygi's .I have never looked. ;) awesome ...

Hmmm... ok... I just had a look via my telephone system and cant seem to find the remote facility on the XS / analogue ports... where do I need to look....


Regards

Kevin

davrays
06-13-2008, 11:06 AM
Hmm... :) I don't know where are you looking, Kevin, but there definitely should be a "Remote Settings" in "Extensions Management - Edit Entry" page :)
It is there on all extensions - IP (attached to IP Line), analog (attached to FXS line), virtual (not attached to anything). But, of course, you can activate the Remote Extension only on IP and analog extensions.

Regards,
David

acs@kems.net
06-15-2008, 03:04 AM
Sorry for late reply! Here are the updates:

1- I have asked for Remote Access to the Quadro2x, I expect to have it this week!
2- I have tried the Remote Extension on "11", but no success!
3- I have made a call from analog extension "13" at Quadro4x towards analog extension "12" at Quadro2x, and make comparison between this INVITE request and the one X-Lite sends.

Follows is the one made by the analog extension "13":

******************************

14:30:57 CM --> UA # MakeCall # from: XXXXX713, to: XXXXX912@sip.epygi.com:5060, media exist # replace not exist # privacy: 0 # AddInfo: [NP#0:60] #OID: 5758 # SID: 22809299999320196
14:30:57 Send SIP message # (12/06/2008 11:30:57:387 GMT) # UDP # 992 bytes # from: A.A.A.A:5060 # to: 74.54.85.200:5060

***************************** SIP message buffer start *****************************
INVITE sip:XXXXX912@sip.epygi.com SIP/2.0
Via: SIP/2.0/UDP A.A.A.A:5060;rport;branch=z9hG4bKEPSVBUS8f8de2b6-5a85-4933-ad92-bb7a32292104
To: <sip:XXXXX912@sip.epygi.com>
From: <sip:XXXXX713@sip.epygi.com>;tag=121316976720e9311f-5370-411c-9775-34bb8ccb307e
CSeq: 971 INVITE
Call-ID: 0cec0233-f324-4403-be84-d9744821859c@FTZ
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, UPDATE
Contact: <sip:XXXXX713@A.A.A.A:5060>
Content-Type: application/sdp
Supported: replaces
User-Agent: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
Max-Forwards: 70
Content-Length: 361

v=0
o=XXXXX713 8923057 18923057 IN IP4 A.A.A.A
s=-
c=IN IP4 A.A.A.A
t=0 0
m=audio 6006 RTP/AVP 2 97 96 18 0 8 98 101
a=rtpmap:2 G726-32/8000
a=rtpmap:97 G726-24/8000
a=rtpmap:96 G726-16/8000
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:98 G726-40/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
***************************** SIP message buffer end ******************************

14:30:57 Send SIP message # (12/06/2008 11:30:57:901 GMT) # UDP # 992 bytes # from: A.A.A.A:5060 # to: 74.54.85.200:5060

***************************** SIP message buffer start *****************************
INVITE sip:XXXXX912@sip.epygi.com SIP/2.0
Via: SIP/2.0/UDP A.A.A.A:5060;rport;branch=z9hG4bKEPSVBUS8f8de2b6-5a85-4933-ad92-bb7a32292104
To: <sip:XXXXX912@sip.epygi.com>
From: <sip:XXXXX713@sip.epygi.com>;tag=121316976720e9311f-5370-411c-9775-34bb8ccb307e
CSeq: 971 INVITE
Call-ID: 0cec0233-f324-4403-be84-d9744821859c@FTZ
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, UPDATE
Contact: <sip:XXXXX713@A.A.A.A:5060>
Content-Type: application/sdp
Supported: replaces
User-Agent: Epygi Quadro SIP User Agent/v3.1.12 (QUADRO-4X/16X)
Max-Forwards: 70
Content-Length: 361

v=0
o=XXXXX713 8923057 18923057 IN IP4 A.A.A.A
s=-
c=IN IP4 A.A.A.A
t=0 0
m=audio 6006 RTP/AVP 2 97 96 18 0 8 98 101
a=rtpmap:2 G726-32/8000
a=rtpmap:97 G726-24/8000
a=rtpmap:96 G726-16/8000
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:98 G726-40/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
***************************** SIP message buffer end ******************************

14:30:58 Receive SIP message # (12/06/2008 11:30:58:203 GMT) # UDP # 389 bytes # from: 74.54.85.200:5060 # to: A.A.A.A:5060

***************************** SIP message buffer start *****************************
SIP/2.0 100 Giving a try
Via: SIP/2.0/UDP A.A.A.A:5060;
************************** COPY END ******************

From this log I notice the offerred Audio Codec list, and when compared with the one X-Lite sends, I notice that the only common codecs between the two are (a=rtpmap:0 PCMU/8000 & a=rtpmap:8 PCMA/8000). If I assume that the Quadro2x does not use any of these two codecs PCMU and PCMA (or if they are not preferred) maybe then the call will be rejected, otherwise I see no big diffrence in INVITE packets between the two cases that could cause the failure of call establishment (Just a thought!). I would like here to ask you Davrays about the behavior of Quadro while RTP Proxy is enabled like in my case, will the Quadro speak to X-Lite PCMU or PCMA, then speak to the remote called party any supported codec like G726-16 for example? Or alternatively If I may ask, Will the RTP Proxy do transcoding between the two ends of the telephonic call?
I think the final clear answer to the cause of call failure will be available when we get logs of Quadro2x!

P.S. I have to admit that with this case I got good experience with Quadro PBX!

acs@kems.net
06-18-2008, 06:54 AM
I still did not have access to Quadro2x, but I write to you to update you on this issue, where I think I have found the cause of the problem: It is: The supported Audio Codecs (as I doubted before). It looks like the Quadro2x rejects calls based on any Audio codec other than the G726 family. All the soft phones I have been trying until today did not support these codecs, but today I used PAP2 VoIP adapter from linksys which supports G726 codecs family and I could establish the call normally to the Quadro2x. Many thanks for you David <Davrays> and for you Kevin <KSComs> for your great support. I think before I liked Epygi, but today I admire it more!

Best Regards

davrays
06-18-2008, 01:11 PM
Theoretically in such case we should get the "415 Unsupported Media Type" from the Quadro2x, thats why I didn't suspect codecs... If codecs indeed are the reason of the problem (probably on 2x only G726 codecs are enabled on extension), this could be because of two possible reasons:
a) either the 2x FW is very old and the correct answer was not implemented yet on that firmware;
b) or there is a little bit complex configuration on 2x, and after being rejected because of codec mismatch, the call pass through some other processing (maybe fallback to another routing rule..?) and then got Temp Unavailable. Anyway, it would be great to see the logs on 2x.

Related to your question, acs@kems.net (BTW whats your name? :) ), Quadro does not do transcoding in case of RTP proxy. Actually it never does transcoding, as this will affect the performance too much!

Best regards and congratulations on finding the problem (and especially on getting experienced with Quadro :)
David

acs@kems.net
06-19-2008, 07:54 AM
Theoretically in such case we should get the "415 Unsupported Media Type" from the Quadro2x, thats why I didn't suspect codecs... If codecs indeed are the reason of the problem (probably on 2x only G726 codecs are enabled on extension), this could be because of two possible reasons:
a) either the 2x FW is very old and the correct answer was not implemented yet on that firmware;
b) or there is a little bit complex configuration on 2x, and after being rejected because of codec mismatch, the call pass through some other processing (maybe fallback to another routing rule..?) and then got Temp Unavailable. Anyway, it would be great to see the logs on 2x.
Well I did not know that, all I remember about the Quadro2x that it had the G726 at the top of list of prefferred codecs for lines 1 & 2, and that G711 a & U came at the tail of that list. I do not know why it did not take the G711 ones which were offered by the X-Lit?!! All I know that the internet access bandwidth for the Quadro2x is too low that maybe any G711 call will not establish due to lack of bandwidth! I will come back with Quadro2x logs once I get my remote login to it!


Related to your question, acs@kems.net (BTW whats your name? :) ), Quadro does not do transcoding in case of RTP proxy. Actually it never does transcoding, as this will affect the performance too much!

My name is Mohamed :) I think if it could do transcoding (maybe in the higher end models) that will resolve any problems related to Codec Mismatch


Best regards and congratulations on finding the problem (and especially on getting experienced with Quadro :)
Thank you for your great support :)

Best Regards
Mohamed

davrays
06-19-2008, 09:50 AM
Hello Mohamed

it looks like you really found the problem :) - see below:


All I know that the internet access bandwidth for the Quadro2x is too low that maybe any G711 call will not establish due to lack of bandwidth! I will come back with Quadro2x logs once I get my remote login to it!

That explains everything! If your bandwidth on 2x is less than 80-84 kbps, both PCMU and PCMA are removed from the list when doing external call. But in such cases (when the bandwidth is the reason of codec mismatch), instead of "415 Unsupported Media Type" Quadro gives "Temp Unavailable". That is a implemenation specifics, which is not fully correct in this specific case, but we didn't change that yet.


I think if it could do transcoding (maybe in the higher end models) that will resolve any problems related to Codec Mismatch

Yep, you are right. Maybe... maybe in higher end models, if we have much more spare CPU power.. we'll do that. But more probably, we'll do that only if somebody requests it and promice a big deal if we implement it :)

Best regards,
David

acs@kems.net
06-22-2008, 04:08 AM
So that is the problem! Now I understand the effect of configuring Uplink and Downlink speeds in the Internet Wizard! Those are configured for low values on the Quadro2x!

Many Thanks for your wonderful support David!

Best Regards

Mohamed

KSComs
06-22-2008, 07:42 AM
Hi Mohamed,

I am not sure that the Bandwidth is allocated sufficiently or correctly in the 2x models yet.
You might want to look at the Wan port speed and make it 10 meg full duplex though - hidden page in the epygi (I make sure that all Epygis pre M32 are made 10 meg full duplex on the wan port)

If you have a firewall in front of the device that has QOS built in like the Linksys variety then use that for allocating bandwidth and priorities to the Epygi. I think if you rely heavily on the Wan port via an internet connection then this might be a better route to go. Others may have some different hardware configs but they might tout a similar course.

This is all just my opinion and may not reflect those of main stream Epygi users or gurus, just what I have found to work best in the situations I have come across.

Regards

Kevin