Patton Dropping Calls to some external numbers

Discussion in '3CX Phone System - General' started by networkwise, Aug 28, 2013.

Thread Status:
Not open for further replies.
  1. networkwise

    Joined:
    Mar 22, 2013
    Messages:
    1
    Likes Received:
    0
    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:10004@192.168.33.116: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:0112741300@192.168.33.2: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:10004@192.168.33.116: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:0112741300@192.168.33.2: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:10004@192.168.33.2:5060>
    28-Aug-2013 17:13:07.671 [CM503007]: Call(C:704): Extn:1002 has joined, contact <sip:1002@192.168.33.118: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:10004@192.168.33.116:5060]
    28-Aug-2013 17:13:03.032 [CM503025]: Call(C:704): Calling T:Line:10004>>0112741300@[Dev:sip:10004@192.168.33.2:5060,Dev:sip:10005@192.168.33.2:5062,Dev:sip:10006@192.168.33.2:5064,Dev:sip:10007@192.168.33.2:5066] for L:704.1[Extn]
    28-Aug-2013 17:13:02.982 [CM503027]: Call(C:704): From: Extn:1002 ("William" <sip:1002@192.168.33.116:5060>) to T:Line:10004>>0112741300@[Dev:sip:10004@192.168.33.2:5060,Dev:sip:10005@192.168.33.2:5062,Dev:sip:10006@192.168.33.2:5064,Dev:sip:10007@192.168.33.2: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:10004@192.168.33.2:5060,Dev:sip:10005@192.168.33.2:5062,Dev:sip:10006@192.168.33.2:5064,Dev:sip:10007@192.168.33.2:5066]
    28-Aug-2013 17:13:02.982 Line limit check: Current # of calls for line Lc:10007(@192.168.33.2[<sip:10007@192.168.33.2: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:10006@192.168.33.2: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:10005@192.168.33.2: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:10004@192.168.33.2: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:0112741300@192.168.33.116:5060>
     
  2. mark.s.lowe

    Joined:
    Jan 14, 2010
    Messages:
    6
    Likes Received:
    0
    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.
     
  3. leejor

    leejor Well-Known Member

    Joined:
    Jan 22, 2008
    Messages:
    10,781
    Likes Received:
    286
    This would appear to be the log of the call being dropped, with no apparent explanation.

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

    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.
     
  4. mark.s.lowe

    Joined:
    Jan 14, 2010
    Messages:
    6
    Likes Received:
    0
    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.
     
  5. leejor

    leejor Well-Known Member

    Joined:
    Jan 22, 2008
    Messages:
    10,781
    Likes Received:
    286
    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.
     
Thread Status:
Not open for further replies.