09-13-2016 06:11 AM - edited 03-17-2019 08:05 AM
Dear all,
I try to deploy LSC certificate on Cisco phone trough CME, but on the phone I can see “LSC : Not installed”.
But the secure SCCP is working, but i just want to have LSC certificat eon my phone for EAP-TLS with LSC certificate.
Tested with CME v8.6 and v10 and the result is the same, no LSC on the phone :(
You can find the CME config bellow:
version 15.3
service timestamps debug datetime msec
service timestamps log datetime msec
no service password-encryption
!
hostname CME
!
boot-start-marker
boot system flash0:c2900-universalk9-mz.SPA.153-3.M7.bin
boot-end-marker
!
aqm-register-fnf
!
! card type command needed for slot/vwic-slot 0/0
!
no aaa new-model
!
!
!
!
!
!
!
ip domain name qar.local
ip cef
no ipv6 cef
!
multilink bundle-name authenticated
!
!
!
!
!
capf-server
auth-mode LSC
cert-enroll-trustpoint CAserver password 1 135445415F59527D737D
trustpoint-label CME
source-addr 10.57.37.130
!
ctl-client
server cme 10.57.37.130 trustpoint CME
server tftp 10.57.37.130 trustpoint CME
server capf 10.57.37.130 trustpoint CME
sast1 trustpoint CME
sast2 trustpoint sast2
!
!
crypto pki server CAserver
database level complete
no database archive
grant auto
!
crypto pki trustpoint CAserver
enrollment url http://10.57.37.130:80
revocation-check none
rsakeypair CAserver 1024 1024
!
crypto pki trustpoint CME
enrollment url http://10.57.37.130:80
revocation-check none
rsakeypair CME 1024 1024
auto-enroll regenerate
!
crypto pki trustpoint sast2
enrollment url http://10.57.37.130:80
revocation-check crl
rsakeypair sast2 1024 1024
!
!
crypto pki certificate chain CAserver
certificate ca 01
308201FF 30820168 A0030201 02020101 300D0609 2A864886 F70D0101 04050030
13311130 0F060355 04031308 43417365 72766572 301E170D 31363039 31323135
30313032 5A170D31 39303931 32313530 3130325A 30133111 300F0603 55040313
08434173 65727665 7230819F 300D0609 2A864886 F70D0101 01050003 818D0030
81890281 81008A4E AEB330F8 8811CD67 B702C9B1 C720BCA2 37A4906B FD725A7E
4ECB8547 E19BEDBB F6953B95 AB45DB0D 311F62E3 55BDB5D1 5855AB16 E0B8ACDD
C3F24FE3 CF3FAA54 FB8C4CAC C8164576 96283BC2 8BABE7FF 31FDC1B3 E7717459
92D236AB DA77BAC6 48A5CCBC CCC755E0 A2EB89B7 D35D43AC 28133ACC 8396169A
CBD0212E 37DD0203 010001A3 63306130 0F060355 1D130101 FF040530 030101FF
300E0603 551D0F01 01FF0404 03020186 301F0603 551D2304 18301680 14ADF2F4
E7F0DDA5 AC51C064 D915B752 447B0B8E D7301D06 03551D0E 04160414 ADF2F4E7
F0DDA5AC 51C064D9 15B75244 7B0B8ED7 300D0609 2A864886 F70D0101 04050003
8181007F 8703C9C4 9AE1B279 B5320B33 CBB5A1BD 274F949B 3BA0FB03 ECBBA82C
28633555 8C86FC2C 97F75056 8CE96CA7 1D3DBC88 1ABA0B91 0E0E9EA5 68C38903
1C13FD75 2CC4D150 042DB86A B3BDF394 D222B3A0 958EE9D4 7C907F59 6152E93C
1C9FF878 4DA6E997 021B9273 03B84DC0 DC8EE44C B66B4D51 F42B3087 44736D65 373F30
quit
crypto pki certificate chain CME
certificate 02
308201F6 3082015F A0030201 02020102 300D0609 2A864886 F70D0101 05050030
13311130 0F060355 04031308 43417365 72766572 301E170D 31363039 31323135
30323136 5A170D31 37303931 32313530 3231365A 301E311C 301A0609 2A864886
F70D0109 02160D43 4D452E71 61722E6C 6F63616C 30819F30 0D06092A 864886F7
0D010101 05000381 8D003081 89028181 009496B7 655A222F 784C2205 ABB782D2
BC84B8C6 D88D05A0 3856B99C 897B9EE9 602BE107 F2729A29 7B8D2BB7 8F0FEA2F
19D55B2C 3D83D994 8FF9981C 7CF44C72 26C7D23B DE82CD83 6862672E 8BF867B4
347CDC99 0544D556 C3E34222 004CE762 595EEFAA 456F874A 16C51590 2942D11E
2742F34D A59C9947 88FBD7A3 8495CE91 95020301 0001A34F 304D300B 0603551D
0F040403 0205A030 1F060355 1D230418 30168014 ADF2F4E7 F0DDA5AC 51C064D9
15B75244 7B0B8ED7 301D0603 551D0E04 160414F1 1B2C9A1E 1713F3C8 295CCD5C
F59D78AC 3F5B0230 0D06092A 864886F7 0D010105 05000381 81008701 462DCBFC
EDEFA053 BFC94C21 347AAAA5 B09D2C43 1B006FFA 16AE7A21 95A0EB0D 3F412755
02269524 F85F08D3 5BD53EED BE615830 2708EE7F 61997872 89AE026B 2869EFCC
ADC92BDC 4E33C821 89D3F3C3 8B92293F D31F4C9E CD5DA218 4A8B062A 05C385C1
B72FB055 5748287A 4FCB7174 FCD1D1D0 C099559A 5C163D69 7041
quit
certificate ca 01
308201FF 30820168 A0030201 02020101 300D0609 2A864886 F70D0101 04050030
13311130 0F060355 04031308 43417365 72766572 301E170D 31363039 31323135
30313032 5A170D31 39303931 32313530 3130325A 30133111 300F0603 55040313
08434173 65727665 7230819F 300D0609 2A864886 F70D0101 01050003 818D0030
81890281 81008A4E AEB330F8 8811CD67 B702C9B1 C720BCA2 37A4906B FD725A7E
4ECB8547 E19BEDBB F6953B95 AB45DB0D 311F62E3 55BDB5D1 5855AB16 E0B8ACDD
C3F24FE3 CF3FAA54 FB8C4CAC C8164576 96283BC2 8BABE7FF 31FDC1B3 E7717459
92D236AB DA77BAC6 48A5CCBC CCC755E0 A2EB89B7 D35D43AC 28133ACC 8396169A
CBD0212E 37DD0203 010001A3 63306130 0F060355 1D130101 FF040530 030101FF
300E0603 551D0F01 01FF0404 03020186 301F0603 551D2304 18301680 14ADF2F4
E7F0DDA5 AC51C064 D915B752 447B0B8E D7301D06 03551D0E 04160414 ADF2F4E7
F0DDA5AC 51C064D9 15B75244 7B0B8ED7 300D0609 2A864886 F70D0101 04050003
8181007F 8703C9C4 9AE1B279 B5320B33 CBB5A1BD 274F949B 3BA0FB03 ECBBA82C
28633555 8C86FC2C 97F75056 8CE96CA7 1D3DBC88 1ABA0B91 0E0E9EA5 68C38903
1C13FD75 2CC4D150 042DB86A B3BDF394 D222B3A0 958EE9D4 7C907F59 6152E93C
1C9FF878 4DA6E997 021B9273 03B84DC0 DC8EE44C B66B4D51 F42B3087 44736D65 373F30
quit
crypto pki certificate chain sast2
certificate 03
308201F6 3082015F A0030201 02020103 300D0609 2A864886 F70D0101 05050030
13311130 0F060355 04031308 43417365 72766572 301E170D 31363039 31323135
30323435 5A170D31 37303931 32313530 3234355A 301E311C 301A0609 2A864886
F70D0109 02160D43 4D452E71 61722E6C 6F63616C 30819F30 0D06092A 864886F7
0D010101 05000381 8D003081 89028181 00A27607 C677F703 FAD3FFEB 1FA6FB59
F0AF83F4 106B77F5 B9359DD6 9DAF9B7C 2B067F98 BB2DDD22 D9C57937 0725B464
09ACB29D 7968D99C 46A5E820 D69EFD06 BBF11EBE 10D898AD BB3F05F4 3C0AE5AE
110E86DE 62755CB5 392E095C 0A1D1FED D496E1D5 06CD71D0 A8C4713A DF2BC217
069FCAA2 619C744D 4231F541 B6A22095 F1020301 0001A34F 304D300B 0603551D
0F040403 0205A030 1F060355 1D230418 30168014 ADF2F4E7 F0DDA5AC 51C064D9
15B75244 7B0B8ED7 301D0603 551D0E04 160414B6 CA2539E7 12167BCA 0DEFDB89
945C246F 85D89330 0D06092A 864886F7 0D010105 05000381 810007C3 89B9C2E6
00DBE558 7CB6A53D 0211F656 DEEBFD47 B5BEA4BD AD6514D5 C417FEC2 B276B842
ED2B5A21 211B09C1 8632F8BD 2B4AB7F2 D9E71A66 F02E1552 B7BFC888 E4C10793
6E4C7958 205A8DDE 39A84344 09C07C31 63311C25 E0C42B33 A779B273 47EAEA5D
40675254 D6886573 971C73EA 6787FD33 F378169B 62FE6EFA F389
quit
certificate ca 01
308201FF 30820168 A0030201 02020101 300D0609 2A864886 F70D0101 04050030
13311130 0F060355 04031308 43417365 72766572 301E170D 31363039 31323135
30313032 5A170D31 39303931 32313530 3130325A 30133111 300F0603 55040313
08434173 65727665 7230819F 300D0609 2A864886 F70D0101 01050003 818D0030
81890281 81008A4E AEB330F8 8811CD67 B702C9B1 C720BCA2 37A4906B FD725A7E
4ECB8547 E19BEDBB F6953B95 AB45DB0D 311F62E3 55BDB5D1 5855AB16 E0B8ACDD
C3F24FE3 CF3FAA54 FB8C4CAC C8164576 96283BC2 8BABE7FF 31FDC1B3 E7717459
92D236AB DA77BAC6 48A5CCBC CCC755E0 A2EB89B7 D35D43AC 28133ACC 8396169A
CBD0212E 37DD0203 010001A3 63306130 0F060355 1D130101 FF040530 030101FF
300E0603 551D0F01 01FF0404 03020186 301F0603 551D2304 18301680 14ADF2F4
E7F0DDA5 AC51C064 D915B752 447B0B8E D7301D06 03551D0E 04160414 ADF2F4E7
F0DDA5AC 51C064D9 15B75244 7B0B8ED7 300D0609 2A864886 F70D0101 04050003
8181007F 8703C9C4 9AE1B279 B5320B33 CBB5A1BD 274F949B 3BA0FB03 ECBBA82C
28633555 8C86FC2C 97F75056 8CE96CA7 1D3DBC88 1ABA0B91 0E0E9EA5 68C38903
1C13FD75 2CC4D150 042DB86A B3BDF394 D222B3A0 958EE9D4 7C907F59 6152E93C
1C9FF878 4DA6E997 021B9273 03B84DC0 DC8EE44C B66B4D51 F42B3087 44736D65 373F30
quit
voice-card 0
!
!
!
voice service voip
ip address trusted list
ipv4 10.57.37.130
qsig decode
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
supplementary-service h450.12
fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback none
!
!
!
!
!
!
license udi pid CISCO2921/K9 sn XXXXXXXX
license boot module c2900 technology-package securityk9
hw-module pvdm 0/0
!
!
!
vtp mode transparent
username admin privilege 15 password 0 T0ta1$
!
redundancy
!
!
!
!
!
ip ssh logging events
ip ssh version 2
!
!
!
!
!
!
!
!
!
interface Embedded-Service-Engine0/0
no ip address
shutdown
!
interface GigabitEthernet0/0
ip address 10.57.37.130 255.255.255.224
duplex auto
speed auto
!
interface GigabitEthernet0/1
no ip address
shutdown
duplex auto
speed auto
!
interface GigabitEthernet0/2
no ip address
shutdown
duplex auto
speed auto
!
ip default-gateway 10.xxx.xxx.xxx
ip forward-protocol nd
!
ip http server
no ip http secure-server
!
ip route 0.0.0.0 0.0.0.0 10.xxx.xxx.xxx
!
!
!
tftp-server flash:/IP_Phones/7942-62/apps42.9-4-2ES22.sbn alias apps42.9-4-2ES22.sbn
tftp-server flash:/IP_Phones/7942-62/cnu42.9-4-2ES22.sbn alias cnu42.9-4-2ES22.sbn
tftp-server flash:/IP_Phones/7942-62/cvm42sccp.9-4-2ES22.sbn alias cvm42sccp.9-4-2ES22.sbn
tftp-server flash:/IP_Phones/7942-62/dsp42.9-4-2ES22.sbn alias dsp42.9-4-2ES22.sbn
tftp-server flash:/IP_Phones/7942-62/jar42sccp.9-4-2ES22.sbn alias jar42sccp.9-4-2ES22.sbn
tftp-server flash:/IP_Phones/7942-62/SCCP42.9-4-2SR2-2S.loads alias SCCP42.9-4-2SR2-2S.loads
tftp-server flash:/IP_Phones/7942-62/term42.default.loads alias term42.default.loads
tftp-server flash:/IP_Phones/7942-62/term62.default.loads alias term62.default.loads
tftp-server flash:/IP_Phones/7945-65/apps45.9-4-2ES22.sbn alias apps45.9-4-2ES22.sbn
tftp-server flash:/IP_Phones/7945-65/cnu45.9-4-2ES22.sbn alias cnu45.9-4-2ES22.sbn
tftp-server flash:/IP_Phones/7945-65/cvm45sccp.9-4-2ES22.sbn alias cvm45sccp.9-4-2ES22.sbn
tftp-server flash:/IP_Phones/7945-65/dsp45.9-4-2ES22.sbn alias dsp45.9-4-2ES22.sbn
tftp-server flash:/IP_Phones/7945-65/jar45sccp.9-4-2ES22.sbn alias jar45sccp.9-4-2ES22.sbn
tftp-server flash:/IP_Phones/7945-65/SCCP45.9-4-2SR2-2S.loads alias SCCP45.9-4-2SR2-2S.loads
tftp-server flash:/IP_Phones/7945-65/term45.default.loads alias term45.default.loads
tftp-server flash:/IP_Phones/7945-65/term65.default.loads alias term65.default.loads
!
control-plane
!
!
voice-port 0/1/0
!
voice-port 0/1/1
!
voice-port 0/1/2
!
voice-port 0/1/3
!
!
!
!
!
!
mgcp behavior rsip-range tgcp-only
mgcp behavior comedia-role none
mgcp behavior comedia-check-media-src disable
mgcp behavior comedia-sdp-force disable
!
mgcp profile default
!
!
!
!
!
gatekeeper
shutdown
!
!
telephony-service
secure-signaling trustpoint CME
tftp-server-credentials trustpoint CME
server-security-mode secure
device-security-mode encrypted
ssh userid cisco password cisco
no auto-reg-ephone
max-ephones 20
max-dn 10
ip source-address 10.57.37.130 port 2000
service phone daysBacklightNotActive 1,2,3,4,5,6,7
service phone backlightOnTime 07:30
service phone backlightOnDuration 00:01
service phone backlightIdleTimeout 00:01
service phone daysDisplayNotActive 1,2,3,4,5,6,7
service phone settingsAccess 1
service phone displayOnWhenIncomingCall 1
service phone webAccess 1
service phone spanToPCPort 0
service phone sshAccess 1
system message TEST
url services http://10.57.37.130/telephony_service.html
url authentication http://10.57.37.130/CCMCIP/authenticate.asp
cnf-file location flash:
cnf-file perphone
load 7942 SCCP42.9-4-2SR2-2S
load 7945 SCCP45.9-4-2SR2-2S
load 7962 SCCP42.9-4-2SR2-2S
load 7965 SCCP45.9-4-2SR2-2S
time-zone 23
time-format 24
date-format dd-mm-yy
max-conferences 8 gain -6
multicast moh 239.1.1.1 port 2000 route 10.57.37.130
transfer-system full-consult
create cnf-files version-stamp Jan 01 2002 00:00:00
!
!
!
ephone-dn 2 dual-line
number 456
label 456
name 456
!
!
ephone-dn 3 dual-line
number 789
label 789
name 789
!
!
!
!
ephone 2
device-security-mode encrypted
cert-oper upgrade auth-mode LSC
mac-address 0021.5502.E6B7
type 7965
button 1:2
!
!
!
ephone 4
device-security-mode encrypted
cert-oper upgrade auth-mode LSC
mac-address 0025.84A2.6BFF
type 7942
button 1:3
!
!
!
!
line con 0
line aux 0
line 2
no activation-character
no exec
transport preferred none
transport output pad telnet rlogin lapb-ta mop udptn v120 ssh
stopbits 1
line vty 0 4
login local
transport input ssh
line vty 5 15
login local
transport input ssh
!
scheduler allocate 20000 1000
ntp server 10.10.10.10 prefer
!
end
Debugs :
debug capf-server all
debug tftp events
debug ephone register
debug ephone detail
debug crypto pki server
debug crypto pki messages
debug crypto pki transactions
debug crypto pki validation
debug crypto pki api
debug ssl openssl errors
debug ssl openssl msg
debug ssl openssl states
You can find the result of the debug bellow:
Sep 13 12:03:36.245: CRYPTO_PKI: Initializing renewal timers
Sep 13 12:03:36.245: PKI:get_cert CME 0x10 (expired=0):
Sep 13 12:03:36.245: PKI:get_cert sast2 0x10 (expired=0):
Sep 13 12:04:02.105: ephone-3[2/2][SEP001D4595600A]:SkinnyCheckPendingCallBackPhone scan 1 lines
Sep 13 12:04:02.105: ephone-4[3/1][SEP002584A26BFF]:SkinnyCheckPendingCallBackPhone scan 2 lines
Sep 13 12:04:03.865: TFTP: Looking for CTLSEP00215502E6B7.tlv
Sep 13 12:04:03.869: TFTP: Opened flash:/CTLFile.tlv, fd 14, size 3837 for process 255
Sep 13 12:04:03.877: TFTP: Finished flash:/CTLFile.tlv, time 00:00:00 for process 255
Sep 13 12:04:04.029: TFTP: Looking for ITLSEP00215502E6B7.tlv
Sep 13 12:04:04.149: TFTP: Looking for ITLFile.tlv
Sep 13 12:04:04.637: TFTP: Looking for SEP00215502E6B7.cnf.xml.sgn
Sep 13 12:04:04.641: TFTP: Opened system:/its/vrf1/SEP00215502E6B7.cnf.xml.sgn, fd 14, size 2194 for process 255
Sep 13 12:04:04.645: TFTP: Finished system:/its/vrf1/SEP00215502E6B7.cnf.xml.sgn, time 00:00:00 for process 255
Sep 13 12:04:08.577: TFTP: Looking for English_United_States/be-sccp.jar.sgn
Sep 13 12:04:09.029: TFTP: Looking for United_States/g3-tones.xml.sgn
Sep 13 12:04:10.289: New Skinny socket accepted [2] from 1, sub 1 (2 active)
Sep 13 12:04:10.289: sin_family 2, sin_port 50708, in_addr 10.192.255.163
Sep 13 12:04:10.289: add_skinny_secure_socket: signal tunnel reserved for 2 10.192.255.163 50708
Sep 13 12:04:10.289: add_skinny_secure_socket: pid =255, new_sock=0, ip address = 10.192.255.163
Sep 13 12:04:10.289: skinny_secure_handshake: pid =255, sock=0, args->pid=255, ip address = 10.192.255.163
Sep 13 12:04:10.289: opssl_SetPKIInfo entry
Sep 13 12:04:10.289: CRYPTO_PKI: (A0036) Session started - identity selected (CME)
Sep 13 12:04:10.289: CRYPTO_OPSSL: Got router SIGNATURE private key
Sep 13 12:04:10.289: No need to add ROOT cert.
Sep 13 12:04:10.289: CRYPTO_PKI: Rcvd request to end PKI session A0036.
Sep 13 12:04:10.289: CRYPTO_PKI: PKI session A0036 has ended. Freeing all resources.
Sep 13 12:04:10.289: CRYPTO_PKI: unlocked trustpoint CME, refcount is 0
Sep 13 12:04:10.289: opssl_SetPKIInfo done.
Sep 13 12:04:10.289: Start TLS Handshake 0 10.192.255.163 50708
Sep 13 12:04:10.289: Handshake start: before/accept initialization
Sep 13 12:04:10.289: SSL_accept:before/accept initialization
==========================
Sep 13 12:04:10.289: SSL_accept:would block on read in SSLv3 read client hello B
Sep 13 12:04:10.289: TLS Handshake retcode OPSSLReadWouldBlockErr
Sep 13 12:04:11.289: <<< TLS 1.0 Handshake [length 002F], ClientHello
Sep 13 12:04:11.289: 01 00 00 2B 03 01 57 51 B6 5D 3A 93 31 88 33 E9
Sep 13 12:04:11.289: AF CA 87 1C 67 85 BA C1 3D 98 A3 C0 57 BC A4 0C
Sep 13 12:04:11.289: 7C 74 86 55 1F BA 00 00 04 00 35 00 2F 01 00
Sep 13 12:04:11.289:
==========================
Sep 13 12:04:11.289: SSL_accept:SSLv3 read client hello B
Sep 13 12:04:11.289: >>> TLS 1.0 Handshake [length 004A], ServerHello
Sep 13 12:04:11.289: 02 00 00 46 03 01 57 D7 EB 3B F7 6E 35 F7 9F 7C
Sep 13 12:04:11.289: A2 E6 C0 98 D8 76 4C 96 12 7C 47 EE EF FB 7B 3E
Sep 13 12:04:11.289: B8 F5 79 4A E0 74 20 9A E3 B4 46 EB 3E 64 3F B5
Sep 13 12:04:11.289: 22 0E A4 D7 84 B3 80 5A 61 B2 24 CD 39 3A 86 11
Sep 13 12:04:11.289: 2B 0A 14 0E 35 5F 23 00 2F 00
Sep 13 12:04:11.289:
==========================
Sep 13 12:04:11.289: SSL_accept:SSLv3 write server hello A
Sep 13 12:04:11.289: >>> TLS 1.0 Handshake [length 0204], Certificate
Sep 13 12:04:11.289: 0B 00 02 00 00 01 FD 00 01 FA 30 82 01 F6 30 82
Sep 13 12:04:11.289: 01 5F A0 03 02 01 02 02 01 02 30 0D 06 09 2A 86
Sep 13 12:04:11.289: 48 86 F7 0D 01 01 05 05 00 30 13 31 11 30 0F 06
Sep 13 12:04:11.289: 03 55 04 03 13 08 43 41 73 65 72 76 65 72 30 1E
Sep 13 12:04:11.289: 17 0D 31 36 30 39 31 32 31 35 30 32 31 36 5A 17
Sep 13 12:04:11.289: 0D 31 37 30 39 31 32 31 35 30 32 31 36 5A 30 1E
Sep 13 12:04:11.289: 31 1C 30 1A 06 09 2A 86 48 86 F7 0D 01 09 02 16
Sep 13 12:04:11.289: 0D 43 4D 45 2E 71 61 72 2E 6C 6F 63 61 6C 30 81
Sep 13 12:04:11.289: 9F 30 0D 06 09 2A 86 48 86 F7 0D 01 01 01 05 00
Sep 13 12:04:11.289: 03 81 8D 00 30 81 89 02 81 81 00 94 96 B7 65 5A
Sep 13 12:04:11.289: 22 2F 78 4C 22 05 AB B7 82 D2 BC 84 B8 C6 D8 8D
Sep 13 12:04:11.289: 05 A0 38 56 B9 9C 89 7B 9E E9 60 2B E1 07 F2 72
Sep 13 12:04:11.289: 9A 29 7B 8D 2B B7 8F 0F EA 2F 19 D5 5B 2C 3D 83
Sep 13 12:04:11.289: D9 94 8F F9 98 1C 7C F4 4C 72 26 C7 D2 3B DE 82
Sep 13 12:04:11.289: CD 83 68 62 67 2E 8B F8 67 B4 34 7C DC 99 05 44
Sep 13 12:04:11.289: D5 56 C3 E3 42 22 00 4C E7 62 59 5E EF AA 45 6F
Sep 13 12:04:11.289: 87 4A 16 C5 15 90 29 42 D1 1E 27 42 F3 4D A5 9C
Sep 13 12:04:11.289: 99 47 88 FB D7 A3 84 95 CE 91 95 02 03 01 00 01
Sep 13 12:04:11.289: A3 4F 30 4D 30 0B 06 03 55 1D 0F 04 04 03 02 05
Sep 13 12:04:11.289: A0 30 1F 06 03 55 1D 23 04 18 30 16 80 14 AD F2
Sep 13 12:04:11.289: F4 E7 F0 DD A5 AC 51 C0 64 D9 15 B7 52 44 7B 0B
Sep 13 12:04:11.289: 8E D7 30 1D 06 03 55 1D 0E 04 16 04 14 F1 1B 2C
Sep 13 12:04:11.289: 9A 1E 17 13 F3 C8 29 5C CD 5C F5 9D 78 AC 3F 5B
Sep 13 12:04:11.289: 02 30 0D 06 09 2A 86 48 86 F7 0D 01 01 05 05 00
Sep 13 12:04:11.293: 03 81 81 00 87 01 46 2D CB FC ED EF A0 53 BF C9
Sep 13 12:04:11.293: 4C 21 34 7A AA A5 B0 9D 2C 43 1B 00 6F FA 16 AE
Sep 13 12:04:11.293: 7A 21 95 A0 EB 0D 3F 41 27 55 02 26 95 24 F8 5F
Sep 13 12:04:11.293: 08 D3 5B D5 3E ED BE 61 58 30 27 08 EE 7F 61 99
Sep 13 12:04:11.293: 78 72 89 AE 02 6B 28 69 EF CC AD C9 2B DC 4E 33
Sep 13 12:04:11.293: C8 21 89 D3 F3 C3 8B 92 29 3F D3 1F 4C 9E CD 5D
Sep 13 12:04:11.293: A2 18 4A 8B 06 2A 05 C3 85 C1 B7 2F B0 55 57 48
Sep 13 12:04:11.293: 28 7A 4F CB 71 74 FC D1 D1 D0 C0 99 55 9A 5C 16
Sep 13 12:04:11.293: 3D 69 70 41
Sep 13 12:04:11.293:
==========================
Sep 13 12:04:11.293: SSL_accept:SSLv3 write certificate A
Sep 13 12:04:11.293: >>> TLS 1.0 Handshake [length 000C], CertificateRequest
Sep 13 12:04:11.293: 0D 00 00 04 01 01 00 00 0E 00 00 00
Sep 13 12:04:11.293:
Sep 13 12:04:11.293: SSL_accept:SSLv3 write certificate request A
Sep 13 12:04:11.293: SSL_accept:would block on write in SSLv3 flush data
==========================
Sep 13 12:04:11.293: TLS Handshake retcode OPSSLReadWouldBlockErr
Sep 13 12:04:12.293: SSL_accept:SSLv3 flush data
Sep 13 12:04:12.293: SSL_accept:would block on read in SSLv3 read client certificate A
Sep 13 12:04:12.293: TLS Handshake retcode OPSSLReadWouldBlockErr
Sep 13 12:04:13.293: SSL_accept:would block on read in SSLv3 read client certificate A
Sep 13 12:04:13.293: TLS Handshake retcode OPSSLReadWouldBlockErr
Sep 13 12:04:14.293: <<< TLS 1.0 Handshake [length 0487], Certificate
Sep 13 12:04:14.293: 0B 00 04 83 00 04 80 00 04 7D 30 82 04 79 30 82
Sep 13 12:04:14.293: 03 61 A0 03 02 01 02 02 0A 77 1E C4 95 00 00 00
Sep 13 12:04:14.293: 11 CD DE 30 0D 06 09 2A 86 48 86 F7 0D 01 01 05
Sep 13 12:04:14.293: 05 00 30 39 31 16 30 14 06 03 55 04 0A 13 0D 43
Sep 13 12:04:14.293: 69 73 63 6F 20 53 79 73 74 65 6D 73 31 1F 30 1D
Sep 13 12:04:14.293: 06 03 55 04 03 13 16 43 69 73 63 6F 20 4D 61 6E
Sep 13 12:04:14.293: 75 66 61 63 74 75 72 69 6E 67 20 43 41 30 1E 17
Sep 13 12:04:14.293: 0D 30 38 30 34 30 38 30 34 32 38 35 30 5A 17 0D
Sep 13 12:04:14.293: 31 38 30 34 30 38 30 34 33 38 35 30 5A 30 50 31
Sep 13 12:04:14.293: 1B 30 19 06 03 55 04 0A 13 12 43 69 73 63 6F 20
Sep 13 12:04:14.293: 53 79 73 74 65 6D 73 20 49 6E 63 2E 31 0E 30 0C
Sep 13 12:04:14.293: 06 03 55 04 0B 13 05 45 56 56 42 55 31 21 30 1F
Sep 13 12:04:14.293: 06 03 55 04 03 13 18 43 50 2D 37 39 36 35 47 2D
Sep 13 12:04:14.293: 53 45 50 30 30 32 31 35 35 30 32 45 36 42 37 30
Sep 13 12:04:14.293: 82 01 22 30 0D 06 09 2A 86 48 86 F7 0D 01 01 01
Sep 13 12:04:14.293: 05 00 03 82 01 0F 00 30 82 01 0A 02 82 01 01 00
Sep 13 12:04:14.293: B7 85 EF E3 EB 12 C4 92 39 F3 F5 5B 2A 1C DE B8
Sep 13 12:04:14.293: 50 BC AD E8 84 53 CB 31 5D 74 8F 05 1B B8 90 8F
Sep 13 12:04:14.293: C8 8F 47 D3 5E 6E CC 00 FE A6 B6 CF 67 4A FA EC
Sep 13 12:04:14.293: 28 B6 CC 51 FA 70 45 3F 66 56 DC DC FD 6B C4 3D
Sep 13 12:04:14.293: F8 0A ED D1 65 AC 4E D0 13 5C A3 2A AB D5 04 E7
Sep 13 12:04:14.293: 5A 62 2E DD 9A 9A A7 9B 41 55 FF 5E C6 CD 96 2F
Sep 13 12:04:14.293: 0C DC CD 90 B2 EE 8C F0 F8 3D 18 4C 12 66 30 71
Sep 13 12:04:14.293: 2C D3 B2 BB E0 40 13 E2 85 7A 28 82 AA 22 D9 6C
Sep 13 12:04:14.293: 91 81 7B D1 B8 42 19 9B E3 17 B5 B8 44 A4 AB C2
Sep 13 12:04:14.293: D2 A3 C2 68 50 A8 13 47 FE 65 EE 6D 25 1A 51 58
Sep 13 12:04:14.293: 70 44 34 FC D0 DD 9F 01 64 7D 1D 7B 6B 34 DA 69
Sep 13 12:04:14.293: 14 5A 3F 4B D3 6E 9D 62 95 7B 52 1A 3E 14 7E E1
Sep 13 12:04:14.293: 0A 33 61 DD 7C 2D CC 67 25 CF 99 77 5B 98 2C 9F
Sep 13 12:04:14.293: 66 35 14 C4 39 27 02 7C 50 5A E3 5A 27 DC 7F 74
Sep 13 12:04:14.293: 06 52 66 2B 6F 35 17 8D 13 F2 33 90 D5 5A F2 40
Sep 13 12:04:14.293: 54 74 50 70 E5 C8 D9 15 72 0F DF E1 9B FD 4D EB
Sep 13 12:04:14.297: 02 03 01 00 01 A3 82 01 6A 30 82 01 66 30 0B 06
Sep 13 12:04:14.297: 03 55 1D 0F 04 04 03 02 04 F0 30 2A 06 03 55 1D
Sep 13 12:04:14.297: 25 01 01 FF 04 20 30 1E 06 08 2B 06 01 05 05 07
Sep 13 12:04:14.297: 03 01 06 08 2B 06 01 05 05 07 03 02 06 08 2B 06
Sep 13 12:04:14.297: 01 05 05 07 03 05 30 23 06 03 55 1D 11 04 1C 30
Sep 13 12:04:14.297: 1A 86 18 43 50 2D 37 39 36 35 47 2D 53 45 50 30
Sep 13 12:04:14.297: 30 32 31 35 35 30 32 45 36 42 37 30 1D 06 03 55
Sep 13 12:04:14.297: 1D 0E 04 16 04 14 43 CC E3 E7 53 DF 93 CE 01 B4
Sep 13 12:04:14.297: 34 07 31 D5 07 B4 97 5E A2 7D 30 1F 06 03 55 1D
Sep 13 12:04:14.297: 23 04 18 30 16 80 14 D0 C5 22 26 AB 4F 46 60 EC
Sep 13 12:04:14.297: AE 05 91 C7 DC 5A D1 B0 47 F7 6C 30 3B 06 03 55
Sep 13 12:04:14.297: 1D 1F 04 34 30 32 30 30 A0 2E A0 2C 86 2A 68 74
Sep 13 12:04:14.297: 74 70 3A 2F 2F 77 77 77 2E 63 69 73 63 6F 2E 63
Sep 13 12:04:14.297: 6F 6D 2F 73 65 63 75 72 69 74 79 2F 63 72 6C 2F
Sep 13 12:04:14.297: 63 6D 63 61 2E 63 72 6C 30 48 06 08 2B 06 01 05
Sep 13 12:04:14.297: 05 07 01 01 04 3C 30 3A 30 38 06 08 2B 06 01 05
Sep 13 12:04:14.297: 05 07 30 02 86 2C 68 74 74 70 3A 2F 2F 77 77 77
Sep 13 12:04:14.297: 2E 63 69 73 63 6F 2E 63 6F 6D 2F 73 65 63 75 72
Sep 13 12:04:14.297: 69 74 79 2F 63 65 72 74 73 2F 63 6D 63 61 2E 63
Sep 13 12:04:14.297: 65 72 30 3F 06 09 2B 06 01 04 01 82 37 14 02 04
Sep 13 12:04:14.297: 32 1E 30 00 49 00 50 00 53 00 45 00 43 00 49 00
Sep 13 12:04:14.297: 6E 00 74 00 65 00 72 00 6D 00 65 00 64 00 69 00
Sep 13 12:04:14.297: 61 00 74 00 65 00 4F 00 66 00 66 00 6C 00 69 00
Sep 13 12:04:14.297: 6E 00 65 30 0D 06 09 2A 86 48 86 F7 0D 01 01 05
Sep 13 12:04:14.297: 05 00 03 82 01 01 00 65 5C C0 B3 1F 94 C1 69 1C
Sep 13 12:04:14.297: 8F 02 EE A9 57 9F FC CC 4C 02 C1 00 97 D2 68 CA
Sep 13 12:04:14.297: F8 00 D7 0F D6 D0 C6 9A 8B 78 9C D4 A5 DC 02 EF
Sep 13 12:04:14.297: B7 4E 15 E7 35 6C 04 2A FE 45 00 27 59 88 F3 9D
Sep 13 12:04:14.297: 89 BC 84 2A B6 65 05 34 66 91 1F AE 5D AF CF 27
Sep 13 12:04:14.297: A1 FD B4 75 7B 6C 72 96 DD DA 05 01 BC CA D3 E2
Sep 13 12:04:14.297: 45 EA 48 1F 74 2B 2B E7 23 22 64 DD 77 50 F0 0C
Sep 13 12:04:14.297: A0 E7 D8 C8 07 D9 83 12 C5 CD 0E 9F 3B E0 AF 0E
Sep 13 12:04:14.297: 81 86 50 91 33 07 CD AE 62 39 AF 8C 07 2C C4 F6
Sep 13 12:04:14.297: AF 3D AA B2 59 B9 5F E9 02 9F 49 3A 49 DA C4 CC
Sep 13 12:04:14.297: 15 59 70 99 FE 0B DA 07 54 9D 0F CC 0E 72 F2 86
Sep 13 12:04:14.297: A5 8C F5 7E E3 94 8D F0 4F C5 6E D0 47 65 C4 A1
Sep 13 12:04:14.297: 3A 9D 28 FF 36 71 05 D4 48 65 D3 5B D9 50 C7 B1
Sep 13 12:04:14.297: B8 45 63 E2 50 21 5B 72 65 21 57 40 84 5D 2D 25
Sep 13 12:04:14.297: 92 49 7F 1B 7B 5D D1 B4 92 E9 81 D4 DB 78 22 BD
Sep 13 12:04:14.297: 55 B2 CE E4 BF 41 8A 2F 48 5D 34 D6 D2 AB 4C B1
Sep 13 12:04:14.297: DA 99 8F 1D 6A FD B0
Sep 13 12:04:14.297:
==========================
Sep 13 12:04:14.301: CRYPTO_PKI: (A0037) Session started - identity not specified
Sep 13 12:04:14.301: CRYPTO_PKI: (A0037) Adding peer certificate
Sep 13 12:04:14.301: CRYPTO_PKI: Added x509 peer certificate - (1149) bytes
Sep 13 12:04:14.301: CRYPTO_PKI: ip-ext-val: IP extension validation not required
Sep 13 12:04:14.301: CRYPTO_PKI: create new ca_req_context type PKI_VERIFY_CHAIN_CONTEXT,ident 6
Sep 13 12:04:14.301: CRYPTO_PKI: (A0037)validation path has 1 certs
Sep 13 12:04:14.301: CRYPTO_PKI: (A0037) Check for identical certs
Sep 13 12:04:14.301: CRYPTO_PKI : (A0037) Validating non-trusted cert
Sep 13 12:04:14.301: CRYPTO_PKI: (A0037) Create a list of suitable trustpoints
Sep 13 12:04:14.301: CRYPTO_PKI: Unable to locate cert record by issuername
Sep 13 12:04:14.301: CRYPTO_PKI: No trust point for cert issuer, looking up cert chain
Sep 13 12:04:14.301: CRYPTO_PKI: Found a issuer match
Sep 13 12:04:14.301: CRYPTO_PKI: (A0037) Suitable trustpoints are: Trustpool2,
Sep 13 12:04:14.301: CRYPTO_PKI: (A0037) Attempting to validate certificate using Trustpool2 policy
Sep 13 12:04:14.301: CRYPTO_PKI: (A0037) Using Trustpool2 to validate certificate
Sep 13 12:04:14.301: CRYPTO_PKI: Added 1 certs to trusted chain.
Sep 13 12:04:14.301: CRYPTO_PKI: Prepare session revocation service providers
Sep 13 12:04:14.301: CRYPTO_PKI: Deleting cached key having key id 5
Sep 13 12:04:14.301: CRYPTO_PKI: Attempting to insert the peer's public key into cache
Sep 13 12:04:14.301: CRYPTO_PKI:Peer's public inserted successfully with key id 6
Sep 13 12:04:14.305: CRYPTO_PKI: Expiring peer's cached key with key id 6
Sep 13 12:04:14.305: CRYPTO_PKI: (A0037) Certificate is verified
Sep 13 12:04:14.305: CRYPTO_PKI: Remove session revocation service providers
Sep 13 12:04:14.305: CRYPTO_PKI: Remove session revocation service providers
Sep 13 12:04:14.305: CRYPTO_PKI: (A0037) Certificate validated without revocation check
Sep 13 12:04:14.305: CRYPTO_PKI: Populate AAA auth data
Sep 13 12:04:14.305: CRYPTO_PKI: Unable to get configured attribute for primary AAA list authorization.
Sep 13 12:04:14.305: CRYPTO_PKI: (A0037)chain cert was anchored to trustpoint Trustpool2, and chain validation result was: CRYPTO_VALID_CERT_WITH_WARNING
Sep 13 12:04:14.305: CRYPTO_PKI: locked trustpoint Trustpool2, refcount is 1
Sep 13 12:04:14.305: CRYPTO_PKI: (A0037) Validation TP is Trustpool2
Sep 13 12:04:14.305: CRYPTO_PKI: (A0037) Certificate validation succeeded
Sep 13 12:04:14.305: CRYPTO_PKI: Rcvd request to end PKI session A0037.
Sep 13 12:04:14.305: CRYPTO_PKI: PKI session A0037 has ended. Freeing all resources.
Sep 13 12:04:14.305: CRYPTO_PKI: unlocked trustpoint Trustpool2, refcount is 0
Sep 13 12:04:14.305: SSL_accept:SSLv3 read client certificate A
Sep 13 12:04:14.305: <<< TLS 1.0 Handshake [length 0086], ClientKeyExchange
Sep 13 12:04:14.305: 10 00 00 82 00 80 59 44 59 FB C1 89 32 11 CF AA
Sep 13 12:04:14.305: 70 2E C6 CE 9F 92 72 4D 08 59 46 C9 56 CA 94 3E
Sep 13 12:04:14.305: 69 B5 5B F9 21 0E 07 0A C3 E6 AF C2 AF 23 36 1C
Sep 13 12:04:14.305: C5 36 5A 5A D0 14 05 26 E7 8F 1F 77 C3 51 3E 30
Sep 13 12:04:14.305: 29 D1 D2 76 13 2A C8 20 3B E4 63 AB 8E E7 EB D6
Sep 13 12:04:14.305: A4 89 03 1D 28 89 19 2F 53 49 B6 D6 86 FF D6 5F
Sep 13 12:04:14.305: 07 A0 7D CA 50 7A BA DD 4B E9 AF 1D 31 74 07 5E
Sep 13 12:04:14.305: 9E C9 F0 ED 43 7F DF 7F 41 D3 D3 30 A7 11 36 95
Sep 13 12:04:14.305: 34 E5 7D A9 2E 7A
Sep 13 12:04:14.305:
Sep 13 12:04:14.341: SSL_accept:SSLv3 read client key exchange A
Sep 13 12:04:14.341: <<< TLS 1.0 Handshake [length 0106], CertificateVerify
Sep 13 12:04:14.341: 0F 00 01 02 01 00 14 89 17 0D 00 42 26 E4 71 7C
Sep 13 12:04:14.341: 23 50 21 28 09 21 4F 12 EE 89 EF AD EB D3 7E 79
Sep 13 12:04:14.341: 50 78 55 19 84 C6 44 94 CF 26 A9 29 F6 A0 BF A2
Sep 13 12:04:14.341: 31 76 CB 79 8F 17 08 C9 75 7D 20 B9 23 CE BC 63
Sep 13 12:04:14.345: 87 26 57 72 62 20 8E B1 9A F2 DB 37 FE C7 42 2E
Sep 13 12:04:14.345: 66 5B CB 2B C0 C8 E2 41 83 04 F4 61 E6 6E 0A A4
Sep 13 12:04:14.345: 70 D1 3E BE DD 4A A8 D8 2A ED 38 73 E2 25 FA 80
Sep 13 12:04:14.345: AB 68 D0 DE B1 0F A2 F7 C0 63 6B E0 35 55 30 78
Sep 13 12:04:14.345: 5C 1F 28 4F 9D E2 D2 D1 9C AD 73 2A 17 85 19 91
Sep 13 12:04:14.345: 9A 96 77 45 62 99 27 CC CD E1 7D AF 73 FD D9 EF
Sep 13 12:04:14.345: 9D 5D 99 F9 9A 32 CC 32 25 DB 93 B3 A4 07 B9 EE
Sep 13 12:04:14.345: A4 19 1D 7E 31 C1 FE F3 30 CE 5E F1 DA CB C7 49
Sep 13 12:04:14.345: 6A F1 A2 B0 BE 4C D9 70 F5 60 76 4D 62 30 4A BA
Sep 13 12:04:14.345: 3F 80 1C 28 EC E7 AA 2B E6 77 82 86 B9 03 00 CB
Sep 13 12:04:14.345: 78 D9 48 1E B0 3B F7 61 54 D9 6E 65 3C C3 A7 02
Sep 13 12:04:14.345: DE 9E 73 7D 19 F0 FA 31 C2 10 AE E9 9A 07 A6 BF
Sep 13 12:04:14.345: 5B 54 E2 CC AD 4A
Sep 13 12:04:14.345:
Sep 13 12:04:14.345: CRYPTO_PKI: Attempting to insert the peer's public key into cache
Sep 13 12:04:14.345: CRYPTO_PKI:Peer's public inserted successfully with key id 6
Sep 13 12:04:14.349: SSL_accept:SSLv3 read certificate verify A
Sep 13 12:04:14.349: <<< TLS 1.0 ChangeCipherSpec [length 0001]
Sep 13 12:04:14.349: 01
Sep 13 12:04:14.349:
Sep 13 12:04:14.353: <<< TLS 1.0 Handshake [length 0010], Finished
Sep 13 12:04:14.353: 14 00 00 0C 52 F2 BB 67 BF 83 B2 39 96 6C 01 78
Sep 13 12:04:14.353:
Sep 13 12:04:14.353: SSL_accept:SSLv3 read finished A
Sep 13 12:04:14.353: >>> TLS 1.0 ChangeCipherSpec [length 0001]
Sep 13 12:04:14.353: 01
Sep 13 12:04:14.353:
Sep 13 12:04:14.353: SSL_accept:SSLv3 write change cipher spec A
Sep 13 12:04:14.353: >>> TLS 1.0 Handshake [length 0010], Finished
Sep 13 12:04:14.353: 14 00 00 0C FD A5 87 A8 DA 79 10 28 E3 9D 2D D6
Sep 13 12:04:14.353:
Sep 13 12:04:14.353: SSL_accept:SSLv3 write finished A
Sep 13 12:04:14.353: SSL_accept:SSLv3 flush data
Sep 13 12:04:14.353: Handshake done: SSL negotiation finished successfully
Sep 13 12:04:14.353: TLS Handshake completes
Sep 13 12:04:14.353: add_skinny_secure_socket 0 10.192.255.163 50708
Sep 13 12:04:15.109: UNSUPPORTED message 0x15A on socket 3
Sep 13 12:04:15.185: ephone-(2)[3] StationRegisterMessage (2/4/21) from 10.192.255.163
Sep 13 12:04:15.185: ephone-(2)[3] Register StationIdentifier DeviceName SEP00215502E6B7
Sep 13 12:04:15.185: ephone-(2)[3] StationIdentifier Instance 0 deviceType 436
Sep 13 12:04:15.185: fSkinnyStationRegister deviceType 436 protocolVer = 0xAD720016
Sep 13 12:04:15.185: StationJoinAndDirectTransferFeatureSupportMask set disable
Sep 13 12:04:15.185: StationDisableJoinOnTheSameLineFeatureMask set enable
Sep 13 12:04:15.185: StationDisableJoinAcrossLineFeatureMask set enable
Sep 13 12:04:15.185: StationDisableDirectTransferOnTheSameLineFeatureMask set enable
Sep 13 12:04:15.185: StationDisableDirectTransferAcrossLineFeatureMask set enable
Sep 13 12:04:15.185: ephone-2[1/-1][SEP00215502E6B7]:stationIpAddr 10.192.255.163
Sep 13 12:04:15.185: ephone-2[1/-1][SEP00215502E6B7]:stationIpv6Addr ::
Sep 13 12:04:15.185: ephone-2[1/-1][SEP00215502E6B7]:maxStreams 5
Sep 13 12:04:15.185: ephone-2[1/-1][SEP00215502E6B7]:From Phone raw protocol Ver 0xAD720016
Sep 13 12:04:15.185: ephone-2[1/-1][SEP00215502E6B7]:protocol Ver 0xAD720016
Sep 13 12:04:15.185: ephone-2[1/-1][SEP00215502E6B7]:phone-size 36136 dn-size 1048
Sep 13 12:04:15.185: ephone-(2) Allow any Skinny Server IP address 10.57.37.130
Sep 13 12:04:15.185: ephone-2[1/-1][SEP00215502E6B7]:Found entry 1 for 00215502E6B7
Sep 13 12:04:15.185: ephone-2[1/-1][SEP00215502E6B7]:socket change -1 to 3
Sep 13 12:04:15.185: ephone-2[1/-1][SEP00215502E6B7]:FAILED: CLOSED old socket -1
Sep 13 12:04:15.185: ephone-2[1/-1][SEP00215502E6B7]:
Sep 13 12:04:15.185: bulk_speeddial_init_ephone: 1
Sep 13 12:04:15.185: ephone-2[1/-1]:SkinnyVideoParmsUpdate: phone: 1, action: 2, dn_index: -1
Sep 13 12:04:15.185: ephone-2[1/3][SEP00215502E6B7]:phone SEP00215502E6B7 re-associate OK on socket [3]
Sep 13 12:04:15.185: %IPPHONE-6-REGISTER: ephone-2:SEP00215502E6B7 IP:10.192.255.163 Socket:3 DeviceType:Phone has registered.
Sep 13 12:04:15.185: Phone 1 socket 3
Sep 13 12:04:15.185: Skinny Local IP address = 10.57.37.130 on port 2000
Sep 13 12:04:15.185: Skinny Phone IP address = 10.192.255.163 50708
Sep 13 12:04:15.185: ephone-2[1/3]:SkinnyVideoParmsUpdate: phone: 1, action: 2, dn_index: -1
Sep 13 12:04:15.185: ephone-2[1/3][SEP00215502E6B7]:Signal protocol ver 17 to phone with ver 22
Sep 13 12:04:15.185: ephone-2[1/3][SEP00215502E6B7]:Date Format D/M/Y
Sep 13 12:04:15.185: ephone-2[1/3]:RegisterAck sent to sockettype ephone socket 3: keepalive period 30 use sccp-version 17
Sep 13 12:04:15.185: ephone-2[1/3]:CapabilitiesReq sent
Sep 13 12:04:15.221: ephone-2[1/3][SEP00215502E6B7]:Skinny IP port 3500 set for socket [3]
Sep 13 12:04:15.225: ephone-2[1/3]:ButtonTemplateReqMessage
Sep 13 12:04:15.225: ephone-2[1/3][SEP00215502E6B7]:ButtonTemplateReqMessage get maxNumberOfLines 54
Sep 13 12:04:15.225: ephone-2[1/3]:ButtonTemplateReqMessage waiting for Caps
Sep 13 12:04:15.225: ephone-2[1/3]:StationSoftKeyTemplateReqMessage
Sep 13 12:04:15.225: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 1 Redial
Sep 13 12:04:15.225: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 2 NewCall
Sep 13 12:04:15.225: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 3 Hold
Sep 13 12:04:15.225: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 4 Trnsfer
Sep 13 12:04:15.225: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 5 CFwdAll
Sep 13 12:04:15.225: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 6 CW Off
Sep 13 12:04:15.225: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 7 CFwdNoAnswer
Sep 13 12:04:15.225: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 8 <<
Sep 13 12:04:15.225: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 9 EndCall
Sep 13 12:04:15.225: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 10 Resume
Sep 13 12:04:15.225: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 11 Answer
Sep 13 12:04:15.225: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 12 Info
Sep 13 12:04:15.225: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 13 Confrn
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 14 Park
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 15 Join
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 16 MeetMe
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 17 PickUp
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 18 GPickUp
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 19 RmLstC
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 20 CallBack
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 21 Barge
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 22 DnD
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 23 Acct
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 24 Flash
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 25 Login
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 26 HLog
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 27 ConfList
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 28 Select
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 29 TrnsfVM
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 30 CBarge
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 31 LiveRcd
Sep 13 12:04:15.229: ephone-2[1/3][SEP00215502E6B7]:SoftKeys: 32 Mobility
Sep 13 12:04:15.229: ephone-2[1/3]:StationSoftKeyTemplateResMessage
Sep 13 12:04:15.229: ephone-2[1/3]:StationSoftKeySetReqMessage
Sep 13 12:04:15.229: ephone-2[1/3]:GetSoftkeysetIdle: Default Template
Sep 13 12:04:15.229: ephone-2[1/3]:GetSoftkeysetConnected:Default Template
Sep 13 12:04:15.229: ephone-2[1/3]:GetSoftkeysetHold: Default Template
Sep 13 12:04:15.229: ephone-2[1/3]:GetSoftkeysetRinging: Default Template
Sep 13 12:04:15.229: ephone-2[1/3]:GetSoftkeysetSeized:Default Template
Sep 13 12:04:15.229: ephone-2[1/3]:GetSoftkeysetAlerting:Default Template
Sep 13 12:04:15.229: ephone-2[1/3]:GetSoftkeysetRemote_in_use:Default Template
Sep 13 12:04:15.229: ephone-2[1/3]:StationSoftKeySetResMessage
Sep 13 12:04:15.229: skinny_tls_write block error=11 returnCode=-6982, len=792 buf_len=0
Sep 13 12:04:15.233: ephone-2[1/3]:StationConfigStatReqMessage
Sep 13 12:04:15.233: ephone-2[1/3]:waiting for Caps
Sep 13 12:04:15.241: ephone-2[1/3]:CapabilitiesRes received
Sep 13 12:04:15.241: ephone-2[1/3][SEP00215502E6B7]:Caps list 9
WideBand_256K 40 ms, is_mtp 0
G711Ulaw64k 40 ms, is_mtp 0
G711Alaw64k 40 ms, is_mtp 0
ILBC 60 ms, is_mtp 0
G729AnnexB 60 ms, is_mtp 0
G729AnnexAwAnnexB 60 ms, is_mtp 0
G729 60 ms, is_mtp 0
G729AnnexA 60 ms, is_mtp 0
RFC2833 Dynamic Payload 1 ms, is_mtp 0
Sep 13 12:04:15.241: ephone-2[1/3]:Process pending button template
Sep 13 12:04:15.245: ephone-2[1/3]:ButtonTemplateReqMessage
Sep 13 12:04:15.245: ephone-2[1/3][SEP00215502E6B7]:StationButtonTemplateReqMessage set max presentation to 6
Sep 13 12:04:15.245: ephone-2[1/3]:CheckAutoReg
Sep 13 12:04:15.245: ephone-2[1/3]:AutoReg is disabled
Sep 13 12:04:15.245: ephone-2[1/3][SEP00215502E6B7]:Setting 6 lines 0 speed-dials on phone (max_line 6)
Sep 13 12:04:15.245: ephone-2[1/3][SEP00215502E6B7]:First Speed Dial Button location is 0 (0)
Sep 13 12:04:15.245: ephone-2[1/3]:ButtonTemplate lines=6 speed=0 buttons=6 offset=0
Sep 13 12:04:15.245: ephone-2[1/3][SEP00215502E6B7]:ButtonTemplate buttonCount=6 totalButtonCount=6 buttonOffset=0
Sep 13 12:04:15.245: ephone-2[1/3][SEP00215502E6B7]:Configured 0 speed dial buttons
Sep 13 12:04:15.245: ephone-2[1/3]:Process pending ConfigStat
Sep 13 12:04:15.245: ephone-2[1/3]:StationConfigStatReqMessage
Sep 13 12:04:15.245: ephone-2[1/3][SEP00215502E6B7]:StationConfigStatMessage sent for device SEP00215502E6B7 (40/280)
Sep 13 12:04:15.301: ephone-2[1/3][SEP00215502E6B7]:StationLineStatReqMessage from ephone line 1
Sep 13 12:04:15.301: ephone-2[1/3]:StationLineStatReqMessage ephone line 1 DN 2 = 456 desc = 456 label = 456
Sep 13 12:04:15.301: ephone-2[1/3][SEP00215502E6B7]:StationLineStatResMessage sent to ephone (1 of 6)
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationForwardStatReqMessage line 1 from ephone
Sep 13 12:04:15.325: Skinny StationForwardStatMessage line 1 sent on ephone socket [3] for ephone-2
Sep 13 12:04:15.325: activeForward 0 AllActive 0 BusyActive 0 NoAnswerActive 0
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationLineStatReqMessage from ephone line 2
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationLineStatReqMessage from ephone line 2 Invalid DN -1
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationLineStatResMessage sent to ephone (2 of 6)
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationForwardStatReqMessage line 2 from ephone
Sep 13 12:04:15.325: Skinny StationForwardStatMessage line 2 sent on ephone socket [3] for ephone-2
Sep 13 12:04:15.325: activeForward 0 AllActive 0 BusyActive 0 NoAnswerActive 0
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationLineStatReqMessage from ephone line 3
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationLineStatReqMessage from ephone line 3 Invalid DN -1
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationLineStatResMessage sent to ephone (3 of 6)
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationForwardStatReqMessage line 3 from ephone
Sep 13 12:04:15.325: Skinny StationForwardStatMessage line 3 sent on ephone socket [3] for ephone-2
Sep 13 12:04:15.325: activeForward 0 AllActive 0 BusyActive 0 NoAnswerActive 0
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationLineStatReqMessage from ephone line 4
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationLineStatReqMessage from ephone line 4 Invalid DN -1
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationLineStatResMessage sent to ephone (4 of 6)
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationForwardStatReqMessage line 4 from ephone
Sep 13 12:04:15.325: Skinny StationForwardStatMessage line 4 sent on ephone socket [3] for ephone-2
Sep 13 12:04:15.325: activeForward 0 AllActive 0 BusyActive 0 NoAnswerActive 0
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationLineStatReqMessage from ephone line 5
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationLineStatReqMessage from ephone line 5 Invalid DN -1
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationLineStatResMessage sent to ephone (5 of 6)
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationForwardStatReqMessage line 5 from ephone
Sep 13 12:04:15.325: Skinny StationForwardStatMessage line 5 sent on ephone socket [3] for ephone-2
Sep 13 12:04:15.325: activeForward 0 AllActive 0 BusyActive 0 NoAnswerActive 0
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationLineStatReqMessage from ephone line 6
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationLineStatReqMessage from ephone line 6 Invalid DN -1
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationLineStatResMessage sent to ephone (6 of 6)
Sep 13 12:04:15.325: ephone-2[1/3]:SkinnyCompleteRegistration
Sep 13 12:04:15.325: ephone-2[1/3]:SelectPhoneSoftKeys set 0 mask FFBE for line 0 ref 0
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:SkinnyCheckPendingCallBackPhone scan 6 lines
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]: DN in-service for DN 2 chan 1
Sep 13 12:04:15.325: SkinnyGetCallState for DN 2 chan 1 IDLE
Sep 13 12:04:15.325: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]: DN in-service for DN 2 chan 2
Sep 13 12:04:15.325: SkinnyGetCallState for DN 2 chan 2 IDLE
Sep 13 12:04:15.325: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
Sep 13 12:04:15.325: Skinny DN 2 chan 1 state change to UP
Sep 13 12:04:15.325: Skinny DN 2 chan 2 state change to UP
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:SkinnyCleanupOverlapInfo: Obtained phone index(1) from DN
Sep 13 12:04:15.325: SkinnySetCTILineStatus: phone 1 dn 2 line status 1 line_removed 0
Sep 13 12:04:15.325: ephone-2[1/3][SEP00215502E6B7]:StationForwardStatReqMessage line 6 from ephone
Sep 13 12:04:15.325: Skinny StationForwardStatMessage line 6 sent on ephone socket [3] for ephone-2
Sep 13 12:04:15.325: activeForward 0 AllActive 0 BusyActive 0 NoAnswerActive 0
Sep 13 12:04:15.601: ephone-2[1/3]:TimeDateReqMessage
Sep 13 12:04:15.601: year=2016 month=9 day=13
Sep 13 12:04:15.601: hour=12 minute=4 second=15
Sep 13 12:04:15.601: day=2 dayofyear=257 tzoffset=0
Sep 13 12:04:15.601: ephone-2[1/3]:DefineTimeDate sent
Sep 13 12:04:15.989: ephone-2[1/3][SEP00215502E6B7]:Check Hot-Sync for line from 1 to 6 out of 1 lines
Sep 13 12:04:15.989: ephone-2[1/3][SEP00215502E6B7]:SkinnySyncDnCFA DN 2 line 1 to []
Sep 13 12:04:15.989: SkinnySyncDnCFA for DN 2 for 1 phones
Sep 13 12:04:15.989: SkinnyGetCallState for DN 2 chan 1 IDLE
Sep 13 12:04:15.989: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
Sep 13 12:04:15.989: SkinnyGetCallState for DN 2 chan 1 IDLE
Sep 13 12:04:15.989: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
Sep 13 12:04:15.989: SkinnyGetCallState for DN 2 chan 2 IDLE
Sep 13 12:04:15.989: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
Sep 13 12:04:15.989: ephone-2[3]:SetCallState line 1 DN 2(-1) chan 1 ref 0 TsOnHook
Sep 13 12:04:15.989: ephone-2[1/3]:SkinnyTrackActiveCall for line 1 ref 0 state 2 (slot 0)
Sep 13 12:04:15.989: ephone-2[1/3]:SelectPhoneSoftKeys set 0 mask FFBE for line 1 ref 0
Sep 13 12:04:15.989: SkinnyGetCallState for DN 2 chan 2 IDLE
Sep 13 12:04:15.989: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
Sep 13 12:04:15.989: SkinnyGetCallState for DN 2 chan 1 IDLE
Sep 13 12:04:15.989: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
Sep 13 12:04:15.989: SkinnyGetCallState for DN 2 chan 2 IDLE
Sep 13 12:04:15.989: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
Sep 13 12:04:15.989: ephone-2[3]:SetCallState line 1 DN 2(-1) chan 2 ref 0 TsOnHook
Sep 13 12:04:15.989: ephone-2[1/3]:SkinnyTrackActiveCall for line 1 ref 0 state 2 (slot 0)
Sep 13 12:04:15.989: ephone-2[1/3]:SelectPhoneSoftKeys set 0 mask FFBE for line 1 ref 0
Sep 13 12:04:15.989: ephone-2[1/3][SEP00215502E6B7]:SkinnyCleanupOverlapInfo: Obtained phone index(1) from DN
Sep 13 12:04:15.989: ephone-2[3]:Set MWI line 1 to OFF
Sep 13 12:04:15.989: SkinnyNotifyCTIMWI: phone 1 dn 2 mwi OFF mwi count 0
Sep 13 12:04:15.989: ephone-2[3]:Set MWI line 0 to OFF
Sep 13 12:04:15.989: ephone-2[1/3][SEP00215502E6B7]:SkinnyCheckPendingCallBackPhone scan 6 lines
Sep 13 12:04:15.989: ephone-2[1/3][SEP00215502E6B7]:Skinny Available Lines 6 set for socket [3]
Sep 13 12:04:15.989: ephone-2[1/3]:Already done SkinnyCompleteRegistration
Sep 13 12:04:16.013: ephone-2[1/3][SEP00215502E6B7]:MediaPathEventMessage Headset OFF
Sep 13 12:04:16.013: ephone-2[1/3]:MediaPathEventMessage
Sep 13 12:04:16.013: ephone-2[1/3][SEP00215502E6B7]:MediaPathEventMessage Handset OFF
Sep 13 12:04:16.013: ephone-2[1/3]:MediaPathEventMessage
Sep 13 12:04:16.017: ephone-2[1/3][SEP00215502E6B7]:MediaPathCapabilitiesMessage Speaker Enable
Sep 13 12:04:16.017: ephone-2[1/3]:MediaPathCapabilitiesMessage
Sep 13 12:04:16.113: ephone-2[1/3][SEP00215502E6B7]:Skinny Available Lines 6 set for socket [3]
Sep 13 12:04:16.113: ephone-2[1/3]:Already done SkinnyCompleteRegistration
Documentation :
https://supportforums.cisco.com/blog/9913611/chalk-talk-understanding-and-setting-secure-cme
09-14-2016 11:21 PM
capf-ip-in-cnf was missing on my ephone config.
Now all is ok.
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide