Hello everyone!
FreePBX: 15.0.16.53
Asterisk: 16.9.0
Cisco 7975G w/ SIP FW: 9.4(2) SR4-3
FreePBX IP: 172.17.50.99
Phone IP: 172.17.50.111 (DHCP)
I have a Cisco 7975G running the latest SIP Firmware version, and I have a xml file that is parsed ok and hands the settings to the phone, but for some reason the phone will not get past the “Registering…” phase
After several hours of playing with this I am completely out of ideas on how to fix this. There are some mentions on here form 5+ years ago about setting NAT to “Never” in freePBX somewhere, though non are clear where, and the only place I can find it is in the Asterisk SIP Settings (I am using pjSip) and toggling that does not make a difference.
I also tried using firmware 8.5(4)S with the exact same results.
Below are the logs from the phone and the XML config file.
Thanks in advance for the help.
Here are the pjSIP logs from asterisk: https://pastebin.freepbx.org/view/07d5a4e6
Here are the logs from the phone itself:
8399: WRN 21:15:30.090798 JVM: Startup Module Loader|cip.l10n.NetworkLocaleProperty:? - Unable to process LocaleProperty 'device.settings.config.localization.networklocale'
8400: WRN 21:15:30.175801 SECD: WARN:lookupCTL: ** no CTL, assume UCM NONSECURE
8401: ERR 21:15:30.524295 JVM: ..LOCALCONNECT FAILED
8402: NOT 21:15:30.581286 JVM: SIPCC-SIP_CTRL: sip_restart: In sip_restart
8403: NOT 21:15:30.592864 JVM: set_active_ccm: ccm=PRIMARY_CCM port=0
8404: ERR 21:15:30.593596 JVM: SIP : sip_transport_setup_cc_conn : Admin has not configured a valid cucm for cucm index=SECONDARY_CCM=1.
8405: ERR 21:15:30.594367 JVM: SIP : sip_transport_setup_cc_conn : Admin has not configured a valid cucm for cucm index=TERTIARY_CCM=2.
8406: ERR 21:15:30.595060 JVM: SIP : sip_transport_setup_cc_conn : Admin has not configured a valid cucm for cucm index=SRST_CCM=3.
8407: ERR 21:15:30.595758 JVM: sip_regmgr_setup_cc_conns: NO VALID STANDBY CALL CONTROL AVAILABLE!
8408: NOT 21:15:30.596465 JVM: SIPCC-SIP_REG: sip_sm_init: Disabling mass reg state
8409: NOT 21:15:30.615824 JVM: SIPCC-SIP_REG: ccsip_register_all_lines: Disabling mass reg state
8410: NOT 21:15:30.616876 JVM: SIPCC-UI_API: 1/0, ui_set_sip_registration_state: 0
8411: NOT 21:15:30.619856 JVM: SIPCC-CC_API: 0/0, cc_int_feature: UI -> GSM: UPD_MEDIA_CAP
8412: NOT 21:15:30.620963 JVM: SIPCC-SIP_CTRL: sip_restart: sip.taskInited is set to true
8413: NOT 21:15:30.621763 JVM: SIPCC-CC_API: 0/0, cc_int_fail_fallback: SIP -> GSM: FAILOVER_FALLBACK
8414: NOT 21:15:30.625160 JVM: SIPCC-DCSM: dcsm_process_event: DCSM 0 :(DCSM_READY:FEAT:UPD_MEDIA_CAP)
8415: NOT 21:15:30.627392 JVM: SIPCC-SIP_REG_STATE: 1/51, sip_reg_sm_process_event: SIP_REG_STATE_IDLE <- E_SIP_REG_REG_REQ
8416: NOT 21:15:30.629308 JVM: ccsip_messaging: sipSPIAddContactHeader: CFGID_DEVICE_NAME = SEP64D989C242E5
8417: NOT 21:15:30.632114 JVM: SIPCC-SIP_MSG_SEND: ccsip_dump_send_msg_info: <172.17.50.99:5060>:REGISTE: <sip:[email protected]> :109 REGISTER::[email protected]
8418: NOT 21:15:30.832240 JVM: Startup Module Loader|cip.sipcc.SipCcAdapter: - cmname=172.17.50.99 cmIp=172.17.50.99 port=5060 isValid=true
8419: ERR 21:15:30.834325 JVM: Startup Module Loader|cip.sipcc.SipCcAdapter: - addSrstToCAgentList: isSrstSecure=false
8420: NOT 21:15:30.836001 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.callagent.messages.0 value=0
8421: NOT 21:15:30.837928 JVM: Startup Module Loader|cip.sipcc.d: - initializeLinePlane(): Mgmt Interface is in Service now..
8422: WRN 21:15:30.839600 JVM: Startup Module Loader|DisplayTask:? - Line 1 property added with dn 1001
8423: ERR 21:15:30.841346 JVM: Startup Module Loader|cip.props.u:? - device.settings.energywise.keywords IO error.
8424: ERR 21:15:30.899385 JVM: tftpClient dialplan.xml /usr/cache/DP158232698 550001 1
8425: NOT 21:15:30.903249 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - --->ConfigManager PropertyChanged: device.callagent.callcount
8426: NOT 21:15:30.904853 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - <---ConfigManager PropertyChanged: device.callagent.callcount
8427: NOT 21:15:30.906494 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.callagent.messages.0 value=0
8428: NOT 21:15:30.907867 xxtpClient: xxtp request rcv'd from /usr/tmp/tftp, srcFile = dialplan.xml, dstFile = /usr/cache/DP158232698 max size = 550001
8429: NOT 21:15:30.908967 ESP: server 1 =
8430: NOT 21:15:30.922136 xxtpClient: auth server - tftpList[0] = ::ffff:172.17.50.99
8431: NOT 21:15:30.922768 xxtpClient: look up server - 0
8432: WRN 21:15:30.924796 SECD: WARN:lookupCTL: ** no CTL, assume TFTP NONSECURE
8433: NOT 21:15:30.927613 xxtpClient: secVal = 0xa
8434: NOT 21:15:30.928332 xxtpClient: ::ffff:172.17.50.99 is a NONsecure server
8435: NOT 21:15:30.928941 xxtpClient: temp retval = SRVR_NONSECURE, keep looking
8436: NOT 21:15:30.929489 xxtpClient: retval = 10
8437: NOT 21:15:30.930834 xxtpClient: Secure file requested
8438: NOT 21:15:30.931487 xxtpClient: Non secure file approved -- dialplan.xml
8439: NOT 21:15:30.949471 HTTPCL: downdload will be limited to 537 KB
8440: ERR 21:15:30.955011 HTTPCL: connect() failed
8441: NOT 21:15:30.964677 SYSMSG: pid 29 (/sbin/httpcl) Normal Exit, status = 102
8442: INF 21:15:30.964717 runtime = 0.020 secs
8443: INF 21:15:30.964738 user cpu = 0.001228030 secs
8444: INF 21:15:30.964758 system cpu = 0.012194720 secs
8445: INF 21:15:30.964772 child user cpu = 0.000000000 secs
8446: INF 21:15:30.964787 child sys cpu = 0.000000000 secs
8447: INF 21:15:30.964808 sys interrupts = 0.000854890 secs for 22 interrupts
8448: INF 21:15:30.964832 total cpu = 0.013422750 secs ( 50% utilization )
8449: WRN 21:15:30.983327 xxtpClient: HTTP failed with code 102
8450: NOT 21:15:31.029141 TFTP: [28]:Requesting dialplan.xml from 172.17.50.99 with size limit of 550001
8451: NOT 21:15:31.032485 TFTP: [28]:Finished --> rcvd 33 bytes
8452: NOT 21:15:31.122562 JVM: SIPCC-SIP_REG_STATE: 1/51, sip_reg_sm_process_event: SIP_REG_STATE_REGISTERING <- E_SIP_REG_TMR_RETRY
8453: NOT 21:15:31.172547 xxtpClient: request server6 0 ---> ::
8454: NOT 21:15:31.173733 ESP: server 2 = ::
8455: NOT 21:15:31.195827 xxtpClient: request server6 0 ---> ::
8456: NOT 21:15:31.212366 xxtpClient: request server6 1 ---> ::
8457: NOT 21:15:31.213556 ESP: server 3 = ::
8458: NOT 21:15:31.221283 ESP: send ADMIN, logging = 1, shell = 0, ipconfig = 1
8459: NOT 21:15:31.236792 xxtpClient: request server6 1 ---> ::
8460: ERR 21:15:31.247187 JVM: ..LOCALCONNECT FAILED
8461: ERR 21:15:31.250871 JVM: ..LOCALCONNECT FAILED
8462: ERR 21:15:31.254280 JVM: ..LOCALCONNECT FAILED
8463: ERR 21:15:31.257753 JVM: ..LOCALCONNECT FAILED
8464: ERR 21:15:31.262039 JVM: ..LOCALCONNECT FAILED
8465: ERR 21:15:31.265428 JVM: ..LOCALCONNECT FAILED
8466: ERR 21:15:31.268844 JVM: ..LOCALCONNECT FAILED
8467: WRN 21:15:31.287606 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
8468: WRN 21:15:31.288627 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//ITLFile.tlv
8469: WRN 21:15:31.293952 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
8470: WRN 21:15:31.294965 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//ITLFile.tlv
8471: WRN 21:15:31.300194 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
8472: WRN 21:15:31.301209 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//ITLFile.tlv
8473: WRN 21:15:31.306795 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
8474: WRN 21:15:31.307865 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//ITLFile.tlv
8475: NOT 21:15:31.319010 xxtpClient: request server 0 ---> 172.17.50.99
8476: NOT 21:15:31.319851 ESP: server 0 = 172.17.50.99
8477: NOT 21:15:31.342964 xxtpClient: request server 1 --->
8478: NOT 21:15:31.344121 ESP: server 1 =
8479: NOT 21:15:31.366526 xxtpClient: request server 0 ---> 172.17.50.99
8480: NOT 21:15:31.382980 xxtpClient: request server6 0 ---> ::
8481: NOT 21:15:31.384230 ESP: server 2 = ::
8482: NOT 21:15:31.415999 xxtpClient: request server 1 --->
8483: NOT 21:15:31.435799 xxtpClient: request server6 1 ---> ::
8484: NOT 21:15:31.436924 ESP: server 3 = ::
8485: NOT 21:15:31.452302 CDP-D: cdpGetPortCfg SPANTOPC CFG:11
8486: NOT 21:15:31.545590 xxtpClient: request server6 0 ---> ::
8487: NOT 21:15:31.566701 xxtpClient: request server6 1 ---> ::
8488: ERR 21:15:31.572030 JVM: ..LOCALCONNECT FAILED
8489: ERR 21:15:31.575496 JVM: ..LOCALCONNECT FAILED
8490: ERR 21:15:31.578890 JVM: ..LOCALCONNECT FAILED
8491: ERR 21:15:31.583192 JVM: ..LOCALCONNECT FAILED
8492: ERR 21:15:31.586529 JVM: ..LOCALCONNECT FAILED
8493: ERR 21:15:31.589907 JVM: ..LOCALCONNECT FAILED
8494: ERR 21:15:31.593390 JVM: ..LOCALCONNECT FAILED
8495: NOT 21:15:32.122323 JVM: SIPCC-SIP_REG_STATE: 1/51, sip_reg_sm_process_event: SIP_REG_STATE_REGISTERING <- E_SIP_REG_TMR_RETRY
8496: NOT 21:15:34.126417 JVM: SIPCC-SIP_REG_STATE: 1/51, sip_reg_sm_process_event: SIP_REG_STATE_REGISTERING <- E_SIP_REG_TMR_RETRY
8497: NOT 21:15:38.122252 JVM: SIPCC-SIP_REG_STATE: 1/51, sip_reg_sm_process_event: SIP_REG_STATE_REGISTERING <- E_SIP_REG_TMR_RETRY
8498: NOT 21:15:42.122298 JVM: SIPCC-SIP_REG_STATE: 1/51, sip_reg_sm_process_event: SIP_REG_STATE_REGISTERING <- E_SIP_REG_TMR_RETRY
And here is my XML settings file:
<?xml version="1.0" encoding="UTF-8"?>
<device>
<fullConfig>true</fullConfig>
<deviceProtocol>SIP</deviceProtocol>
<sshUserId>admin</sshUserId>
<sshPassword>cisco</sshPassword>
<devicePool>
<dateTimeSetting>
<dateTemplate>M/D/Ya</dateTemplate>
<timeZone>Pacific Standard/Daylight Time</timeZone>
<ntps>
<ntp>
<name>172.17.50.99</name>
<ntpMode>Unicast</ntpMode>
</ntp>
</ntps>
</dateTimeSetting>
<callManagerGroup>
<members>
<member priority="0">
<callManager>
<name>172.17.50.99</name>
<description>1800pbx</description>
<ports>
<ethernetPhonePort>2000</ethernetPhonePort>
<sipPort>5060</sipPort>
<securedSipPort>5061</securedSipPort>
</ports>
<processNodeName>172.17.50.99</processNodeName>
</callManager>
</member>
</members>
</callManagerGroup>
<connectionMonitorDuration>120</connectionMonitorDuration>
</devicePool>
<commonProfile>
<phonePassword></phonePassword>
<backgroundImageAccess>true</backgroundImageAccess>
<callLogBlfEnabled>2</callLogBlfEnabled>
</commonProfile>
<loadInformation></loadInformation>
<vendorConfig>
<disableSpeaker>false</disableSpeaker>
<disableSpeakerAndHeadset>false</disableSpeakerAndHeadset>
<pcPort>0</pcPort>
<settingsAccess>1</settingsAccess>
<garp>0</garp>
<voiceVlanAccess>0</voiceVlanAccess>
<videoCapability>1</videoCapability>
<autoSelectLineEnable>0</autoSelectLineEnable>
<webAccess>0</webAccess>
<spanToPCPort>1</spanToPCPort>
<loggingDisplay>1</loggingDisplay>
<loadServer></loadServer>
<daysDisplayNotActive></daysDisplayNotActive>
<displayOnTime>07:00</displayOnTime>
<displayOnDuration>17:00</displayOnDuration>
<displayIdleTimeout>1:00</displayIdleTimeout>
</vendorConfig>
<userLocale>
<name>English_United_States</name>
<uid>1</uid>
<langCode>en_US</langCode>
<version>1.0.0.0-1</version>
<winCharSet>iso-8859-1</winCharSet>
</userLocale>
<networkLocale>United_States</networkLocale>
<networkLocaleInfo>
<name>United_States</name>
<version>1.0.0.0-1</version>
</networkLocaleInfo>
<deviceSecurityMode>1</deviceSecurityMode>
<authenticationURL>http://172.17.50.99/cisco/services/authentication.php</authenticationURL>
<directoryURL>http://172.17.50.99/xmlservices/PhoneDirectory.php</directoryURL>
<idleURL>http://172.17.50.99/xmlservices/index.php</idleURL>
<informationURL>http://172.17.50.99/xmlservices/help.php</informationURL>
<messagesURL></messagesURL>
<proxyServerURL></proxyServerURL>
<servicesURL>http://172.17.50.99/xmlservices/index.php</servicesURL>
<dscpForSCCPPhoneConfig>96</dscpForSCCPPhoneConfig>
<dscpForSCCPPhoneServices>0</dscpForSCCPPhoneServices>
<dscpForCm2Dvce>96</dscpForCm2Dvce>
<transportLayerProtocol>2</transportLayerProtocol>
<dndCallAlert>5</dndCallAlert>
<capfAuthMode>0</capfAuthMode>
<capfList>
<capf>
<phonePort>3804</phonePort>
</capf>
</capfList>
<certHash></certHash>
<encrConfig>false</encrConfig>
<sipProfile>
<sipProxies>
<backupProxy></backupProxy>
<backupProxyPort>5060</backupProxyPort>
<emergencyProxy></emergencyProxy>
<emergencyProxyPort>5060</emergencyProxyPort>
<outboundProxy></outboundProxy>
<outboundProxyPort>5060</outboundProxyPort>
<registerWithProxy>true</registerWithProxy>
</sipProxies>
<sipCallFeatures>
<cnfJoinEnabled>true</cnfJoinEnabled>
<callForwardURI>x--serviceuri-cfwdall</callForwardURI>
<callPickupURI>x-cisco-serviceuri-pickup</callPickupURI>
<callPickupListURI>x-cisco-serviceuri-opickup</callPickupListURI>
<callPickupGroupURI>x-cisco-serviceuri-gpickup</callPickupGroupURI>
<meetMeServiceURI>x-cisco-serviceuri-meetme</meetMeServiceURI>
<abbreviatedDialURI>x-cisco-serviceuri-abbrdial</abbreviatedDialURI>
<rfc2543Hold>false</rfc2543Hold>
<callHoldRingback>2</callHoldRingback>
<localCfwdEnable>true</localCfwdEnable>
<semiAttendedTransfer>true</semiAttendedTransfer>
<anonymousCallBlock>2</anonymousCallBlock>
<callerIdBlocking>2</callerIdBlocking>
<dndControl>0</dndControl>
<remoteCcEnable>true</remoteCcEnable>
</sipCallFeatures>
<sipStack>
<sipInviteRetx>6</sipInviteRetx>
<sipRetx>10</sipRetx>
<timerInviteExpires>180</timerInviteExpires>
<timerRegisterExpires>3600</timerRegisterExpires>
<timerRegisterDelta>5</timerRegisterDelta>
<timerKeepAliveExpires>120</timerKeepAliveExpires>
<timerSubscribeExpires>120</timerSubscribeExpires>
<timerSubscribeDelta>5</timerSubscribeDelta>
<timerT1>500</timerT1>
<timerT2>4000</timerT2>
<maxRedirects>70</maxRedirects>
<remotePartyID>false</remotePartyID>
<userInfo>None</userInfo>
</sipStack>
<autoAnswerTimer>1</autoAnswerTimer>
<autoAnswerAltBehavior>false</autoAnswerAltBehavior>
<autoAnswerOverride>true</autoAnswerOverride>
<transferOnhookEnabled>false</transferOnhookEnabled>
<enableVad>false</enableVad>
<preferredCodec>none</preferredCodec>
<dtmfAvtPayload>101</dtmfAvtPayload>
<dtmfDbLevel>3</dtmfDbLevel>
<dtmfOutofBand>avt</dtmfOutofBand>
<alwaysUsePrimeLine>false</alwaysUsePrimeLine>
<alwaysUsePrimeLineVoiceMail>false</alwaysUsePrimeLineVoiceMail>
<kpml>3</kpml>
<natEnabled>false</natEnabled>
<natAddress></natAddress>
<stutterMsgWaiting>0</stutterMsgWaiting>
<callStats>false</callStats>
<silentPeriodBetweenCallWaitingBursts>10</silentPeriodBetweenCallWaitingBursts>
<disableLocalSpeedDialConfig>false</disableLocalSpeedDialConfig>
<startMediaPort>16384</startMediaPort>
<stopMediaPort>32766</stopMediaPort>
<voipControlPort>5060</voipControlPort>
<dscpForAudio>184</dscpForAudio>
<ringSettingBusyStationPolicy>0</ringSettingBusyStationPolicy>
<dialTemplate>dialplan.xml</dialTemplate>
<phoneLabel>Office</phoneLabel>
<sipLines>
<line button="1">
<featureID>9</featureID>
<featureLabel>1001</featureLabel>
<name>1001</name>
<displayName>1001</displayName>
<contact>1001</contact>
<proxy>USECALLMANAGER</proxy>
<port>5060</port>
<autoAnswer>
<autoAnswerEnabled>2</autoAnswerEnabled>
</autoAnswer>
<callWaiting>3</callWaiting>
<authName>1001</authName>
<authPassword>{8 character password}</authPassword>
<sharedLine>false</sharedLine>
<messageWaitingLampPolicy>1</messageWaitingLampPolicy>
<messagesNumber>*97</messagesNumber>
<ringSettingIdle>4</ringSettingIdle>
<ringSettingActive>5</ringSettingActive>
<forwardCallInfoDisplay>
<callerName>true</callerName>
<callerNumber>false</callerNumber>
<redirectedNumber>false</redirectedNumber>
<dialedNumber>true</dialedNumber>
</forwardCallInfoDisplay>
</line>
</sipLines>
</sipProfile>
</device>