Patton Dropping Calls to some external numbers

[networkwise]

Hi,

I'm having a problem with my first 3cx deployment. It's a simple straight forward I3 PC running windows 7 and 3cx connected to a Patton 4114 FXO Gateway.
The Patton is running the latest firmware R6.4
For the most part everything seems fine. I can make internal and external calls. Receive most calls fine and get caller ID fine.
But sometimes when I dial external numbers it will ring and cut off as soon as someone answers. I've also had this when calls has been transferred. I.e. the initial call is answered fine but as soon as they transfer me to an extension and that extension answers the call drops.

I've ran a diagnostic on the patton and attached my 3cx log below. I must admit I'm at a bit of a loss as to what could be causing the problem. I did call the receiving party and confirm that they are using an asterisk server with fxo digium card if that helps. the problem seems to be specific to what external system is used although I've not been able to confirm any additional system makes. The log below is for asterisk on the other side. But i can call those numbers fine form a mobile or directly from the land line.

192.168.33.2>enable
192.168.33.2#debug call-control detail 5
192.168.33.2#debug call-router detail 5
192.168.33.2#debug ccfxo
192.168.33.2#debug fxo
192.168.33.2#17:11:32 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: Network -> GW_SIP_0/192.168.33.2
17:11:32 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: E164-Number -> 10004
17:11:32 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: URI -> sip:[email protected]:5060
17:11:32 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: Type-Of-Number -> Unknown
17:11:32 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: Numbering-Plan -> Unknown
17:11:32 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: Presentation-Indicator -> Presentation allowed
17:11:32 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: Name -> William Barendse
17:11:32 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: Screening-Indicator -> User provided, not screened
17:11:32 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: Supports Overlap-Sending -> true
17:11:32 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: Supported Codecs -> Voice: G.711 u-law[20/20], G.711 A-law[20/20], G.729A[20/20]
17:11:32 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: Codec Negotiating -> Idle
17:11:32 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: Unique Identifier -> YWFjM2M5OTEzMzQ5MGU0ZmFkMjY1YTU0N2FiZmFlZWQ.
17:11:32 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: IP-Address -> 192.168.33.116
17:11:32 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: Call-Leg-ID -> 0x00d82cc0
17:11:32 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: State -> CONNECTED
17:11:32 CC > [Call 01021e50] Set call property: Context -> 0x0000001e
17:11:32 CC > [Call 01021e50] Set call property: Information-Transfer-Capability -> 3.1kHz Audio
17:11:32 CC > [Call 01021e50] Set call property: Hops -> 0x00000010
17:11:32 CC > [EP IF_SIP_0-00ac5f88/active] Dial to provider router (IF_SIP_0-precall-service) using call 01021e50
17:11:32 CC > [EP router-00d64410/incoming] Accept call 01021e50
17:11:32 CC > [EP router-00d64410/incoming] Set call-leg property: E164-Number -> 0112741300
17:11:32 CC > [EP router-00d64410/incoming] Set call-leg property: Type-Of-Number -> Unknown
17:11:32 CC > [EP router-00d64410/incoming] Set call-leg property: Numbering-Plan -> Unknown
17:11:32 CC > [EP router-00d64410/incoming] Set call-leg property: Name ->
17:11:32 CC > [EP router-00d64410/incoming] Set call-leg property: Alert-Info ->
17:11:32 CC > [EP router-00d64410/incoming] Set call-leg property: URI -> sip:[email protected]:5060
17:11:32 CC > [EP router-00d64410/incoming] Set call-leg property: Network -> router
17:11:32 CC > [EP router-00d64410/incoming] Set call-leg property: Call-Leg-ID -> 0x00d840c8
17:11:32 CC > [EP router-00d64410/incoming] Set call-leg property: State -> TRYING
17:11:32 CC > [EP router-00d64410] Start route-lookup
17:11:32 CR > [switch] Routing-Lookup:
17:11:32 CR > Execute all entries in table IF_SIP_0-precall-service
17:11:32 CR > Execute all entries in table IF_FXO_0-dest
17:11:32 CR > Execute all entries in table route-found-place-call
17:11:32 CR > Lookup result: Route found; place call (timeout=0)
17:11:32 CC > [EP router-00d64410] Route found; immediately place call
17:11:32 CC > [EP router-00d64410] Route to provider 'IF_FXO_0'
17:11:32 CC > [EP router-00d64410/outgoing] Set call-leg property: E164-Number -> 10004
17:11:32 CC > [EP router-00d64410/outgoing] Set call-leg property: URI -> sip:[email protected]:5060
17:11:32 CC > [EP router-00d64410/outgoing] Set call-leg property: Type-Of-Number -> Unknown
17:11:32 CC > [EP router-00d64410/outgoing] Set call-leg property: Numbering-Plan -> Unknown
17:11:32 CC > [EP router-00d64410/outgoing] Set call-leg property: Presentation-Indicator -> Presentation allowed
17:11:32 CC > [EP router-00d64410/outgoing] Set call-leg property: Name -> William Barendse
17:11:32 CC > [EP router-00d64410/outgoing] Set call-leg property: Screening-Indicator -> User provided, not screened
17:11:32 CC > [EP router-00d64410/outgoing] Set call-leg property: Supports Overlap-Sending -> true
17:11:32 CC > [EP router-00d64410/outgoing] Set call-leg property: Supported Codecs -> Voice: G.711 u-law[20/20], G.711 A-law[20/20], G.729A[20/20]
17:11:32 CC > [EP router-00d64410/outgoing] Set call-leg property: Codec Negotiating -> Idle
17:11:32 CC > [EP router-00d64410/outgoing] Set call-leg property: Unique Identifier -> YWFjM2M5OTEzMzQ5MGU0ZmFkMjY1YTU0N2FiZmFlZWQ.
17:11:32 CC > [EP router-00d64410/outgoing] Set call-leg property: IP-Address -> 192.168.33.116
17:11:32 CC > [EP router-00d64410/outgoing] Set call-leg property: Network -> router
17:11:32 CC > [EP router-00d64410/outgoing] Set call-leg property: Call-Leg-ID -> 0x0103cfe0
17:11:32 CC > [EP router-00d64410/outgoing] Set call-leg property: State -> CONNECTED
17:11:32 CC > [Call 0103cea8] Set call property: Context -> 0x0000001e
17:11:32 CC > [Call 0103cea8] Set call property: Information-Transfer-Capability -> 3.1kHz Audio
17:11:32 CC > [Call 0103cea8] Set call property: Hops -> 0x0000000f
17:11:32 CC > [EP router-00d64410/outgoing] Dial to provider IF_FXO_0 () using call 0103cea8
17:11:32 CFXO > [EP IF_FXO_0] State IDLE, event OFFER
17:11:32 CC > [EP IF_FXO_0/active] Accept call 0103cea8
17:11:32 CC > [EP IF_FXO_0/active] Set call-leg property: E164-Number -> 0112741300
17:11:32 CC > [EP IF_FXO_0/active] Set call-leg property: Type-Of-Number -> Unknown
17:11:32 CC > [EP IF_FXO_0/active] Set call-leg property: Numbering-Plan -> Unknown
17:11:32 CC > [EP IF_FXO_0/active] Set call-leg property: Name ->
17:11:32 CC > [EP IF_FXO_0/active] Set call-leg property: Alert-Info ->
17:11:32 CC > [EP IF_FXO_0/active] Set call-leg property: URI -> sip:[email protected]:5060
17:11:32 CC > [EP IF_FXO_0/active] Set call-leg property: Quality-Of-Service -> MOS 4.50, DS0
17:11:32 CC > [EP IF_FXO_0/active] Set call-leg property: Network -> IF_FXO_0
17:11:32 CC > [EP IF_FXO_0/active] Set call-leg property: Call-Leg-ID -> 0x00d77e40
17:11:32 CC > [EP IF_FXO_0/active] Set call-leg property: State -> TRYING
17:11:32 FXO > [fxo 0 0 0/fxo 0] Dispatching event 'ApplOffHook' in state 'OnHook'
17:11:32 FXO > [fxo 0 0 0/fxo 0] Action 'Off-Hook'
17:11:32 FXO > [0 0] Driver: Setting port state to 'OffHook'
17:11:32 FXO > [fxo 0 0 0/fxo 0] Transition 'OnHook' -> 'OffHook'
17:11:32 CFXO > [EP IF_FXO_0] Deactivate caller-id detection
17:11:32 CFXO > [EP IF_FXO_0] Starting timer with 11 secs
17:11:32 CFXO > [EP IF_FXO_0] Change state to WAIT FOR DIALTONE.
17:11:32 CC > [Call 01021e50] Set call property: Hops -> 0x0000000f
17:11:32 CC > [EP router-00d64410/incoming] Set call-leg property: Quality-Of-Service -> MOS 4.50, DS0
17:11:33 CFXO > [EP IF_FXO_0] State WAIT FOR DIALTONE, event PEER_CONNECTED
17:11:34 CFXO > [EP IF_FXO_0] State WAIT FOR DIALTONE, event DIALTONE
17:11:34 CFXO > [EP IF_FXO_0] Change state to DIALING.
17:11:34 CFXO > [EP IF_FXO_0] Starting timer with 3 secs
17:11:34 CFXO > [EP IF_FXO_0] Dialing to terminal: 0112741300
17:11:37 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: Quality-Of-Service -> MOS 4.40, RTP, (undefined) (0ms), Local: Rx 0 pkts, 0 bytes, 0 lost, jitter 0 ms, Tx 0 pkts, 0 bytes, rtt 0 ms, Remote: Rx 0 pkts, 0 bytes, 0 lost, jitter 0 ms, Tx 0 pkts, 0 bytes, rtt 0 ms
17:11:37 CC > [EP router-00d64410/outgoing] Set call-leg property: Quality-Of-Service -> MOS 4.40, RTP, (undefined) (0ms), Local: Rx 0 pkts, 0 bytes, 0 lost, jitter 0 ms, Tx 0 pkts, 0 bytes, rtt 0 ms, Remote: Rx 0 pkts, 0 bytes, 0 lost, jitter 0 ms, Tx 0 pkts, 0 bytes, rtt 0 ms
17:11:37 CFXO > [EP IF_FXO_0] State DIALING, event TIMEOUT
17:11:37 CFXO > [EP IF_FXO_0] Change state to ACTIVE.
17:11:37 CC > [EP IF_FXO_0/active] Set call-leg property: State -> CONNECTED
17:11:37 CC > [EP IF_FXO_0/active] Set call-leg property: Provides Data -> true
17:11:37 CC > [EP router-00d64410/incoming] Set call-leg property: Provides Data -> true
17:11:37 CC > [EP router-00d64410] Routing succeeded
17:11:37 CC > [EP router-00d64410/incoming] Transfer call 0103cea8 to 01021e50 ==> conference
17:11:37 CC > [EP router-00d64410/incoming] Drop call 01021e50
17:11:37 CC > [EP router-00d64410/incoming] Set call-leg property: Provides Data -> false
17:11:37 CC > [EP router-00d64410/incoming] Set call-leg property: Cause -> Normal call clearing
17:11:37 CC > [EP router-00d64410/incoming] Set call-leg property: State -> RELEASED
17:11:37 CC > [EP router-00d64410/outgoing] Drop call 0103cea8
17:11:37 CC > [EP router-00d64410/outgoing] Set call-leg property: Cause -> Normal call clearing
17:11:37 CC > [EP router-00d64410/outgoing] Set call-leg property: State -> RELEASED
17:11:37 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: Provides Data -> true
17:11:37 CFXO > [EP IF_FXO_0] Change datapath direction to Send/Receive.
17:11:40 CFXO > [EP IF_FXO_0] State ACTIVE, event RELEASETONE
17:11:40 FXO > [fxo 0 0 0/fxo 0] Dispatching event 'ApplOnHook' in state 'OffHook'
17:11:40 FXO > [fxo 0 0 0/fxo 0] Action 'On-Hook'
17:11:40 FXO > [0 0] Driver: Setting port state to 'OnHook'
17:11:40 FXO > [fxo 0 0 0/fxo 0] Transition 'OffHook' -> 'OnHook'
17:11:40 CFXO > [EP IF_FXO_0] Change state to IDLE.
17:11:40 CC > [EP IF_FXO_0/active] Drop call 01021e50
17:11:40 CC > [EP IF_FXO_0/active] Set call-leg property: Provides Data -> false
17:11:40 CC > [EP IF_FXO_0/active] Set call-leg property: Cause -> Normal call clearing
17:11:40 CC > [EP IF_FXO_0/active] Set call-leg property: State -> RELEASED
17:11:40 CFXO > [EP IF_FXO_0] Change datapath direction to Inactive.
17:11:40 CFXO > [EP IF_FXO_0] Configure caller-id detection 'etsi'
17:11:40 CFXO > [EP IF_FXO_0] Change datapath direction to Receive Only.
17:11:40 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: State -> RELEASED
17:11:40 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: Provides Data -> false
17:11:41 CC > [EP IF_SIP_0-00ac5f88/active] Set call-leg property: Cause -> Normal call clearing


