Help for Cisco 7961


(cid254) #1

Hi,

I bought some 7961 in order to setup a pbx through FreePBX 15 and Asterisk 16.

I managed to upload SIP firmware (latest available: cmterm-7941_7961-sip.9-4-2SR3-1) through tftp and then I create the SEPmacaddress.cnf.XML that phone successfull obtain but I can’t make them register to Asterisk.
I already disabled NAT (set to never) and tried both chan_sip and pjsip with appropriate ports.
I managed to register softphones with same credentials so I have the feeling I’m missing something on the SEP config file but cant be 100% sure.

Google is full of information but being these phones on the market since ten years a lot of information are not up to date.

I’m sharing my SEP file where you can see two “lines”: one is chan_sip (port 5160) and the other pjsip (port 5060). Any help would be appreciated.

Thanks in advance

cid

<device>
<deviceProtocol>SIP</deviceProtocol>
<sshUserId>cisco</sshUserId>
<sshPassword>cisco</sshPassword>
<ipAddressMode>0</ipAddressMode>

<devicePool>
    <dateTimeSetting>
        <dateTemplate>D/M/Y</dateTemplate>
        <timeZone>Central Europe Standard/Daylight Time</timeZone>
        <ntps>
            <ntp>
                <name>it.pool.ntp.org</name>
                <ntpMode>Unicast</ntpMode>
            </ntp>
        </ntps>
    </dateTimeSetting>

    <callManagerGroup>
        <members>
            <member priority="0">
                <callManager>
                    <ports>
                        <ethernetPhonePort>2000</ethernetPhonePort>
                        <sipPort>5160</sipPort>
                    </ports>
                    <processNodeName>192.168.0.98</processNodeName>
                </callManager>
            </member>
        </members>
    </callManagerGroup>
</devicePool>

<sipProfile>
    <sipProxies>
        <registerWithProxy>true</registerWithProxy>
    </sipProxies>
    <sipCallFeatures>
        <cnfJoinEnabled>true</cnfJoinEnabled>
        <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>true</remotePartyID>
        <userInfo>None</userInfo>
    </sipStack>

    <autoAnswerTimer>1</autoAnswerTimer>
    <autoAnswerAltBehavior>false</autoAnswerAltBehavior>
    <autoAnswerOverride>true</autoAnswerOverride>
    <transferOnhookEnabled>false</transferOnhookEnabled>
    <enableVad>false</enableVad>
    <preferredCodec>g711ulaw</preferredCodec>
    <dtmfAvtPayload>101</dtmfAvtPayload>
    <dtmfDbLevel>3</dtmfDbLevel>
    <dtmfOutofBand>avt</dtmfOutofBand>
    <alwaysUsePrimeLine>false</alwaysUsePrimeLine>
    <alwaysUsePrimeLineVoiceMail>false</alwaysUsePrimeLineVoiceMail>
    <kpml>3</kpml>
    <natEnabled>false</natEnabled>
    <phoneLabel>Stefano</phoneLabel>
    <stutterMsgWaiting>0</stutterMsgWaiting>
    <callStats>false</callStats>
    <silentPeriodBetweenCallWaitingBursts>10</silentPeriodBetweenCallWaitingBursts>
    <disableLocalSpeedDialConfig>false</disableLocalSpeedDialConfig>
    <startMediaPort>10000</startMediaPort>
    <stopMediaPort>20000</stopMediaPort>

    <sipLines>
        <line button="1">
            <featureID>9</featureID>
            <featureLabel>12</featureLabel>
            <proxy>USECALLMANAGER</proxy>
            <port>5060</port>
            <name>Stefano</name>
            <displayName>Stefano</displayName>
            <autoAnswer>
                <autoAnswerEnabled>2</autoAnswerEnabled>
            </autoAnswer>
            <callWaiting>3</callWaiting>
            <authName>12</authName>
            <authPassword>12345678</authPassword>
            <sharedLine>false</sharedLine>
            <messageWaitingLampPolicy>1</messageWaitingLampPolicy>
            <messagesNumber>*98</messagesNumber>
            <ringSettingIdle>4</ringSettingIdle>
            <ringSettingActive>5</ringSettingActive>
            <contact>12</contact>
            <forwardCallInfoDisplay>
                <callerName>true</callerName>
                <callerNumber>true</callerNumber>
                <redirectedNumber>false</redirectedNumber>
                <dialedNumber>true</dialedNumber>
            </forwardCallInfoDisplay>
        </line>
        <line button="2">
            <featureID>9</featureID>
            <featureLabel>13</featureLabel>
            <proxy>USECALLMANAGER</proxy>
            <port>5160</port>
            <name>Stefano</name>
            <displayName>Stefano</displayName>
            <autoAnswer>
                <autoAnswerEnabled>2</autoAnswerEnabled>
            </autoAnswer>
            <callWaiting>3</callWaiting>
            <authName>13</authName>
            <authPassword>12345678</authPassword>
            <sharedLine>false</sharedLine>
            <messageWaitingLampPolicy>1</messageWaitingLampPolicy>
            <messagesNumber>*98</messagesNumber>
            <ringSettingIdle>4</ringSettingIdle>
            <ringSettingActive>5</ringSettingActive>
            <contact>13</contact>
            <forwardCallInfoDisplay>
                <callerName>true</callerName>
                <callerNumber>true</callerNumber>
                <redirectedNumber>false</redirectedNumber>
                <dialedNumber>true</dialedNumber>
            </forwardCallInfoDisplay>
        </line>

    </sipLines>

    <voipControlPort>5160</voipControlPort>
    <dscpForAudio>184</dscpForAudio>
    <ringSettingBusyStationPolicy>0</ringSettingBusyStationPolicy>
    <dialTemplate>dialplan.xml</dialTemplate>
