Remote Extension problem!

[acs@kems.net]

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]

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]

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]

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]

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]

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]

------------------- 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="4589a791ca78d9639d4b3edca0def7d 8",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="4589a791ca78d9639d4b3edca0def7d 8",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="4589a791ca7 8d9639d4b3edca0def7d8",uri="sip:66@A.A.A.A",respon se="1e854e4d3f112e40a99396c9bcb14a02",algorithm=MD 5,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="3406251b08ad06d32e176eac979d1b9 b",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="3406251b08a d06d32e176eac979d1b9b",uri="sip:66@A.A.A.A",respon se="ed0b3e38bf0045a7a2e33b3bbf277447",algorithm=MD 5,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=1213169767b39a172 0-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=1213169767b39a172 0-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=121290264166bc957 e-e374-46b8-ae29-48e3c15bea8f
From: "XXXXX731" <sip:XXXXX731@sip.epygi.com>;tag=1213169767b39a172 0-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=121290264166bc957 e-e374-46b8-ae29-48e3c15bea8f
From: "XXXXX731" <sip:XXXXX731@sip.epygi.com>;tag=1213169767b39a172 0-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="3406251b08a d06d32e176eac979d1b9b",uri="sip:66@A.A.A.A",respon se="ed0b3e38bf0045a7a2e33b3bbf277447",algorithm=MD 5,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="5c022ac7a22977d022249db03af4ac0 3",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="5c022ac7a22977d022249db03af4ac0 3",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="5c022ac7a22 977d022249db03af4ac03",uri="sip:66@A.A.A.A",respon se="f7da2398c538b18847b35834b2b16192",algorithm=MD 5,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]

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]

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]

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?