3cx LOG
++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
28-Aug-2013 17:13:11.284 Leg L:704.1[Extn] is terminated: Cause: BYE from PBX
28-Aug-2013 17:13:11.283 [CM503008]: Call(C:704): Call is terminated
28-Aug-2013 17:13:11.283 [CM503023]: Call(C:704): Call recording is stopped, audio file: C:\ProgramData\3CX\Data\Recordings\1002\[William]_1002-0112741300_20130828171307(704).wav
28-Aug-2013 17:13:11.282 Leg L:704.2[Line:10004>>0112741300] is terminated: Cause: BYE from 192.168.33.2:5060
28-Aug-2013 17:13:07.671 [CM503007]: Call(C:704): Line:10004>>0112741300 has joined, contact <sip:[email protected]:5060>
28-Aug-2013 17:13:07.671 [CM503007]: Call(C:704): Extn:1002 has joined, contact <sip:[email protected]:5060>
28-Aug-2013 17:13:07.671 [CM503022]: Call(C:704): Call recording is started, audio file: C:\ProgramData\3CX\Data\Recordings\1002\[William]_1002-0112741300_20130828171307(704).wav
28-Aug-2013 17:13:07.668 L:704.2[Line:10004>>0112741300] has joined to L:704.1[Extn]
28-Aug-2013 17:13:07.668 [CM505002]: Gateway:[192.168.33.2] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Patton SN4114 JO EUI 00A0BA094EDF R6.4 2013-07-12 H323 SIP FXS FXO M5T SIP Stack/4.1.12.18] PBX contact: [sip:[email protected]:5060]
28-Aug-2013 17:13:03.032 [CM503025]: Call(C:704): Calling T:Line:10004>>0112741300@[Dev:sip:[email protected]:5060,Dev:sip:[email protected]:5062,Dev:sip:[email protected]:5064,Dev:sip:[email protected]:5066] for L:704.1[Extn]
28-Aug-2013 17:13:02.982 [CM503027]: Call(C:704): From: Extn:1002 ("William" <sip:[email protected]:5060>) to T:Line:10004>>0112741300@[Dev:sip:[email protected]:5060,Dev:sip:[email protected]:5062,Dev:sip:[email protected]:5064,Dev:sip:[email protected]:5066]
28-Aug-2013 17:13:02.982 [CM503004]: Call(C:704): Route 1: from L:704.1[Extn] to T:Line:10004>>0112741300@[Dev:sip:[email protected]:5060,Dev:sip:[email protected]:5062,Dev:sip:[email protected]:5064,Dev:sip:[email protected]:5066]
28-Aug-2013 17:13:02.982 Line limit check: Current # of calls for line Lc:10007(@192.168.33.2[<sip:[email protected]:5066>]) is 0; limit is 1
28-Aug-2013 17:13:02.982 Line limit check: Current # of calls for line Lc:10006(@192.168.33.2[<sip:[email protected]:5064>]) is 0; limit is 1
28-Aug-2013 17:13:02.982 Line limit check: Current # of calls for line Lc:10005(@192.168.33.2[<sip:[email protected]:5062>]) is 0; limit is 1
28-Aug-2013 17:13:02.982 Line limit check: Current # of calls for line Lc:10004(@192.168.33.2[<sip:[email protected]:5060>]) is 0; limit is 1
28-Aug-2013 17:13:02.982 Call(C:704): Call from Extn:1002 to 0112741300 matches outbound rule 'Rule for 192.168.33.2'
28-Aug-2013 17:13:02.982 [CM503001]: Call(C:704): Incoming call from Extn:1002 to "0112741300" <sip:[email protected]:5060>

 