</sipProfile>

<commonProfile>
    <phonePassword></phonePassword>
    <backgroundImageAccess>true</backgroundImageAccess>
    <callLogBlfEnabled>1</callLogBlfEnabled>
</commonProfile>
<vendorConfig>
    <disableSpeaker>false</disableSpeaker>
    <disableSpeakerAndHeadset>false</disableSpeakerAndHeadset>
    <pcPort>0</pcPort>
    <settingsAccess>1</settingsAccess>
    <garp>0</garp>
    <voiceVlanAccess>0</voiceVlanAccess>
    <videoCapability>0</videoCapability>
    <autoSelectLineEnable>0</autoSelectLineEnable>
    <webAccess>0</webAccess>
    <spanToPCPort>1</spanToPCPort>
    <loggingDisplay>1</loggingDisplay>
    <loadServer></loadServer>
    <sshAccess>0</sshAccess>
</vendorConfig>

<versionStamp>001</versionStamp>
<networkLocale>United_Kingdom</networkLocale>
<networkLocaleInfo>
    <name>United_Kingdom</name>
    <uid>64</uid>
    <version>1.0.0.0-4</version>
</networkLocaleInfo>

<deviceSecurityMode>1</deviceSecurityMode>
<authenticationURL></authenticationURL>
<servicesURL></servicesURL>
<transportLayerProtocol>2</transportLayerProtocol>
<certHash></certHash>
<encrConfig>false</encrConfig>
<dialToneSetting>2</dialToneSetting>

(cid254) #2

Forgot to mention: I’ve also tried to switch from UDP to TCP transport layer as suggested in some howto (anyway this firmware relase can support both encapsulation)


#3

Those phones require shorter passwords than the ones FreePBX generates. I don’t recall the limit. Start small and see what works.


(cid254) #4

@billsimon I used 8 character password which is 12345678 as you can see from SEP config file but still not registered :-\


#5

You can get log files from the phone (go to the phone’s IP with a browser).


