Patton 4114 "Call is terminated"

Discussion in '3CX Phone System - General' started by Pentangle, Jan 30, 2008.

  1. Pentangle

    Pentangle Member

    Joined:
    Dec 6, 2007
    Messages:
    261
    Likes Received:
    0
    So I have a Patton Smartnode 4114, just out of the box, and everything works fine on outgoing calls.

    Incoming calls, and the extension rings all ok, but the instant I pick up the handset the call gets dropped with a "Call is terminated" message in the log. However, the inbound call to the Patton doesn't get dropped.

    Anyone got any ideas on this?

    Cheers,
    Mike.
     
  2. archie

    archie Well-Known Member
    3CX Staff

    Joined:
    Aug 18, 2006
    Messages:
    1,309
    Likes Received:
    0
    Please make sure that Patton and phones has at least one common codec.
     
  3. Pentangle

    Pentangle Member

    Joined:
    Dec 6, 2007
    Messages:
    261
    Likes Received:
    0
    Hi Archie,

    How would I do that? I auto-provisioned the handset (Snom320) from 3CX and I used the Patton 4114 config from 3CX as well.

    Also, surely a common CODEC issue would have occurred when ringing outbound as well?

    Cheers,
    Mike.
     
  4. archie

    archie Well-Known Member
    3CX Staff

    Joined:
    Aug 18, 2006
    Messages:
    1,309
    Likes Received:
    0
    Ah, another possible deal breaker is Fax media in SDP. It seems Patton sends Fax media, if fax is enabled, and some phones (GXP for sure) doesn't like it and terminate a call at sight.
     
  5. Pentangle

    Pentangle Member

    Joined:
    Dec 6, 2007
    Messages:
    261
    Likes Received:
    0
    Well there's 2 VoIP profiles that come as default on the Patton, one is "Default" and the other is "Fax Enabled".

    The 4 interfaces on the box are all set to "Default".

    This is the complete report from the box:

    ==============================================================================
    VERSION
    =======

    Productname : SN4114/JO/EUI
    Software Version : R4.2 2007-09-19 H323 SIP FXS FXO
    Supplier :
    Provider :
    Subscriber :

    Information for Slot 0:
    SN4114/JO/EUI (Admin State: Application Started, Real State: Application Started)
    Hardware Version : 4, 4
    Serial number : 00A0BA036638
    PLD Version : 0x00040103
    Software Version : R4.2 2007-09-19 H323 SIP FXS FXO


    ------------------------------------------------------------------------------
    CLOCK
    =====
    System Time: 1970-01-01T03:34:42

    Up Time: The system is up for 0 days, 2 hours, 34 minutes, 41 seconds

    ------------------------------------------------------------------------------
    LICENSES
    ========

    No licenses installed.


    ------------------------------------------------------------------------------
    MEMORY
    ======

    -----------------------------------------------------------------------------
    Slot 0
    -----------------------------------------------------------------------------
    Allocated Free

    Bytes Blocks Calls Bytes Blocks Calls Largest Used dCalls

    -----------------------------------------------------------------------------

    4689872 n/a 1053169 13504560 n/a 999018 12428416 25% 54151



    ------------------------------------------------------------------------------
    RESET LOG
    =========
    1970-01-01T00:00:00 : First start
    1970-01-01T01:00:07 : Pwr off/Man reset
    1970-01-01T01:00:03 : Pwr off/Man reset
    1970-01-01T01:02:14 : Target Shell
    1970-01-01T01:00:01 : Pwr off/Man reset
    1970-01-01T01:00:01 : Target Shell
    1970-01-01T01:00:01 : Target Shell
    1970-01-01T01:00:01 : Target Shell
    1970-01-01T01:00:01 : Target Shell
    1970-01-01T01:00:01 : Target Shell
    1970-01-01T01:00:01 : Pwr off/Man reset
    1970-01-01T01:00:01 : Target Shell
    1970-01-01T01:00:01 : Target Shell

    ------------------------------------------------------------------------------
    BOOT LOG
    ========

    System type is SN4114/JO/EUI, 0004-0004
    Serial No. 00A0BA036638

    Proceed with standard boot.

    Checking PLDs...
    Planar board : checking - please wait (this may take some seconds)
    PLD OK, Version = 0x00040103

    Installing Drivers...
    Ethernet 0/0, MAC Addr 00:A0:BA:03:66:38, RxQ 32, TxQ 64

    Starting Modules...
    1970-01-01T01:00:01 : Target Shell
    All modules successfully started
    Config startup-config READ [ OK ]
    Config startup-config EXECUTE 1970-01-01T01:00:27 : LOGINFO : Link up on interface ethernet 0 0 0
    1970-01-01T01:00:28 : LOGINFO : Link up on interface eth0
    [ OK ]

    Opening Modules...
    All modules successfully opened
    
    System is up.

    1970-01-01T01:00:31 : LOGINFO : Warm start.

    ------------------------------------------------------------------------------
    EVENT LOG
    =========
    1970-01-01T01:03:08 : LOGINFO : Link up on interface eth0
    1970-01-01T01:00:17 : LOGWARNING : Errors in configuration of router 'switch' ('show call-router switch reload' for details)
    1970-01-01T01:00:18 : LOGINFO : Link up on interface eth0
    1970-01-01T01:00:20 : LOGINFO : Link up on interface ethernet 0 0 0
    1970-01-01T01:00:22 : LOGINFO : Auto Configuration (DHCP):
    IP Address of interface eth0 set to 192.168.78.120
    Default gateway set to 192.168.78.1
    Added name server 192.168.78.1

    1970-01-01T01:00:22 : LOGINFO : Link down on interface eth0
    1970-01-01T01:00:23 : LOGINFO : Link up on interface eth0
    1970-01-01T01:00:23 : LOGINFO : Cold start.
    1970-01-01T01:02:24 : LOGINFO : Warm start.
    1970-01-01T01:02:25 : LOGINFO : Link up on interface ethernet 0 0 0
    1970-01-01T01:02:25 : LOGINFO : Link up on interface eth0
    1970-01-01T01:02:25 : LOGINFO : Auto Configuration (DHCP):
    IP Address of interface eth0 set to 192.168.78.120
    Default gateway set to 192.168.78.1
    Added name server 192.168.78.1

    1970-01-01T01:00:08 : LOGWARNING : startup-config does not exist
    1970-01-01T01:00:12 : LOGINFO : Cold start.
    1970-01-01T01:08:11 : LOGINFO : Link up on interface ethernet 0 0 0
    1970-01-01T01:08:12 : LOGINFO : Link up on interface eth0
    1970-01-01T01:08:12 : LOGINFO : Auto Configuration (DHCP):
    IP Address of interface eth0 set to 192.168.20.107
    Default gateway set to 192.168.20.1
    Added name server 192.168.20.1

    2008-01-28T14:27:45 : LOGINFO : Time: set clock from 1970-01-01T01:08:28 to 2008-01-28T14:27:45
    1970-01-01T01:00:26 : LOGINFO : Link up on interface ethernet 0 0 0
    1970-01-01T01:00:27 : LOGINFO : Link up on interface eth0
    1970-01-01T01:00:30 : LOGINFO : Warm start.
    2008-01-28T15:08:30 : LOGINFO : Time: set clock from 1970-01-01T02:32:55 to 2008-01-28T15:08:30
    1970-01-01T01:00:26 : LOGINFO : Link up on interface ethernet 0 0 0
    1970-01-01T01:00:27 : LOGINFO : Link up on interface eth0
    1970-01-01T01:00:30 : LOGINFO : Warm start.
    1970-01-01T01:00:26 : LOGINFO : Link up on interface ethernet 0 0 0
    1970-01-01T01:00:27 : LOGINFO : Link up on interface eth0
    1970-01-01T01:00:30 : LOGINFO : Warm start.
    1970-01-01T01:00:26 : LOGINFO : Link up on interface ethernet 0 0 0
    1970-01-01T01:00:27 : LOGINFO : Link up on interface eth0
    1970-01-01T01:00:30 : LOGINFO : Warm start.
    2008-01-28T16:39:30 : LOGINFO : Time: set clock from 1970-01-01T01:14:00 to 2008-01-28T16:39:30
    1970-01-01T01:00:26 : LOGINFO : Link up on interface ethernet 0 0 0
    1970-01-01T01:00:27 : LOGINFO : Link up on interface eth0
    1970-01-01T01:00:30 : LOGINFO : Warm start.
    1970-01-01T02:45:58 : LOGINFO : Link down on interface ethernet 0 0 0
    1970-01-01T02:45:58 : LOGINFO : Link down on interface eth0
    1970-01-01T02:46:00 : LOGINFO : Link up on interface ethernet 0 0 0
    1970-01-01T02:46:00 : LOGINFO : Link up on interface eth0
    1970-01-01T01:00:26 : LOGINFO : Link up on interface ethernet 0 0 0
    1970-01-01T01:00:27 : LOGINFO : Link up on interface eth0
    1970-01-01T01:00:30 : LOGINFO : Cold start.
    1970-01-01T01:00:12 : LOGINFO : Warm start.
    1970-01-01T01:00:12 : LOGINFO : Link up on interface ethernet 0 0 0
    1970-01-01T01:00:13 : LOGINFO : Link up on interface eth0
    1970-01-01T01:00:13 : LOGINFO : Auto Configuration (DHCP):
    IP Address of interface eth0 set to 10.0.0.14
    Default gateway set to 10.0.0.1
    Added name server 10.0.0.2

    2008-01-31T16:15:31 : LOGINFO : Time: set clock from 1970-01-01T01:00:14 to 2008-01-31T16:15:31
    1970-01-01T01:00:27 : LOGINFO : Link up on interface ethernet 0 0 0
    1970-01-01T01:00:28 : LOGINFO : Link up on interface eth0
    1970-01-01T01:00:31 : LOGINFO : Warm start.

    ------------------------------------------------------------------------------
    SUPERVISOR LOG
    ==============
    1970-01-01T00:00:05 - #SYSLOG FILE - START - R4.1 2007-05-24 H323 SIP FXS FXO

    1970-01-01T01:00:13 - #SYSLOG FILE - START - R4.1 2007-05-24 H323 SIP FXS FXO

    1970-01-01T01:00:09 - #SYSLOG FILE - START - R4.1 2007-05-24 H323 SIP FXS FXO

    1970-01-01T01:02:21 - #SYSLOG FILE - START - R4.2 2007-09-19 H323 SIP FXS FXO

    1970-01-01T01:00:08 - #SYSLOG FILE - START - R4.2 2007-09-19 H323 SIP FXS FXO

    1970-01-01T01:00:08 - #SYSLOG FILE - START - R4.2 2007-09-19 H323 SIP FXS FXO

    1970-01-01T01:00:08 - #SYSLOG FILE - START - R4.2 2007-09-19 H323 SIP FXS FXO

    1970-01-01T01:00:08 - #SYSLOG FILE - START - R4.2 2007-09-19 H323 SIP FXS FXO

    1970-01-01T01:00:08 - #SYSLOG FILE - START - R4.2 2007-09-19 H323 SIP FXS FXO

    1970-01-01T01:00:08 - #SYSLOG FILE - START - R4.2 2007-09-19 H323 SIP FXS FXO

    1970-01-01T01:00:08 - #SYSLOG FILE - START - R4.2 2007-09-19 H323 SIP FXS FXO

    1970-01-01T01:00:08 - #SYSLOG FILE - START - R4.2 2007-09-19 H323 SIP FXS FXO

    1970-01-01T01:00:08 - #SYSLOG FILE - START - R4.2 2007-09-19 H323 SIP FXS FXO


    ------------------------------------------------------------------------------
    FACTORY CONFIGURATION
    =====================
    #----------------------------------------------------------------#
    # #
    # Factory configuration file #
    # #
    #----------------------------------------------------------------#

    sntp-client
    sntp-client server primary 129.132.2.21 port 123 version 4

    system
    ic voice 0
    low-bitrate-codec g729

    context ip router

    interface eth0
    ipaddress dhcp
    tcp adjust-mss rx mtu
    tcp adjust-mss tx mtu

    port ethernet 0 0
    medium auto
    encapsulation ip
    bind interface eth0 router
    no shutdown

    ------------------------------------------------------------------------------
    STARTUP CONFIGURATION
    =====================
    webserver port 80 language en

    system

    ic voice 0
    low-bitrate-codec g729

    profile ppp default

    profile call-progress-tone UK_Dialtone
    flush-play-list
    play 1 1000 350 -13 440 -13

    profile call-progress-tone UK_Alertingtone
    flush-play-list
    play 1 400 400 -19 450 -19
    pause 2 200
    play 3 400 400 -19 450 -19
    pause 4 2000

    profile call-progress-tone UK_Busytone
    flush-play-list
    play 1 375 400 -24
    pause 2 375

    profile call-progress-tone UK_Releasetone
    flush-play-list
    play 1 400 400 -24
    pause 2 350
    play 3 225 400 -24
    pause 4 525

    profile call-progress-tone UK_Congestiontone
    flush-play-list
    play 1 400 400 -24
    pause 2 350
    play 3 225 400 -24
    pause 4 525

    profile tone-set UK_default
    map call-progress-tone dial-tone UK_Dialtone
    map call-progress-tone ringback-tone UK_Alertingtone
    map call-progress-tone busy-tone UK_Busytone
    map call-progress-tone release-tone UK_Releasetone
    map call-progress-tone congestion-tone UK_Congestiontone

    profile tone-set default

    profile voip default
    codec 1 g711alaw64k rx-length 20 tx-length 20
    codec 2 g711ulaw64k rx-length 20 tx-length 20

    profile voip fax_enabled
    codec 1 g711alaw64k rx-length 20 tx-length 20
    codec 2 g711ulaw64k rx-length 20 tx-length 20
    fax transmission 1 relay t38-udp

    profile pstn default

    profile sip default

    profile aaa default
    method 1 local
    method 2 none

    context ip router

    interface eth0
    ipaddress 10.0.0.14 255.255.255.0

    context cs switch
    no digit-collection timeout

    interface sip sipif0
    bind gateway sipgw0
    service default
    route call dest-service fxohuntgroup
    address-translation outgoing-call request-uri user-part fix 10001 host-part to-header target-param none

    interface sip sipif1
    bind gateway sipgw1
    service default
    route call dest-service fxohuntgroup
    address-translation outgoing-call request-uri user-part fix 10002 host-part to-header target-param none

    interface sip sipif2
    bind gateway sipgw2
    service default
    route call dest-service fxohuntgroup
    address-translation outgoing-call request-uri user-part fix 10003 host-part to-header target-param none

    interface sip sipif3
    bind gateway sipgw3
    service default
    route call dest-service fxohuntgroup
    address-translation outgoing-call request-uri user-part fix 10004 host-part to-header target-param none

    interface fxo fxoif0
    route call dest-interface sipif0
    disconnect-signal battery-reversal
    disconnect-signal busy-tone
    dial-after timeout 1
    use profile tone-set UK_default

    interface fxo fxoif1
    route call dest-interface sipif1
    disconnect-signal battery-reversal
    disconnect-signal busy-tone
    dial-after timeout 1
    use profile tone-set UK_default

    interface fxo fxoif2
    route call dest-interface sipif2
    disconnect-signal battery-reversal
    disconnect-signal busy-tone
    dial-after timeout 1
    use profile tone-set UK_default

    interface fxo fxoif3
    route call dest-interface sipif3
    disconnect-signal battery-reversal
    disconnect-signal busy-tone
    dial-after timeout 1
    use profile tone-set UK_default

    service hunt-group fxohuntgroup
    drop-cause normal-unspecified
    drop-cause no-circuit-channel-available
    drop-cause network-out-of-order
    drop-cause temporary-failure
    drop-cause switching-equipment-congestion
    drop-cause access-info-discarded
    drop-cause circuit-channel-not-available
    drop-cause resources-unavailable
    drop-cause user-busy
    route call 1 dest-interface fxoif0
    route call 2 dest-interface fxoif1
    route call 3 dest-interface fxoif2
    route call 4 dest-interface fxoif3

    context cs switch
    no shutdown

    gateway sip sipgw0
    bind interface eth0 router

    service default
    domain 10.0.0.2
    authentication 10001 password 10001 default
    default-server 10.0.0.2 loose-router
    registrar 10.0.0.2
    user 10001

    gateway sip sipgw0
    no shutdown

    gateway sip sipgw1
    bind interface eth0 router

    service default
    domain 10.0.0.2
    authentication 10002 password 10002 default
    default-server 10.0.0.2 loose-router
    registrar 10.0.0.2
    user 10002

    gateway sip sipgw1
    no shutdown

    gateway sip sipgw2
    bind interface eth0 router

    service default
    domain 10.0.0.2
    authentication 10003 password 10003 default
    default-server 10.0.0.2 loose-router
    registrar 10.0.0.2
    user 10003

    gateway sip sipgw2
    no shutdown

    gateway sip sipgw3
    bind interface eth0 router

    service default
    domain 10.0.0.2
    authentication 10004 password 10004 default
    default-server 10.0.0.2 loose-router
    registrar 10.0.0.2
    user 10004

    gateway sip sipgw3
    no shutdown

    port ethernet 0 0
    medium auto
    encapsulation ip
    bind interface eth0 router
    no shutdown

    port fxo 0 0
    use profile fxo gb
    encapsulation cc-fxo
    bind interface fxoif0 switch
    no shutdown

    port fxo 0 1
    use profile fxo gb
    encapsulation cc-fxo
    bind interface fxoif1 switch
    no shutdown

    port fxo 0 2
    use profile fxo gb
    encapsulation cc-fxo
    bind interface fxoif2 switch
    no shutdown

    port fxo 0 3
    use profile fxo gb
    encapsulation cc-fxo
    bind interface fxoif3 switch
    no shutdown
    ------------------------------------------------------------------------------
    RUNNING CONFIGURATION
    =====================
    #----------------------------------------------------------------#
    # #
    # SN4114/JO/EUI #
    # R4.2 2007-09-19 H323 SIP FXS FXO #
    # 1970-01-01T03:34:43 #
    # SN/00A0BA036638 #
    # Generated configuration file #
    # #
    #----------------------------------------------------------------#

    cli version 3.20
    webserver port 80 language en

    system

    ic voice 0
    low-bitrate-codec g729

    profile ppp default

    profile call-progress-tone UK_Dialtone
    play 1 1000 350 -13 440 -13

    profile call-progress-tone UK_Alertingtone
    play 1 400 400 -19 450 -19
    pause 2 200
    play 3 400 400 -19 450 -19
    pause 4 2000

    profile call-progress-tone UK_Busytone
    play 1 375 400 -24
    pause 2 375

    profile call-progress-tone UK_Releasetone
    play 1 400 400 -24
    pause 2 350
    play 3 225 400 -24
    pause 4 525

    profile call-progress-tone UK_Congestiontone
    play 1 400 400 -24
    pause 2 350
    play 3 225 400 -24
    pause 4 525

    profile tone-set default

    profile tone-set UK_default
    map call-progress-tone dial-tone UK_Dialtone
    map call-progress-tone ringback-tone UK_Alertingtone
    map call-progress-tone busy-tone UK_Busytone
    map call-progress-tone release-tone UK_Releasetone
    map call-progress-tone congestion-tone UK_Congestiontone

    profile voip default
    codec 1 g711alaw64k rx-length 20 tx-length 20
    codec 2 g711ulaw64k rx-length 20 tx-length 20

    profile voip fax_enabled
    codec 1 g711alaw64k rx-length 20 tx-length 20
    codec 2 g711ulaw64k rx-length 20 tx-length 20
    fax transmission 1 relay t38-udp

    profile pstn default

    profile sip default

    profile aaa default
    method 1 local
    method 2 none

    context ip router

    interface eth0
    ipaddress 10.0.0.14 255.255.255.0

    context cs switch
    no digit-collection timeout

    interface sip sipif0
    bind gateway sipgw0
    service default
    route call dest-service fxohuntgroup
    address-translation outgoing-call request-uri user-part fix 10001 host-part to-header target-param none

    interface sip sipif1
    bind gateway sipgw1
    service default
    route call dest-service fxohuntgroup
    address-translation outgoing-call request-uri user-part fix 10002 host-part to-header target-param none

    interface sip sipif2
    bind gateway sipgw2
    service default
    route call dest-service fxohuntgroup
    address-translation outgoing-call request-uri user-part fix 10003 host-part to-header target-param none

    interface sip sipif3
    bind gateway sipgw3
    service default
    route call dest-service fxohuntgroup
    address-translation outgoing-call request-uri user-part fix 10004 host-part to-header target-param none

    interface fxo fxoif0
    route call dest-interface sipif0
    disconnect-signal battery-reversal
    disconnect-signal loop-break
    disconnect-signal busy-tone
    dial-after timeout 1
    use profile tone-set UK_default

    interface fxo fxoif1
    route call dest-interface sipif1
    disconnect-signal battery-reversal
    disconnect-signal loop-break
    disconnect-signal busy-tone
    dial-after timeout 1
    use profile tone-set UK_default

    interface fxo fxoif2
    route call dest-interface sipif2
    disconnect-signal battery-reversal
    disconnect-signal loop-break
    disconnect-signal busy-tone
    dial-after timeout 1
    use profile tone-set UK_default

    interface fxo fxoif3
    route call dest-interface sipif3
    disconnect-signal battery-reversal
    disconnect-signal loop-break
    disconnect-signal busy-tone
    dial-after timeout 1
    use profile tone-set UK_default

    service hunt-group fxohuntgroup
    drop-cause normal-unspecified
    drop-cause no-circuit-channel-available
    drop-cause network-out-of-order
    drop-cause temporary-failure
    drop-cause switching-equipment-congestion
    drop-cause access-info-discarded
    drop-cause circuit-channel-not-available
    drop-cause resources-unavailable
    drop-cause user-busy
    route call 1 dest-interface fxoif0
    route call 2 dest-interface fxoif1
    route call 3 dest-interface fxoif2
    route call 4 dest-interface fxoif3

    context cs switch
    no shutdown

    gateway sip sipgw0
    bind interface eth0 router

    service default
    domain 10.0.0.2
    defaultserver manual 10.0.0.2 loose-router
    registration manual 10.0.0.2
    user 10001 authenticate password dZ8edXkjFnM= encrypted default register

    gateway sip sipgw0
    no shutdown

    gateway sip sipgw1
    call-signaling-port 5062
    bind interface eth0 router

    service default
    domain 10.0.0.2
    defaultserver manual 10.0.0.2 loose-router
    registration manual 10.0.0.2
    user 10002 authenticate password iAaKwKfxX4Q= encrypted default register

    gateway sip sipgw1
    no shutdown

    gateway sip sipgw2
    call-signaling-port 5064
    bind interface eth0 router

    service default
    domain 10.0.0.2
    defaultserver manual 10.0.0.2 loose-router
    registration manual 10.0.0.2
    user 10003 authenticate password ySyWxUvSc/c= encrypted default register

    gateway sip sipgw2
    no shutdown

    gateway sip sipgw3
    call-signaling-port 5066
    bind interface eth0 router

    service default
    domain 10.0.0.2
    defaultserver manual 10.0.0.2 loose-router
    registration manual 10.0.0.2
    user 10004 authenticate password 9CS6/5Fhrd0= encrypted default register

    gateway sip sipgw3
    no shutdown

    port ethernet 0 0
    medium auto
    encapsulation ip
    bind interface eth0 router
    no shutdown

    port fxo 0 0
    use profile fxo gb
    encapsulation cc-fxo
    bind interface fxoif0 switch
    no shutdown

    port fxo 0 1
    use profile fxo gb
    encapsulation cc-fxo
    bind interface fxoif1 switch
    no shutdown

    port fxo 0 2
    use profile fxo gb
    encapsulation cc-fxo
    bind interface fxoif2 switch
    no shutdown

    port fxo 0 3
    use profile fxo gb
    encapsulation cc-fxo
    bind interface fxoif3 switch
    no shutdown


    ==============================================================================
     
  6. Pentangle

    Pentangle Member

    Joined:
    Dec 6, 2007
    Messages:
    261
    Likes Received:
    0
    An update to this, i've determined it's an issue on the Patton, as it does exactly the same to the Digital Receptionist, which removes any handsets from the equation. (also a VoIP trunk from Sipgate works fine with the handsets).

    The call log just says:

    19:42:57.875 Call::Terminate [CM503008]: Call(9): Call is terminated
    19:42:57.859 Call::Terminate [CM503008]: Call(9): Call is terminated
    19:42:57.328 LineCfg::getInboundTarget [CM503011]: Inbound office hours' rule for LN:10001 forwards to DN:8000
    19:42:56.234 LineCfg::getInboundTarget [CM503011]: Inbound office hours' rule for LN:10001 forwards to DN:8000
    19:42:56.218 CallCtrl::eek:nLegConnected [CM503007]: Call(9): Device joined: sip:xxxxxxxxxxx@10.0.0.14:5060
    19:42:55.453 CallCtrl::eek:nLegConnected [CM503007]: Call(9): Device joined: sip:
    19:42:55.453 CallCtrl::eek:nSelectRouteReq [CM503004]: Call(9): Calling: IVR:8000@[Dev]
    19:42:55.437 Line::printEndpointInfo [CM505002]: Gateway:[pattonmhk] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Patton SN4114 JO EUI MxSF v3.2.8.45 00A0BA036638 R4.2 2007-09-19 H323 SIP FXS FXO] Transport: [sip:10.0.0.2:5060]
    19:42:55.437 LineCfg::getInboundTarget [CM503011]: Inbound office hours' rule for LN:10001 forwards to DN:8000
    19:42:55.406 CallCtrl::eek:nIncomingCall [CM503001]: Call(9): Incoming call from xxxxxxxxxxx@(Ln.10001@pattonmhk) to [sip:8000@phonesystem.mydomain.com:5060]
    19:42:55.406 LineCfg::getInboundTarget [CM503011]: Inbound office hours' rule for LN:10001 forwards to DN:8000
     
  7. archie

    archie Well-Known Member
    3CX Staff

    Joined:
    Aug 18, 2006
    Messages:
    1,309
    Likes Received:
    0
    Could you please look at debug log of Patton? It seems it drops the call by some reason. We need to find that reason.
     
  8. Pentangle

    Pentangle Member

    Joined:
    Dec 6, 2007
    Messages:
    261
    Likes Received:
    0
    Archie, where would I find that log? There doesn't seem to be a log named that in the GUI (under "Various/Reports")
     
  9. archie

    archie Well-Known Member
    3CX Staff

    Joined:
    Aug 18, 2006
    Messages:
    1,309
    Likes Received:
    0
    I don't know exactly. But I know that Kevin (at 3cx dot com) turns it on somehow in telnet console to patton and than gets debug output right into console.
     
  10. Pentangle

    Pentangle Member

    Joined:
    Dec 6, 2007
    Messages:
    261
    Likes Received:
    0
    Here you go - the majority of a failed inbound call (it logs a HELL of a lot of data!).

    -----------------------------------
    21:04:39 SIP_SI> [EP sipif0-00b285f8] < Peer : Datapath context: 00000022IP_SI> [EP sipif0-00b285f8 SES 0xe
    help Shows inf
    21:04:39 SIP_SI> [EP sipif0-00b285f8] < Peer : Inband info: yes SES 0xe2f
    (State 'Final-Of
    install Insta
    21:04:39 SIP_SI> [EP sipif0-00b285f8] < Peer : State: CONNECTEDtion 'App-Pr
    jobs Shows current running
    21:04:39 SIP_SI> [EP sipif0-00b285f8] < SM : Event 'CC-Connected' (Active Sta Terminates console / telnet session8 SES 0xe2f610] > SM : N
    te 'Trying', Passive State 'Idle')0:57
    ping
    21:04:39 ARP > Rx ARP Request: Who has 10.0.0.14 tell 10.0.0.2 at 00:14:22:5FP_SI> [EP sipif0-00b285f8] > SM : New
    set Set syste
    :6F:CA0 SIP/
    21:04:39 ARP > Tx ARP Reply: 10.0.0.14 is at 00:A0:BA:03:66:38 tell 10.0.0.2tem information Stack: from 10.0.0.2:5060
    <sip:0787665
    Content-Leng
    sniff
    at 00:14:22:5F:6F:CASn0 Ringingntent-Len

    To: <sip:anonymous@10.0.0.2>;tag=ec1cc03b,realm="3CXP
    who
    From: <sip:xxxxxxxxxxx@10.0.0.2>;tag=50d97f1c0626b33-00b285f8 SES 0xe2f610] < Stack: 180 Ringingpor
    --
    Call-ID: d6b9c9fc6934e5ca16179d9252f56372@10.0.0.2------------------------SIP_SI> [EP sipif0-00b285f
    CSeq: 1811869825 INVITE Event 'Stk-Status'A0BA
    User-Agent: 3CXPhoneSystem 5.0.3790.0anipulates call-control objects
    20:58
    Content-Length: 0ing-Outgoing')3 S


    T
    (Activ
    To: sip:anonymous@10.0.0.2;tag=ec1cc03b Configures DynDNS client0-00b2
    From: sip:xxxxxxxxxxx@10.0.0.2;tag=50d97f1c0626b33edit Edits a non-volatile f
    Call-ID: d6b9c9fc6934e5ca16179d9252f56372@10.0.0.2, Passive State 'Idle') 0) Codec
    21:00:29 FXO >
    CSeq: 1811869825 ACK enter '
    Route: <sip:10.0.0.2;lr>e enter ':q'ive state 'A
    User-Agent: Patton SN4114 JO EUI MxSF v3.2.8.45 00A0BA036638 R4.2 2007-09-19 H32 Go back to 'administrator exec' configurationing Start: Setting port
    3 SIP FXS FXOHook'0]igured


    21:04:39 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610] < Stack: AuFXO > [0 0] PortSM: New s

    Via: SIP/2.0/UDP 10.0.0.14:5060;branch=z9hG4bK7116d92a7sipif0-00e38cc
    21:01:31
    provisioning
    Max-Forwards: 70oning commandsew
    Content-Length: 212 FXO > [0 0] Rin
    To: sip:anonymous@10.0.0.2d Res
    From: sip:xxxxxxxxxxx@10.0.0.2;tag=50d97f1c0626b33FXO > [0 0]
    show Shows
    Call-ID: d6b9c9fc6934e5ca16179d9252f56372@10.0.0.221:01:32 FXO > [0 0
    sip
    CSeq: 1811869826 INVITEmeters of SIP callsFXO
    Route: <sip:10.0.0.2;lr>ait for
    Supported: time

    900",algorithm=MD5,uri="sip:10001@10.0.0.2"tate
    clear Clears
    Supported: replaces1:00:30 FXO > [0
    User-Agent: Patton SN4114 JO EUI MxSF v3.2.8.45 00A0BA036638 R4.2 2007-09-19 H32> [EP sipif0-00
    21:00:30 SIP_SI> [EP si
    dns-lookup Look up t
    3 SIP FXS FXO of a host/IP

    v=0 SI
    o=MxSIP 0 6 IN IP4 10.0.0.14
    CSeq: 13377937 INVITEipif0-
    s=SIP Call
    c=IN IP4 10.0.0.14administrator exec
    t=0 0igura
    m=audio 4874 RTP/AVP 8 0 101ished' (State 'Up'
    1
    21:00:
    a=rtpmap:8 PCMA/80000-00b2w

    Via: SIP/2.0/UDP 10.0.0.14:5060;branch=z9hG4bK7116d92a7 Shows current running commandsb285f8]
    a=fmtp:101
    Max-Forwards: 70Ac
    a=rtpmap:8 pc
    Content-Length: 212 T
    To: sip:anonymous@10.0.0.2sessionpmap:101 telephone-
    From: sip:xxxxxxxxxxx@10.0.0.2;tag=50d97f1c0626b33ng Sends icmp ping packets
    Call-ID: d6b9c9fc6934e5ca16179d9252f56372@10.0.0.2 > [0 0] Driver: Ring StartTo: sip:anonymous
    se
    CSeq: 1811869826 INVITE Set system statexe2f6
    Route: <sip:10.0.0.2;lr>tatus': Code: 0
    show
    Supported: timer Shows syst
    Contact: sip:xxxxxxxxxxx@10.0.0.14:5060ablishing-Outgoing')for
    eari
    sniff
    Content-Type: application/s
    21
    who
    3 SIP FXS FXOrs logged int

    v=0 sy
    o=MxSIP 0 6 IN IP4 10.0.0.14:CE:8B
    a=rtpmap:0 P
    21:00:30
    s=SIP Call
    --- Cur
    c=IN IP4 10.0.0.14------------------
    t=0 0-----
    m=audio 4874 RTP/AVP 8 0 1010xe2f610 SDP] > SM : Actio
    a=rtpmap:8 PCMA/8000tack: to 10.0.0.2:50
    a=rtpmap:0 PCMU/8000 Manipul
    a=rtpmap:101 telephone-event/8000.0.2:5060 SIP/2.0 SIP_SI>

    a=fmtp:101 0-15

    a=sendrec
    2
    edit Edits a non-vo
    CSeq: 1811869826 INVITE). To savethorization:
    Content-Length: 0abddd0f1af10b8636


    21:04:40 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610] < Stack: 100 Tryingto quit without save enter ':q'ce:0001",realm="3CXPhoneSystem",nonce="1
    21:04:40 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610] < SM : Event 'Stk-Status'inistrator exec' configuration14:50
    21:0
    637",algorithm=MD5,uri
    21:01:32 SIP_S
    (State 'Establishing-Outgoing') modees
    pr
    ' (Active State 'Trying', Passive State 'Idle')commandsSES 0xe2f610 SDP] Created datapath cont
    21:04:40 SIP_SI> [EP sipif0-00b285f8] > SM : New active state 'Proceeding' (C0b
    ler: Audio Peer
    21:01:32 SIP_D
    show Shows syst
    ode: 0)mationi
    21:04:40 SIP_TR> [GW] < Stack: from 10.0.0.2:5060ll
    sip Changes run-time
    SIP/2.0 180 Ringingllsterminat6652785@
    Via: SIP/2.0/UDP 10.0.0.14:5060;b




    (State 'Final-Offer-Sent') Resumes a suspended task
    21:04:40 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610] > SM : Action 'App-Alertin Shows information on how to use help_DP> [EP sipif0-00b285f8/0 AUDIO] Fax
    g'ns
    21:04:40 SIP_SI> [EP sipif0-00b285f8] < SM : Event 'Active-Session-Alerting'85f8] > SM : New act

    21:01:32 SIP_DP> [EP sipif0-0
    jobs
    (Active State 'Proceeding', Passive State 'Idle'): 0)-00b2
    21:00:30 SIP_SI> [EP sipif0-00b285f

    21:04:40 SIP_SI> [EP sipif0-00b285f8] > SM : New active state 'Alerting' (Codss Coder: (und:00:30 SIP_SI> [EP sipif0
    ping Se
    e: 0)mp pi
    21:04:41 FXO > [0 0] Driver: Ring Start [EP sipif0-00b285f8/0 AUDIO] Fax Dejitt
    21:04:41 FXO > [0 0] RingSM: State=Idle, Event=Ring-Startem state30 SIP_SI> [EP sipif0-00b285f
    21:01:32 SIP_D
    sho
    21:04:41 FXO > [0 0] RingSM: New state Wait for Stop00:4
    21:00:30 SIP_SI> [EP sipif0-00b285f8] Des
    sniff
    21:04:42 FXO > [0 0] Driv
    21:01:32 SIP

    21:04:44 FXO > [0 0] RingSM: New state Wait for Stop Shows users logged into the system FXO
    21:04:44 SIP_TR> [GW] < Stack: from 10.0.0.2:50608/0 AUD
    --- Current mode commands --------------
    SIP/2.0 200 OK--------------
    Via: SIP/2.0/UDP 10.0.0.14:5060;branch=z9hG4bK7116d92a7P_
    l-Off
    21:01:32 SIP_DP> [EP
    call-control
    Contact: <sip:xxxxxxxxxxx@10.0.0.2:5060>E sip:10001@ jobs
    To: <sip:anonymous@10.0.
    debug
    edit
    User-Agent: 3CXPhoneSystem 5.0.3790.0file (append only). To saveUDIO] Ma
    Content-Length: 242 Lo

    v=0 th
    o=3cxPS 324941119488 315763982337 IN IP4 10.0.0.2 enter ':wq', to quit without save enter ':q'ack
    s=3cxPS Audio call Enters 'admin
    c=IN IP4 10.0.0.10iguration mode8

    t=0 0
    m=audio 62646 RTP/AVP 8 0 101dministrator exec' configurat
    a=fmtp:101 0-16
    a=rtpmap:8 pcma/8000 taskID: b70ffab89b

    a=rtpmap:0 pcmu/8000 mode
    a=rtpmap:101 telephone-event/8000

    ping
    21:04:44 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610 SDP] < SM : Event 'RX-Succeracing information0.0.14-event/8000
    s=S
    set
    ss' (State 'Final-Offer-Sent') Executes provisioning comman
    21:04:44 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610 SDP] > SM : Action 'Verify- Restarts the system
    21:01:32 SIP_DP> [EP sipif0-00b285f8/0 DP] Pee
    Received-Answer' Sh
    21:04:44 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610 SDP] < Stack: Verifying answep Changes run-time parameters of SIP calls terminal para
    ro
    21:04:44 SIP_SI> [EP sipif0-00b285f8] Remote Codecs: Voice:ip:anonymous@10.0.0.2>;
    dyn
    help Shows information o
    G.711 A-law[any/any][ss], G.711 u-law[any/any][ss]ce
    edit
    install
    21:04:44 SIP_SI> [EP sipif0-00b285f8] Resulting Remote Codecs: Voice:Local=0, NSE-Re
    jobs Shows current running commands[EP
    G.711 A-law[20/20], G.711 u-law[20/20]nge direction to S
    logout
    21:04:44 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610 SDP] > SM : Action 'Update-ec
    21:01:32 SIP_SI> [EP sipif
    ping Sends icmp ping pac
    Datapath'destinati
    21:04:44 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610 SDP] Stream 0: Added_SI> [EP s
    set Set system state 'TX-Ack'82a5f
    21:04:44 SIP_DP> [EP sipif0-00b285f8/0 DP] Using datapath termination 0x0200fffmationtiated')t configuration mode
    21:01:32 SIP_SI> [EP sip
    sniff
    f
    21:04:44 SIP_DP> [EP sipif0-00b285f8/0 AUDIO] Voice Update Frames: disa
    User-Agent: Patton SN4114 JO EUI MxSF v3.2.8.45
    copy
    bledCopi
    21:04:44 SIP_DP> [EP sipif0-00b285f8/0 AUDIO] DTMF Relay: enabS
    debug Enables debug monitorf0-00b285f8 SES 0xe2f610] >
    led :
    21:04:44 SIP_DP> [EP sipif0-00b285f8/0 AUDIO] Mute Encoder: enab:58:54 SIP_SI> [EP
    hed': 18
    21:01:32 SIP_SI
    edit Edit
    lednon
    21:04:44 SIP_DP> [EP sipif0-00b285f8/0 AUDIO] Modem Transmission mode: none0 0] Driver: Ring Sto
    ed'b29
    21:01
    enter ':wq', to

    21:04:44 SIP_DP> [EP sipif0-00b285f8/0 AUDIO] Fax Transmission mode: none929c7be7932990f491de8
    end Go back to 'administrator ex

    21:04:44 SIP_DP> [EP sipif0-00b285f8/0 AUDIO] Fax Bypass Coder: (und] > SM :
    mode tmonit rout



    efined)
    arp
    one
    21:04:44 SIP_DP> [EP sipif0-00b285f8/0 AUDIO] Fax Bypass Method: defa33 FXO > [0 0
    blink Reads/writes/erases data from/to
    ultMethoder
    21:01:
    21:04:44 SIP_DP> [EP sipif0-00b285f8/0 AUDIO] Modem Bypass Method: defa Manipulates local calls4 SIP
    21:
    21:01:33 FXO > [0 0] PortSM: New state
    ultMethod
    21:04:44 SIP_DP> [EP sipif0-00b285f8/0 AUDIO] Fax Volume: 1273bug Enables debug monitor Resulting Remote C
    21:01:
    2456IP_S
    21:04:44 SIP_DP> [EP sipif0-00b285f8/0 AUDIO] Configuring datapath termination:1 A-law[20/20], G.711 u-law[20
    21:01:33 SIP_DP> [EP sipif0-0
    enable
    Dejitter Enters
    21:04:44 SIP_DP> [EP sipif0-00b285f8/0 AUDIO] Dejitter Mode: adapti
    21:01:33 SIP_SI> [EP sipif0-00b2O

    21:04:44 SIP_DP> [EP sipif0-00b285f8/0 DP] Add termination RTP-00/0022 to conteoutes which IP packets takes to a networkO > [0 0] RingSM: State=Idle,
    21:01:2
    xt 00000022EP sipif0-0
    21:04:44 SIP_DP> [EP sipif0-00b285f8/0 AUDIO] Configuring datapath termination:S 0xe
    who Shows users logged into the system6 SIP_SI>
    RTPsipi
    21:04:44 SIP_DP> [EP sipif0-00b285f8/0 AUDIO] Local Address: 10.0.0.14/4874---------------------------------------------P/2.0/UDP
    'Idle', Passive St
    21:0
    21:04:44 SIP_DP> [EP sipif0-00b285f8/0 AUDIO] Remote Address: 10.0.0.10/62646ulates call-control objects2785@10.0.0.2:5060 SIP/2.0nectivity d

    To: <sip:a

    d/Receive.
    21:04:44 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610] > SM : Action 'Stk-Ack'SN4114 JO EUI MxSF v3.2.
    exit Goes back to the paren
    21:04:44 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610 SDP] < SM : Event 'TX-Ack'state 'Relea
    h323 Changes run-time parameters of H.323
    (State 'Negotiated')
    21:01:33 SIP_TR> [
    21:04:44 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610] > Stack: ACK Display memory tracing information0

    Via: SIP/2.0/UDP 1
    21:04:44 SIP_TR> [GW] > Stack: to 10.0.0.2:5060ing Executes provisioning command
    ACK sip:xxxxxxxxxxx@10.0.0.2:5060 SIP/2.00xe2f
    sip:078
    reload
    Via: SIP/2.0/UDP 10.0.0.14:5060;branch=z9hG4bKaa96fc8a60b285f8 SES 0xe2f610

    show Sho
    Max-Forwards: 70tion sipif0-00b2
    Content-Length: 0ip:anonymous@10.0
    To: sip:anonymous@10.0.0.2;tag=6976da35anges run-time parameters of SIP calls0
    From: sip:xxxxxxxxxxx@10.0.0.2;tag=50d97fE



    21:04:44 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610] > SM : Action 'App-Connecthe
    21:04:39 FXO > [0 0] RingSM: State=Wait for Stop, Event=Ring-Stop101
    G
    ed'(Ac
    21:04:44 SIP_SI> [EP sipif0-00b285f8] < SM : Event 'Active-Session-Connected'SM: Notifying Ring Detect33 SIP_SI> [EP sipif0-00b285f8] > SM : New active
    2
    (Active State 'Alerting', Passive State 'Idle')if0-00

    21:01:26 SIP_SI> [EP sipif0-00b28
    21:04
    21:04:44 SIP_SI> [EP sipif0-00b285f8] > SM : New active state 'Connected' (Cod'P_SI> [EP sipif0-00b285f8 SES 0x
    21:04:39 SIP_SI> [EP sipif0-00b285f8] > LocC
    de: 0)d Loca
    21:04:44 FXO > [0 0] PortSM: State=On-Hook, Event=Off-Hookpath'0-0
    m
    21:01:33 SIP_SI> [PR sipif0
    Configured/Peer Cap
    21:04:44 FXO > [0 0] PortSM: Notifying Off-HookP_SI> [EP sipif0-00b285f8] Configured Cod
    21:04:44 FXO > [0 0] Driver: Setting port state to 'OffHook'5f8] < Peer : State: CONNECTE
    21
    G.711 A-law[20/20], G.711 u-la
    21:04:44 FXO > [0 0] PortSM: New s

    21:04:45 FXO > [0 0] PortSM: State=Off-Hook, Event=On-Hook.711 u-law[20/20]ARP Reply: 10.0.0.14 is at 00:A0:BA:03
    21:04
    21:04:45 FXO > [0 0] PortSM: Notifying On-Hookocal Capabilities mergingred: 3CXPhoneSystemg
    at
    21:04:45 FXO > [0 0] Driver: Setting port state to 'OnHook'gured/Peer Capabilities ARP databaserom: sip:0787665
    21:04:39
    21:04:45 FXO > [0 0] PortSM: New state On-HookCodecs: Voice:rites/erases data from/to Fla
    21:04:45 FXO > [0 0] PortSM: Notifying Caller-Id-Enablelaw[20/20], G.711 u-law[20/20] Manipulates loca
    21:04:45 SIP_SI> [EP sipif0-00b285f8] < Peer : Inband info: noCodecs: N/A Clears the screen0.0.0.14:5060;branc
    21:04:45 SIP_DP> [EP sipif0-00b285f8/0 DP] Peer call-leg provides no data Resulting Local Codecs: Voice:ength: 0m="3CXP
    dns-lookup
    21:04:45 SIP_SI> [EP sipif0-00b2efined)

    'Connected', Passive State 'Idle') Remote Codecs: N/A
    21:04:45 SIP_SI> [EP sipif0-00b285f8] > SM : New active state 'Disconnected'04:39 SIP_SI> [EP sipif0-00b285f8] Resulting Remote Codecs: Voice:
    (Code: 0) Sen
    21:04:45 SIP_SI> [EP sipif0-00b285f8] > Peer : Code: 0 -> Cause: Normal call cl11 u-law[20/20]7876652785@10.0.0.2;t
    set
    21:04:39 SIP_SI> [EP sipi
    earing (Profile: default)

    tate 'Established')d New s
    ="10001",re
    21:04:45 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610] > SM : Action 'Stk-Bye'kes to a networkCaller-Id-Disableinished'-----------
    21:04:39 SIP_SI> [EP si
    21:04:45 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610] > Stack: BYE datapath termin
    Suppo
    who
    21:04:39 SIP_SI> [EP sipif0-00b28
    21:04:45 SIP_TR> [GW] > Stack: to 10.0.0.2:5060638 R4.2 2007-09-19 H32DP> [EP sipif0-
    --- Cur
    BYE sip:xxxxxxxxxxx@10.0.0.2:5060 SIP/2.0-------op d

    Supported: timerres DynDN
    Offer'
    Proxy-Authorization: Digest response="97ae6943bd28aac652dd42a3b48fd232",username Edits a non-volatile file (append only). To savewards: 70: No
    21:04:39 SIP
    ="10001",realm="3CXPhoneSystem",nonce="12846528531:cc567ff9f7aa4cdc7ee9c3525bafc enter ':wq', to quit without save enter ':q'76645966f402480 SIP_D
    l-Offer-Sent'
    900",algorithm=MD5,uri="sip:xxxxxxxxxxx@10.0.0.2:5060" 0xe2f610] > Stack: INVITE sip:10001@tor exec' conP> [
    Supported: replaces
    CSeq: 13377937 I
    21:00:29 SIP_DP> [EP
    sip:anon
    g'us
    21:04:45 SIP_TR> [GW] < Stack: from 10.0.0.2:5060_TR> [GW] > Stack: to 10.0.0.2:5060d944a7d9b12f628
    SIP/2.0 200 OKb2
    xt 0000
    m
    Via: SIP/2.0/UDP 10.0.0.14:5060;branch=z9hG4bK902273332ation,algorithm
    Via: SIP/2.0/UDP 10.0.0.14:5060;branch=
    Contact: <sip:xxxxxxxxxxx@10.0.0.2:5060> Executes provisioning comm
    Max-Forwa
    To: <sip:anonymous@10.0.0.2>;tag=6976da352BA036638 R4
    reloa
    To: sip:anonymous@10
    From: <sip:xxxxxxxxxxx@10.0.0.2>;tag=50d97f1c0626b335@10.0.0.2;tag=50d97f1c0626b33 Show
    Call-ID: d6b9c9fc6934e5ca16179d9252f56372@10.0.0.25ca16179d9252f56372@10.0.0.2p
    CSeq: 1811869827 BYE
    State 'Negotiated')
    21:04:45 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610] > SM : New state 'Released
    '
    21:04:45 SIP_SI> [EP sipif0-00b285f8 SES 0xe2f610] > SM : Action 'App-Finishe
    d'
    21:04:45 SIP_SI> [EP sipif0-00b285f8] < SM : Event 'Active-Session-Finished'
    (Active State 'Disconnected', Passive State 'Idle')
    21:04:45 SIP_SI> [EP sipif0-00b285f8] > SM : New active state 'Released' (Cod
    e: 0)
    21:04:45 SIP_SI> [EP sipif0-00b285f8] > SM : Action 'App-Finished'
    21:04:45 SIP_SI> [EP sipif0-00b285f8] Finished
    21:04:45 SIP_SI> [PR sipif0] Removed endpoint sipif0-00b285f8
    21:04:45 SIP_SI> [EP sipif0-00b285f8] Destroying a session
    21:04:45 SIP_SI> [EP sipif0-00b285f8] Destroying a session
    21:04:45 SIP_SI> [EP sipif0-00b285f8] Removing a session
    21:04:45 SIP_SI> [PR sipif0] < SM : Event 'Ep-Finished' (State 'Up')
    21:04:49 ARP > Rx ARP Request: Who has 10.0.0.14 tell 10.0.0.10 at 00:04:13:2
    7:67:0F
    21:04:49 ARP > Tx ARP Reply: 10.0.0.14 is at 00:A0:BA:03:66:38 tell 10.0.0.10
    at 00:04:13:27:67:0F
    21:04:50 FXO > [0 0] RingSM: State=Idle, Event=Timeout
    21:04:50 FXO > [0 0] RingSM: Notifying Ring Stop Detect
    21:04:50 FXO > [0 0] Driver: Setting port state to 'OnHook'
     
  11. Pentangle

    Pentangle Member

    Joined:
    Dec 6, 2007
    Messages:
    261
    Likes Received:
    0
    Making it a little easier for you to debug, i've enabled certain sections of the debugging one by one, and this is what the FXO debug log gives:

    1:15:15 FXO > [0 0] RingSM: State=Idle, Event=Ring-Start
    21:15:15 FXO > [0 0] RingSM: New state Wait for Stop
    21:15:16 FXO > [0 0] Driver: Ring Stop
    21:15:16 FXO > [0 0] RingSM: State=Wait for Stop, Event=Ring-Stop
    21:15:16 FXO > [0 0] RingSM: Notifying Ring Detect
    21:15:16 FXO > [0 0] RingSM: New state Idle
    21:15:17 FXO > [0 0] PortSM: State=On-Hook, Event=Off-Hook
    21:15:17 FXO > [0 0] PortSM: Notifying Off-Hook
    21:15:17 FXO > [0 0] Driver: Setting port state to 'OffHook'
    21:15:17 FXO > [0 0] PortSM: New state Off-Hook
    21:15:17 FXO > [0 0] PortSM: Notifying Caller-Id-Disable
    21:15:18 FXO > [0 0] Driver: Line reversal
    21:15:18 FXO > [0 0] PortSM: State=Off-Hook, Event=Line-Reversal
    21:15:18 FXO > [0 0] PortSM: Notifying Line-Reversal
    21:15:18 FXO > [0 0] PortSM: Notifying Caller-Id-Disable
    21:15:18 FXO > [0 0] PortSM: State=Off-Hook, Event=On-Hook
    21:15:18 FXO > [0 0] PortSM: Notifying On-Hook
    21:15:18 FXO > [0 0] Driver: Setting port state to 'OnHook'
    21:15:18 FXO > [0 0] PortSM: New state On-Hook
    21:15:18 FXO > [0 0] PortSM: Notifying Caller-Id-Enable
    21:15:22 FXO > [0 0] RingSM: State=Idle, Event=Timeout
    21:15:22 FXO > [0 0] RingSM: Notifying Ring Stop Detect
    21:15:22 FXO > [0 0] Driver: Setting port state to 'OnHook'

    This looks like it's being told to place the call on-hook. I've no idea what "line-reversal" is though?!
     
  12. Pentangle

    Pentangle Member

    Joined:
    Dec 6, 2007
    Messages:
    261
    Likes Received:
    0
    and here's the SIP Signaling debug log:

    10.0.0.14#21:25:31 SIP_SI> [PR sipif0] Added endpoint sipif0-00e0ab50
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] > LocCp: Build Local Capabilities mergins
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] Configured Codecs: Voice:]
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] Peer Codecs: N/A
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] Resulting Local Codecs: Voice:]
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] > RemCp: Build Remote Capabilities mergis
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] Configured Codecs: Voice:]
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] Remote Codecs: N/A
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] Resulting Remote Codecs: Voice:]
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] > LocCp: Build Local Capabilities mergins
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] Configured Codecs: Voice:]
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] Peer Codecs: N/A
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] Resulting Local Codecs: Voice:]
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] > RemCp: Build Remote Capabilities mergis
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] Configured Codecs: Voice:]
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] Remote Codecs: N/A
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] Resulting Remote Codecs: Voice:]
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] do not reroute because: call id propertyg
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] < SM : Event 'CC-Offered' (Active Stat)
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] > SM : New active state 'Trying' (Code)
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] > SM : Action 'Session-Dial'
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] Creating a new session
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] Adding a new session
    21:25:31 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38] < SM : Event 'App-Dial' ()
    21:25:31 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38] > SM : Action 'Stk-Invite'ocal=0, NSE-Re
    21:25:31 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38 SDP] < SM : Event 'TX-Invi)bug monitor
    job
    21:25:31 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38 SDP] > SM : Action 'Create'
    tftp-client Enables TFTP client debug monitor
    21:25:31 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38 SDP] > Stack: Offeraw[20/20], G.711 u-law[20/20]nge directio
    21:25:31 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38 SDP] > SM : New state 'Fin'.0.0.14#debug call-router:44 SIP_SI> [EP sipif0-00b
    21:25:31 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38] > Stack: INVITE sip:10001@12
    21:14:22 CR > Execute a
    21:14:22
    21:25:31 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38] > SM : New state 'Establi'

    21:01:32 SIP_SI> [EP sip
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] < Peer : Datapath context: 00000022d-EnableIO] Voice Update Frames: disa
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] < Peer : Inband info: yes
    21:15:12 FXO > [0 0] Driver: Ring Start
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] < Peer : State: CONNECTED
    21:15:12 FXO > [0 0] RingSM: State=Idle, Event=Ring-
    21:25:31 SIP_SI> [EP sipif0-00e0ab50] < SM : Event 'CC-Connectedcopy
    bledCopi
    21:04:44 SIP_DP> [E
    blink
    21:
    21:25:32 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38] < Stack: 180 Ringing
    ultMethoder
    21
    21:25:32 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38] < SM : Event 'Stk-Status'): defa Manipu
    21:25:32 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38 SDP] < SM : Event 'RX-Info)g port state to 'OffHook'
    21:25:32 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38] > SM : Action 'App-Alerti'
    u
    21:01:2
    xt 00000022EP sipif
    21:25:34 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38 SDP] > SM : Action 'Publis'
    h323
    21:25:34 SIP_SI> [EP sipif0-00e0ab50] > RemCp: Build Remote Capabilities mergis
    dhcp-client Enables DHCP debug moni
    21:25:34 SIP_SI> [EP sipif0-00e0ab50] Configured Codecs: Voice:]
    21:01
    21:04:
    flashserver Enable
    21:25:34 SIP_SI> [EP sipif0-00e0ab50 SES 0xe0ab50 SDP] DTMF over RTP enabled.
    framerelay
    21:25:34 SIP_SI> [EP sipif0-00e0ab50] Remote Codecs: Voice:]

    21:25:34 SIP_SI> [EP sipif0-00e0ab50] Resulting Remote Codecs: Voice:]060;branch=z9hG4bKaa96fc8a60b
    21:25:34 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38] > SM : Action 'App-Connec'
    2
    (Active State 'Alertin
    21:25:34 SIP_SI> [EP sipif0-00e0ab50] < SM : Event 'Active-Session-Connected)onitor

    21:01:26
    21:25:35 SIP_SI> [EP sipif0-00e0ab50] < Peer : Cause: Normal call clearing -> )
    21:25:35 SIP_SI> [EP sipif0-00e0ab50] < SM : Event 'CC-Dropped' (Active Stat)
    21:25:35 SIP_SI> [EP sipif0-00e0ab50] > SM : New active state 'Disconnected')
    21:25:35 SIP_SI> [EP sipif0-00e0ab50] > Peer : Code: 0 -> Cause: Normal call c)
    21:25:35 SIP_SI> [EP sipif0-00e0ab50] > SM : Action 'Session-Drop'
    21:25:35 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38] < SM : Event 'App-Drop' ()
    21:25:35 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38] > SM : Action 'Stk-Bye'
    21:25:35 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38] > Stack: BYE
    21:25:35 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38] > SM : New state 'Releasi'
    21:25:35 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38] < Stack: 200 OK (Terminatio)
    21:25:35 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38] < SM : Event 'Stk-Term-St)
    21:25:35 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38 SDP] < SM : Event 'Reset' )
    21:25:35 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38] > SM : New state 'Release'
    21:25:35 SIP_SI> [EP sipif0-00e0ab50 SES 0xe37f38] > SM : Action 'App-Finish'
    21:25:35 SIP_SI> [EP sipif0-00e0ab50] < SM : Event 'Active-Session-Finished')
    21:25:35 SIP_SI> [EP sipif0-00e0ab50] > SM : New active state 'Released' (Co)
    21:25:35 SIP_SI> [EP sipif0-00e0ab50] > SM : Action 'App-Finished'
    21:25:35 SIP_SI> [EP sipif0-00e0ab50] Finished
    21:25:35 SIP_SI> [PR sipif0] Removed endpoint sipif0-00e0ab50
    21:25:35 SIP_SI> [EP sipif0-00e0ab50] Destroying a session
    21:25:35 SIP_SI> [EP sipif0-00e0ab50] Destroying a session
    21:25:35 SIP_SI> [EP sipif0-00e0ab50] Removing a session
    21:25:35 SIP_SI> [PR sipif0] < SM : Event 'Ep-Finished' (State 'Up')
     
  13. Pentangle

    Pentangle Member

    Joined:
    Dec 6, 2007
    Messages:
    261
    Likes Received:
    0
    Just to let you know, this looks to be a related issue to this one logged by Zen Software:

    http://www.3cx.com/forums/patton-sn4114-in-the-uk-not-disconecting-calls-fix-3627.html

    (although changing the tone settings didn't help the inbound call not dropping)
     
  14. robjbrad

    Joined:
    Dec 6, 2007
    Messages:
    5
    Likes Received:
    0
  15. Pentangle

    Pentangle Member

    Joined:
    Dec 6, 2007
    Messages:
    261
    Likes Received:
    0
    Hi Rob,

    Yes I tried your fix, with no success i'm afraid. Currently my status is that calls originated from outside and terminated inside don't terminate the originating handset. Everything else seems to work (the battery reversal tickbox was the key to keeping the call up).
     

Share This Page