[mark.s.lowe]

Did you resolve this problem?

I have had similar issues with outbound calls. However never when we just made a call but rather after being connected and transferred or on a conference call for a period of time we get dropped. Sometimes there is a clicking and beep noise - it'tt happen a few times and then we get dropped. Other times to notice just a clean disconnect.

All of this started after upgrading to 3CX v12 and Patton R6.4. We have 2 Patton 4114's.

Patton wasn't helpful yet although they had me do the telnet debug on the patton. 3CX just shows the connection got dropped by the patton like any other call being dropped.

 

[leejor]

17:11:37 CC > [EP router-00d64410/incoming] Transfer call 0103cea8 to 01021e50 ==> conference
17:11:37 CC > [EP router-00d64410/incoming] Drop call 01021e50

This would appear to be the log of the call being dropped, with no apparent explanation.

28-Aug-2013 17:13:11.282 Leg L:704.2[Line:10004>>0112741300] is terminated: Cause: BYE from 192.168.33.2:5060

3CX sees it as a "Bye" from the gateway

[Dev:sip:[email protected]:5060

This would indicate that the above IP is trunk 10004. have you confirmed that dropped calls happen all trunks on the gateway (common issue), or do they only happen with trunk 10004? If that is the case, what is different about that trunk/PSTN line? Swap phone lines, on the gateway, and see if the problem stays with one trunk, or follows the PSTN line.

 

[mark.s.lowe]

I have been working on this issue for a month now and found the following:

1. It happens on all lines and both patton 4114 devices, Inbound and Outbound
2. Both pattons 4114 are at r6.6 11-6-2014
3. 3CX is v12 SP6.1 running on Windows 2008 R2 Server 64 Bit fully patch and IIS as 3CX websserver
4. Telepacific providing telco

3CX says "BYE" as a reason on the disconnects - and a few times I've seen "terminated: Cause: 487 Request Terminated/INVITE"

Patton support had me submit their debug logs and indicate the following:

1. "SIP/2.0 407 Proxy Authentication Required" is stated for each line however the pattons register with 3CX (GREEN).
2. 3CX says BYE to patton and Patton support urged me to contact 3CX to check my config

Any help would be appreciated here.

 

[leejor]

When you mentioned that calls dropped when being transferred to an (external extension), what came to mind was (perhaps) a changed in supervision status being sent by the PSTN line. With an analogue gateway, that would be in the form of a current reversal which many PSTN providers don't offer as default unless requested, and then charge a fee. But...every PSTN provider is different.

The fact that it is happening on both inbound and outbound calls, however, sort of throws this out theory, "out the window".

It could be that your PSTN provider, may, be dropping the line voltage, for a split second (CPC), which is commonly used to drop lines when the party at the far end has hung up. The fact it is happening on two Pattons, suggests that you have an incorrect setting in both, they are both faulty (seems unlikely), or, it is being caused by a condition on the lines. The fact that all lines behave the same way sort of rules out a faulty line-card at the providers end unless all lines reside on a common line-card at the central office, which could happen (but aren't that likely), depending on the central office switch.

You might want to "monitor" one of the outgoing lines with a voltmeter while placing some calls on it. See if voltage remains steady during the entire call (should start as about 48 to 51 volts on an idle line, then drop when the line is seized), of course this means that you have to hit upon a dropped call when testing.

You could also try disabling the CPC feature in one of the the Pattons, it may then ignore the line drop, if it is happening.