(cid254) #6
|== Syslogd TNP== Mon Jan 16 09:50:42 2017
====================================================
3613: NOT 09:50:42.485666 SECD: loadTvsSrvrCfg: Phone ipv6 address is .
3614: NOT 09:50:42.486665 SECD: loadTvsSrvrCfg: enter while for the 11 time.
3615: NOT 09:50:42.487698 SECD: loadTvsSrvrCfg: Phone port is 0.
3616: NOT 09:50:42.488778 SECD: loadTvsSrvrCfg: enter while for the 12 time.
3617: NOT 09:50:42.489750 SECD: loadTvsSrvrCfg: Phone ipMode is 0.
3618: NOT 09:50:42.490841 SECD: loadTvsSrvrCfg: enter while for the 13 time.
3619: NOT 09:50:42.491973 SECD: loadTvsSrvrCfg: Phone ipPref is 0.
3620: NOT 09:50:42.492921 SECD: loadTvsSrvrCfg: enter while for the 14 time.
3621: NOT 09:50:42.493997 SECD: loadTvsSrvrCfg: Phone dscp value is 256.
3622: NOT 09:50:42.495006 SECD: loadTvsSrvrCfg: enter while for the 15 time.
3623: NOT 09:50:42.496075 SECD: loadTvsSrvrCfg: write the cfg for the 1 time.
3624: NOT 09:50:42.497082 SECD: loadTvsSrvrCfg: enter while for the 16 time.
3625: NOT 09:50:42.498082 SECD: loadTvsSrvrCfg: Phone ipv4 address is .
3626: NOT 09:50:42.499085 SECD: loadTvsSrvrCfg: enter while for the 17 time.
3627: NOT 09:50:42.500273 SECD: loadTvsSrvrCfg: Phone ipv6 address is .
3628: NOT 09:50:42.501229 SECD: loadTvsSrvrCfg: enter while for the 18 time.
3629: NOT 09:50:42.502263 SECD: loadTvsSrvrCfg: Phone port is 0.
3630: NOT 09:50:42.503337 SECD: loadTvsSrvrCfg: enter while for the 19 time.
3631: NOT 09:50:42.504324 SECD: loadTvsSrvrCfg: Phone ipMode is 0.
3632: NOT 09:50:42.505333 SECD: loadTvsSrvrCfg: enter while for the 20 time.
3633: NOT 09:50:42.506484 SECD: loadTvsSrvrCfg: Phone ipPref is 0.
3634: NOT 09:50:42.507490 SECD: loadTvsSrvrCfg: enter while for the 21 time.
3635: NOT 09:50:42.508496 SECD: loadTvsSrvrCfg: Phone dscp value is 256.
3636: NOT 09:50:42.509613 SECD: loadTvsSrvrCfg:  last write the cfg for the 2 time.
3637: NOT 09:50:42.543069 JVM: emccMode=0,localOverride=0, tftpAddr1=, tftpAddr2=,tftpAddr3=,tftpAddr4=
3638: NOT 09:50:42.547852 xxtpClient: tftp request rcv'd from /usr/tmp/tftp, emccMode =0, emccLocalOverride=0, tempTftp1= , tempTftp2 = , tempTftp3 = , tempTftp4 =  
3639: NOT 09:50:42.549752 JVM: setTempTftpAddress, emcc_mode=0,retEmccMode=0,LocalOverride=0,retLocalOverride=0, status=1
3640: WRN 09:50:42.851942 JVM: Startup Module Loader|cip.l10n.UserLocaleProperty:? - Unable to process LocaleProperty 'device.settings.config.localization.userlocale'
3641: NOT 09:50:42.906273 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.localization.userlocale.charset value=ASCII
3642: NOT 09:50:42.908858 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.localization.userlocale.charset value=ASCII
3643: NOT 09:50:42.911242 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.localization.userlocale.languagecode value=en
3644: NOT 09:50:42.913485 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.localization.userlocale.languagecode value=en
3645: WRN 09:50:42.915910 JVM: Startup Module Loader|cip.l10n.NetworkLocaleProperty:? - Unable to process LocaleProperty 'device.settings.config.localization.networklocale'
3646: WRN 09:50:42.996353 SECD: WARN:lookupCTL: ** no CTL, assume UCM NONSECURE
3647: ERR 09:50:43.446513 JVM: ..LOCALCONNECT FAILED
3648: NOT 09:50:43.553189 JVM: SIPCC-SIP_CTRL: sip_restart: In sip_restart
3649: NOT 09:50:43.569357 JVM: set_active_ccm: ccm=PRIMARY_CCM  port=0
3650: ERR 09:50:43.570437 JVM: SIP : sip_transport_setup_cc_conn : Admin has not configured a valid cucm for cucm index=SECONDARY_CCM=1.
3651: ERR 09:50:43.571451 JVM: SIP : sip_transport_setup_cc_conn : Admin has not configured a valid cucm for cucm index=TERTIARY_CCM=2.
3652: ERR 09:50:43.572491 JVM: SIP : sip_transport_setup_cc_conn : Admin has not configured a valid cucm for cucm index=SRST_CCM=3.
3653: ERR 09:50:43.573448 JVM: sip_regmgr_setup_cc_conns: NO VALID STANDBY CALL CONTROL AVAILABLE!
3654: NOT 09:50:43.574471 JVM: SIPCC-SIP_REG: sip_sm_init: Disabling mass reg state
3655: NOT 09:50:43.603124 JVM: SIPCC-SIP_REG: ccsip_register_all_lines: Disabling mass reg state
3656: NOT 09:50:43.604411 JVM: SIPCC-UI_API: 1/0, ui_set_sip_registration_state: 0
3657: NOT 09:50:43.606730 JVM: SIPCC-UI_API: 2/0, ui_set_sip_registration_state: 0
3658: NOT 09:50:43.610429 JVM: SIPCC-CC_API: 0/0, cc_int_feature: UI -> GSM: UPD_MEDIA_CAP       
3659: NOT 09:50:43.611686 JVM: SIPCC-SIP_CTRL: sip_restart: sip.taskInited is set to true 
3660: NOT 09:50:43.612725 JVM: SIPCC-CC_API: 0/0, cc_int_fail_fallback: SIP -> GSM: FAILOVER_FALLBACK   
3661: NOT 09:50:43.617870 JVM: SIPCC-DCSM: dcsm_process_event: DCSM 0   :(DCSM_READY:FEAT:UPD_MEDIA_CAP)
3662: NOT 09:50:43.621097 JVM: SIPCC-SIP_REG_STATE: 1/51, sip_reg_sm_process_event: SIP_REG_STATE_IDLE <- E_SIP_REG_REG_REQ
3663: NOT 09:50:43.624081 JVM: ccsip_messaging: sipSPIAddContactHeader: CFGID_DEVICE_NAME = SEP001EF7C27EFF
3664: NOT 09:50:43.628175 JVM: SIPCC-SIP_MSG_SEND: ccsip_dump_send_msg_info: <192.168.0.98:5160>:REGISTE: <sip:Stefano@192.168.0.98> :107 REGISTER::001ef7c2-7eff0008-8f155235-730a4577@192.168.0.118
3665: NOT 09:50:43.630758 JVM: SIPCC-SIP_REG_STATE: 2/52, sip_reg_sm_process_event: SIP_REG_STATE_IDLE <- E_SIP_REG_REG_REQ
3666: NOT 09:50:43.634054 JVM: ccsip_messaging: sipSPIAddContactHeader: CFGID_DEVICE_NAME = SEP001EF7C27EFF
3667: NOT 09:50:43.636490 JVM: SIPCC-SIP_MSG_SEND: ccsip_dump_send_msg_info: <192.168.0.98:5160>:REGISTE: <sip:Stefano@192.168.0.98> :107 REGISTER::001ef7c2-7eff0009-720cbd83-bbd05edd@192.168.0.118
3668: NOT 09:50:43.641082 JVM: SIPCC-SIP_MSG_RECV: ccsip_dump_recv_msg_info: <192.168.0.98:0   >:401 Una: <sip:Stefano@192.168.0.98>;tag=001ef7c27eff000b146ab569-f79f011b :107 REGISTER::001ef7c2-7eff0008-8f155235-730a4577@192.168.0.118
3669: NOT 09:50:43.645522 JVM: SIPCC-SIP_REG_STATE: 1/51, sip_reg_sm_process_event: SIP_REG_STATE_REGISTERING <- E_SIP_REG_4xx
3670: NOT 09:50:43.646626 JVM: SIPCC-SIP_REG: ccsip_handle_ev_4xx: Receive 4xx in ccm mode. set registration_reject to TRUE.
3671: NOT 09:50:43.649577 JVM: ccsip_messaging: sipSPIAddContactHeader: CFGID_DEVICE_NAME = SEP001EF7C27EFF
3672: NOT 09:50:43.653745 JVM: SIPCC-SIP_MSG_SEND: ccsip_dump_send_msg_info: <192.168.0.98:5160>:REGISTE: <sip:Stefano@192.168.0.98> :108 REGISTER::001ef7c2-7eff0008-8f155235-730a4577@192.168.0.118
3673: NOT 09:50:43.657978 JVM: SIPCC-SIP_MSG_RECV: ccsip_dump_recv_msg_info: <192.168.0.98:0   >:401 Una: <sip:Stefano@192.168.0.98>;tag=001ef7c27eff000c6fe8f4e7-c5fd9cd1 :107 REGISTER::001ef7c2-7eff0009-720cbd83-bbd05edd@192.168.0.118
3674: NOT 09:50:43.662935 JVM: SIPCC-SIP_REG_STATE: 2/52, sip_reg_sm_process_event: SIP_REG_STATE_REGISTERING <- E_SIP_REG_4xx
3675: NOT 09:50:43.664113 JVM: SIPCC-SIP_REG: ccsip_handle_ev_4xx: Receive 4xx in ccm mode. set registration_reject to TRUE.
3676: NOT 09:50:43.667208 JVM: ccsip_messaging: sipSPIAddContactHeader: CFGID_DEVICE_NAME = SEP001EF7C27EFF
3677: NOT 09:50:43.669673 JVM: SIPCC-SIP_MSG_SEND: ccsip_dump_send_msg_info: <192.168.0.98:5160>:REGISTE: <sip:Stefano@192.168.0.98> :108 REGISTER::001ef7c2-7eff0009-720cbd83-bbd05edd@192.168.0.118
3678: NOT 09:50:43.673848 JVM: SIPCC-SIP_MSG_RECV: ccsip_dump_recv_msg_info: <192.168.0.98:0   >:403 For: <sip:Stefano@192.168.0.98>;tag=001ef7c27eff000b146ab569-f79f011b :108 REGISTER::001ef7c2-7eff0008-8f155235-730a4577@192.168.0.118
3679: NOT 09:50:43.678543 JVM: SIPCC-SIP_REG_STATE: 1/51, sip_reg_sm_process_event: SIP_REG_STATE_REGISTERING <- E_SIP_REG_4xx
3680: NOT 09:50:43.679661 JVM: SIPCC-SIP_REG: ccsip_handle_ev_4xx: Receive 4xx in ccm mode. set registration_reject to TRUE.
3681: NOT 09:50:43.745604 JVM: sip_platform_udp_channel_destroy:closed UDP socket successfully
3682: NOT 09:50:43.747097 JVM: sip_platform_udp_channel_close:closed listening UDP socket
3683: NOT 09:50:43.748563 JVM: SIPCC-UI_API: 1/0, ui_set_sip_registration_state: 0
3684: NOT 09:50:43.751305 JVM: SIPCC-UI_API: 2/0, ui_set_sip_registration_state: 0
3685: NOT 09:50:43.754164 JVM: SIPCC-UI_API: 0/0, ui_set_notification: Registering: 151:20
3686: NOT 09:50:44.163893 JVM: SIPCC-SIP_REG_STATE: 2/52, sip_reg_sm_process_event: SIP_REG_STATE_REGISTERING <- E_SIP_REG_TMR_RETRY
3687: NOT 09:50:44.166171 JVM: sip_platform_udp_channel_destroy:closed UDP socket successfully
3688: NOT 09:50:44.234189 JVM: Startup Module Loader|cip.sipcc.SipCcAdapter:  - cmname=192.168.0.98 cmIp=192.168.0.98 port=5160 isValid=true
3689: ERR 09:50:44.236899 JVM: Startup Module Loader|cip.sipcc.SipCcAdapter:  - addSrstToCAgentList: isSrstSecure=false
3690: NOT 09:50:44.239054 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.callagent.messages.0 value=0
3691: NOT 09:50:44.241587 JVM: Startup Module Loader|cip.sipcc.d:  - initializeLinePlane(): Mgmt Interface is in Service now..
3692: WRN 09:50:44.243780 JVM: Startup Module Loader|DisplayTask:? -  Line 1 property added with dn Stefano
3693: ERR 09:50:44.245908 JVM: Startup Module Loader|cip.props.u:? - device.settings.energywise.keywords IO error.
3694: ERR 09:50:44.248065 JVM: Startup Module Loader|JniJSesssionApi:isThisFailureFromNewCause - old unregReason =24 newUnregReason=24 unregReason ignored
3695: WRN 09:50:44.251679 JVM: Startup Module Loader|cip.sipcc.SipEnhancedAlarmInfo:propertyChanged - name=device.status.lastresetcause2 value=523
3696: ERR 09:50:44.298380 JVM: tftpClient dialplan.xml /usr/cache/DP-404405517 550001 1
3697: NOT 09:50:44.303963 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - --->ConfigManager PropertyChanged: device.callagent.callcount
3698: NOT 09:50:44.306147 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - <---ConfigManager PropertyChanged: device.callagent.callcount
3699: NOT 09:50:44.308331 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - --->ConfigManager PropertyChanged: device.callagent.callcount
3700: NOT 09:50:44.312186 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - <---ConfigManager PropertyChanged: device.callagent.callcount
3701: NOT 09:50:44.314351 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.callagent.messages.0 value=0
3702: NOT 09:50:44.316019 xxtpClient: xxtp request rcv'd from /usr/tmp/tftp, srcFile = dialplan.xml, dstFile = /usr/cache/DP-404405517 max size = 550001 
3703: NOT 09:50:44.318717 ESP: server 2 = :: 
3704: NOT 09:50:44.335817 xxtpClient: auth server - tftpList[0] = ::ffff:192.168.0.98 
3705: NOT 09:50:44.336712 xxtpClient: look up server - 0 
3706: WRN 09:50:44.339707 SECD: WARN:lookupCTL: ** no CTL, assume TFTP NONSECURE
3707: NOT 09:50:44.343886 xxtpClient: secVal = 0xa 
3708: NOT 09:50:44.344908 xxtpClient: ::ffff:192.168.0.98 is a NONsecure server 
3709: NOT 09:50:44.345748 xxtpClient: temp retval = SRVR_NONSECURE, keep looking 
3710: NOT 09:50:44.346564 xxtpClient: retval = 10 
3711: NOT 09:50:44.347397 xxtpClient: Secure file requested 
3712: NOT 09:50:44.348258 xxtpClient: Non secure file approved  -- dialplan.xml  
3713: NOT 09:50:44.394154 HTTPCL: downdload will be limited to 537 KB
3714: ERR 09:50:44.406014 HTTPCL: connect() failed
3715: NOT 09:50:44.418686 SYSMSG: pid 4 (/sbin/httpcl) Normal Exit, status = 102
3716: INF 09:50:44.418755           runtime = 0.060 secs

3717: INF 09:50:44.418798          user cpu = 0.002044276 secs

3718: INF 09:50:44.418837        system cpu = 0.021392820 secs

3719: INF 09:50:44.418871    child user cpu = 0.000000000 secs

3720: INF 09:50:44.418903     child sys cpu = 0.000000000 secs

3721: INF 09:50:44.418949    sys interrupts = 0.002019602 secs for 35 interrupts

3722: INF 09:50:44.418997 total cpu = 0.023437096 secs ( 33% utilization )

3723: WRN 09:50:44.415163 xxtpClient: HTTP failed with code 102
3724: NOT 09:50:44.520794 TFTP: [7]:Requesting dialplan.xml from 192.168.0.98 with size limit of 550001
3725: NOT 09:50:44.532333 TFTP: [7]:Finished --> rcvd 88 bytes 
3726: NOT 09:50:44.655536 xxtpClient: request server6 1 ---> :: 
3727: NOT 09:50:44.657459 ESP: server 3 = :: 
3728: NOT 09:50:44.667968 ESP: send ADMIN, logging = 1, shell = 0, ipconfig = 1 
3729: NOT 09:50:44.688883 xxtpClient: request server6 0 ---> :: 
3730: NOT 09:50:44.719641 xxtpClient: request server6 1 ---> :: 
3731: ERR 09:50:44.729262 JVM: ..LOCALCONNECT FAILED
3732: ERR 09:50:44.736392 JVM: ..LOCALCONNECT FAILED
3733: ERR 09:50:44.744679 JVM: ..LOCALCONNECT FAILED
3734: ERR 09:50:44.751740 JVM: ..LOCALCONNECT FAILED
3735: ERR 09:50:44.757151 JVM: ..LOCALCONNECT FAILED
3736: ERR 09:50:44.762744 JVM: ..LOCALCONNECT FAILED
3737: ERR 09:50:44.768176 JVM: ..LOCALCONNECT FAILED
3738: NOT 09:50:44.787184 xxtpClient: request server 0 ---> 192.168.0.98 
3739: NOT 09:50:44.788878 ESP: server 0 = 192.168.0.98 
3740: WRN 09:50:44.798271 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3741: WRN 09:50:44.799744 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//ITLFile.tlv
3742: WRN 09:50:44.807490 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3743: WRN 09:50:44.808943 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//ITLFile.tlv
3744: WRN 09:50:44.818058 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3745: WRN 09:50:44.819496 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//ITLFile.tlv
3746: WRN 09:50:44.827216 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3747: WRN 09:50:44.828697 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//ITLFile.tlv
3748: NOT 09:50:44.848470 xxtpClient: request server 1 --->  
3749: NOT 09:50:44.849744 ESP: server 1 =  
3750: NOT 09:50:44.878995 xxtpClient: request server 0 ---> 192.168.0.98 
3751: NOT 09:50:44.901892 xxtpClient: request server6 0 ---> :: 
3752: NOT 09:50:44.903702 ESP: server 2 = :: 
3753: NOT 09:50:44.953905 xxtpClient: request server 1 --->  
3754: NOT 09:50:44.979083 xxtpClient: request server6 1 ---> :: 
3755: NOT 09:50:44.981159 ESP: server 3 = :: 
3756: NOT 09:50:45.015515 CDP-D: cdpGetPortCfg SPANTOPC CFG:11
3757: NOT 09:50:45.138184 xxtpClient: request server6 0 ---> :: 
3758: NOT 09:50:45.159437 xxtpClient: request server6 1 ---> :: 
3759: ERR 09:50:45.168668 JVM: ..LOCALCONNECT FAILED
3760: ERR 09:50:45.175264 JVM: ..LOCALCONNECT FAILED
3761: NOT 09:50:45.184178 JVM: SIPCC-SIP_REG_STATE: 2/52, sip_reg_sm_process_event: SIP_REG_STATE_REGISTERING <- E_SIP_REG_TMR_RETRY
3762: NOT 09:50:45.186470 JVM: sip_platform_udp_channel_destroy:closed UDP socket successfully
3763: ERR 09:50:45.188969 JVM: ..LOCALCONNECT FAILED
3764: ERR 09:50:45.195563 JVM: ..LOCALCONNECT FAILED
3765: ERR 09:50:45.200769 JVM: ..LOCALCONNECT FAILED
3766: ERR 09:50:45.205902 JVM: ..LOCALCONNECT FAILED
3767: ERR 09:50:45.212455 JVM: ..LOCALCONNECT FAILED
3768: NOT 09:50:47.183437 JVM: SIPCC-SIP_REG_STATE: 2/52, sip_reg_sm_process_event: SIP_REG_STATE_REGISTERING <- E_SIP_REG_TMR_RETRY
3769: NOT 09:50:47.185766 JVM: sip_platform_udp_channel_destroy:closed UDP socket successfully
3770: DBG 09:50:50.574265 cffs_gc(): runing gc 1
3771: NOT 09:50:51.183442 JVM: SIPCC-SIP_REG_STATE: 2/52, sip_reg_sm_process_event: SIP_REG_STATE_REGISTERING <- E_SIP_REG_TMR_RETRY
3772: NOT 09:50:51.185724 JVM: sip_platform_udp_channel_destroy:closed UDP socket successfully
3773: NOT 09:50:55.183471 JVM: SIPCC-SIP_REG_STATE: 2/52, sip_reg_sm_process_event: SIP_REG_STATE_REGISTERING <- E_SIP_REG_TMR_RETRY
3774: NOT 09:50:55.185788 JVM: sip_platform_udp_channel_destroy:closed UDP socket successfully
3775: NOT 09:50:59.183457 JVM: SIPCC-SIP_REG_STATE: 2/52, sip_reg_sm_process_event: SIP_REG_STATE_REGISTERING <- E_SIP_REG_TMR_RETRY
3776: NOT 09:50:59.185702 JVM: sip_platform_udp_channel_destroy:closed UDP socket successfully

#7

Do you see evidence of the registration attempt in your asterisk logs? It looks like the firewall / fail2ban is blocking.


(cid254) #8

@billsimon

[2020-06-18 15:41:20] NOTICE[935] chan_sip.c: Registration from ‘<sip:Stefano@192.168.0.98>’ failed for ‘192.168.0.118:50971’ - Wrong password

Password was 12345678, so 8 charactes long. I tried also 123456 and 1234 with no luck. As username I use the extension number.

fail2ban and firewall (ufw) are disabled on the machine


#9

It’s trying to register Stefano as the username.


#10

I think you want the extension number here.


(cid254) #11

Thanks for spotting this. I tried extension number as name and displayName without luck.

Then I realized there was something weird in ports. I put 5060 port for chan_sip in phone config (even it is written everywhere it is 5160) and now it is working :slight_smile:

Thanks again for your help @billsimon


(Franck Danard) #12

Also, sometimes it’s good to use chan_skinny or chan_sccpb to get BLF, custom menu…Etc.
If you feel good to try it, then do it.
For my experience, I started to use them on SIP protocol, but that was not really easy to update and get some sip firmware which worked.
Nativeliy with Cisco protocol, that works if they running on Skinny or chan_sccpb.
Next I started to use skinny channel, and next sccpb for a while.


(Dave Burgess) #13

@conduit110 and I were just discussing this in another thread. He’s done a lot of work with chan-skinny, and I’ve done a lot with chan-sccp. Of the three ways to interface Cisco phones (especially 79xx phones), the SIP load (when available) is the least desirable. They cripple the phone and make it harder to administer and use.


(system) closed #14

This topic was automatically closed 7 days after the last reply. New replies are no longer allowed.