Статус: Участник
Группы: Участники
Зарегистрирован: 11.01.2016(UTC) Сообщений: 12
|
Здравствуйте! У нас такая же проблема, на ЦС стоит ОС Windows Server 2003 R2. Стали долго выпускаться сертификаты (более 30 секунд). Сертификаты выпускаем на АРМ Администратора ЦР. Компонент Revocation Provider в CryptoPro CSP на ЦС не установлен. Лог dbgview: Код:
00000004 11:23:22 [3756] {CA}Request.GetCertificateEx Start.
00000005 11:23:22 [3756] {CA} ObjectContext Got
00000006 11:23:22 [3756] {CA} ASP.Request Got
00000007 11:23:22 [3756] {CA} ClientCertificate Got LenB=2232
00000008 11:23:22 [3756] {CA} CAPICOM.Certificate Got
00000009 11:23:22 [3756] {CA} Certificate Importing AS IS
00000010 11:23:22 [3756] {CA} Certificate Imported
00000011 11:23:22 [3756] {CA} CAPICOM.Store Got
00000012 11:23:22 [3756] {CA} CAPICOM.Store Opened
00000013 11:23:22 [3756] {CA} CAPICOM.Certificates Got
00000014 11:23:22 [3756] {CA} Certificates.Find (...True) Start
00000015 11:23:22 [3756] cpcspr: Thread: file:line function text xcode(dcode) level: 0
00000016 11:23:22 [3756] cpui: Thread: file:line function text xcode(dcode) level: 0
00000017 11:23:22 [3756] cpext: Thread: file:line function text xcode(dcode) level: 0
00000018 11:23:22 [3756] {CA} Certificates.Find (...True) Done
00000019 11:23:22 [3756] {CA}Проверка подписи.
00000020 11:23:36 [3756] {CA} Call CertRequest.Submit
00000021 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Started: , ThreadID = 7
00000022 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: certPolicy.SetContext 12, ThreadID = 7
00000023 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: Length(RawRequest) = 1018, ThreadID = 7
00000024 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: New Pkcs10CertificateRequest created, ThreadID = 7
00000025 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: RequestDN = указан subject сертификата, ThreadID = 7
00000026 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: RequestID = 4275, ThreadID = 7
00000027 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.EnableConnectionPooling Started: , ThreadID = 7
00000028 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.EnableConnectionPooling Finished: , ThreadID = 7
00000029 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.GetUsersNumber Started: , ThreadID = 7
00000030 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.GetConnection Started: connection is null, creating, ThreadID = 7
00000031 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.GetConnection Executing: DbConnectionStringBuilder created, ThreadID = 7
00000032 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.GetConnection Executing: SqlConnectionStringBuilder created, ThreadID = 7
00000033 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.GetConnection Executing: connection created, ThreadID = 7
00000034 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.GetConnection Finished: connection opened, ThreadID = 7
00000035 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.GetUsersNumber Executing: SqlCommand created, ThreadID = 7
00000036 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.GetUsersNumber Executing: CommandText created, ThreadID = 7
00000037 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: Текущее число пользователей: 2648 из 10000 разрешенных., ThreadID = 7
00000038 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: Обработка расширений..., ThreadID = 7
00000039 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: Обработка Basic Constraints..., ThreadID = 7
00000040 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: Обработка DN отключена, ThreadID = 7
00000041 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: Проверка корректности субъекта (приказ 795 и т.д.), ThreadID = 7
00000042 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_CheckSubjectValidity Started: , ThreadID = 7
00000043 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_CheckSubjectValidity Finished: , ThreadID = 7
00000044 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: Обработка EKU policy..., ThreadID = 7
00000045 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_CheckEKUPolicy Executing: requestInfo.ExtendedKeyUsage = 1.3.6.1.4.1.33760.1, 1.2.643.100.114.2, 1.2.643.100.113.1, 1.2.643.2.2.34.6, 1.3.6.1.5.5.7.3.2, ThreadID = 7
00000046 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Started: , ThreadID = 7
00000047 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Executing: OID=*************************************** - Allowed, ThreadID = 7
00000048 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Executing: Period = 1-3-0-0-0-0, ThreadID = 7
00000049 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Executing: PKUP = 1-3-0-0-0-0 True, ThreadID = 7
00000050 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Executing: OID=1.2.643.100.114.2 (Класс средств УЦ КС2) - Allowed, ThreadID = 7
00000051 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Executing: Period = 1-3-0-0-0-0, ThreadID = 7
00000052 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Executing: PKUP = 1-3-0-0-0-0 True, ThreadID = 7
00000053 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Executing: OID=1.2.643.100.113.1 (Класс средства ЭП КС1) - Allowed, ThreadID = 7
00000054 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Executing: Period = 1-3-0-0-0-0, ThreadID = 7
00000055 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Executing: PKUP = 1-3-0-0-0-0 True, ThreadID = 7
00000056 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Executing: OID=1.2.643.2.2.34.6 (Пользователь Центра Регистрации, HTTP, TLS клиент) - Allowed, ThreadID = 7
00000057 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Executing: Period = 1-3-0-0-0-0, ThreadID = 7
00000058 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Executing: PKUP = 1-3-0-0-0-0 True, ThreadID = 7
00000059 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Executing: OID=1.3.6.1.5.5.7.3.2 (Проверка подлинности клиента) - Allowed, ThreadID = 7
00000060 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Executing: Period = 1-3-0-0-0-0, ThreadID = 7
00000061 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Executing: PKUP = 1-3-0-0-0-0 True, ThreadID = 7
00000062 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Finished: GetValidPeriodByUsage Done Returns=1-3-0-0-0-0, ThreadID = 7
00000063 11:23:36 [1224] [CA_CryptoProDefault]Policy.GetValidityPeriodByUsage Finished: GetValidPeriodByUsage PKUP= 1-3-0-0-0-0, ThreadID = 7
00000064 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: Установка срока действия, ThreadID = 7
00000065 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_SetCertificateValidity Started: , ThreadID = 7
00000066 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_SetCertificateValidity Executing: dtNotBefore= 20.02.2016 6:22:36, ThreadID = 7
00000067 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_SetCertificateValidity Executing: dtNotAfter=07.02.2031 12:20:12, ThreadID = 7
00000068 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_SetCertificateValidity Executing: vtNotBefore=20.02.2016 6:23:00, ThreadID = 7
00000069 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_SetCertificateValidity Executing: vtNotAfter=20.02.2016 6:23:00, ThreadID = 7
00000070 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_SetCertificateValidity Executing: vtNotAfter before adding KeyUsage: 20.02.2016 6:23:00, ThreadID = 7
00000071 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_SetCertificateValidity Executing: vtNotAfter after adding KeyUsage: 20.05.2017 6:23:00, ThreadID = 7
00000072 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_SetCertificateValidity Executing: vtNotAfter after adding ClockSkewMinutes20.05.2017 6:24:00, ThreadID = 7
00000073 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_SetCertificateValidity Executing: SetCertificateProperty NOTBEFOREDATE, ThreadID = 7
00000074 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_SetCertificateValidity Executing: SetCertificateProperty NOTAFTERDATE, ThreadID = 7
00000075 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_SetCertificateValidity Finished: , ThreadID = 7
00000076 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_SetPKUP Started: PKUP: 1-3-0-0-0-0, ThreadID = 7
00000077 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_SetPKUP Executing: NotBefore: 20.02.2016 11:23:00, ThreadID = 7
00000078 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_SetPKUP Executing: NotAfter: 20.05.2017 11:23:00, ThreadID = 7
00000079 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_SetPKUP Finished: , ThreadID = 7
00000080 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: Обработка расширений из запроса, ThreadID = 7
00000081 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_RequestExtensionPolicy Started: Count=4, ThreadID = 7
00000082 11:23:36 [1224] [CA_CryptoProDefault]Policy.CheckForAPIP Executing: IP Present: False, ThreadID = 7
00000083 11:23:36 [1224] [CA_CryptoProDefault]Policy.CheckForAPIP Executing: AP Present: False, ThreadID = 7
00000084 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_RequestExtensionPolicy Executing: Search policy for extension: 2.5.29.37, ThreadID = 7
00000085 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Started: , ThreadID = 7
00000086 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: Got Enh Key Usage, proceed to SetCertificateExtension ENHANCED_KEY_USAGE, ThreadID = 7
00000087 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: extFlag 1: 0, ThreadID = 7
00000088 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: extFlag 2: 0, ThreadID = 7
00000089 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: m_EKUOIDs.Count: 3, ThreadID = 7
00000090 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: !m_EKUOIDs.Contains(ALL), ThreadID = 7
00000091 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: foreach oid : 1.3.6.1.4.1.33760.1, ThreadID = 7
00000092 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: foreach oid : 1.2.643.2.2.34.6, ThreadID = 7
00000093 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: foreach oid : 1.3.6.1.5.5.7.3.2, ThreadID = 7
00000094 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: foreach passed, ThreadID = 7
00000095 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: certPolicy.SetBinaryCertificateExtension szOID_ENHANCED_KEY_USAGE passed, ThreadID = 7
00000096 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_RequestExtensionPolicy Executing: Search policy for extension: 2.5.29.15, ThreadID = 7
00000097 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Started: , ThreadID = 7
00000098 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: Got Key Usage, proceed to SetCertificateExtension, ThreadID = 7
00000099 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_RequestExtensionPolicy Executing: Search policy for extension: 1.2.643.100.111, ThreadID = 7
00000100 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Started: , ThreadID = 7
00000101 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: Got Extension 1.2.643.100.111 (Len = 0), ThreadID = 7
00000102 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: HasConstantValue : True, AddToAllCertificates : True, Len = 45, ThreadID = 7
00000103 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: SetCertificateExtension 1.2.643.100.111, ThreadID = 7
00000104 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_RequestExtensionPolicy Executing: Search policy for extension: 1.2.643.100.112, ThreadID = 7
00000105 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Started: , ThreadID = 7
00000106 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: Got Extension 1.2.643.100.112 (Len = 0), ThreadID = 7
00000107 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: HasConstantValue : True, AddToAllCertificates : True, Len = 296, ThreadID = 7
00000108 11:23:36 [1224] [CA_CryptoProDefault]Policy.SubStep_ProcessRequestExtension Executing: SetCertificateExtension 1.2.643.100.112, ThreadID = 7
00000109 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_RequestExtensionPolicy Finished: , ThreadID = 7
00000110 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_ProcessCDPAndAIA Started: , ThreadID = 7
00000111 11:23:36 [1224] [CA_CryptoProDefault]Policy.ProcessExtRules Started: , ThreadID = 7
00000112 11:23:36 [1224] [CA_CryptoProDefault]Policy.ProcessExtRules Executing: No rules found - adding all enabled URLs., ThreadID = 7
00000113 11:23:36 [1224] [CA_CryptoProDefault]Policy.ProcessExtRules Executing: Found total 1 CDP points, ThreadID = 7
00000114 11:23:36 [1224] [CA_CryptoProDefault]mRules.GetURLWithValuesVars Started: Url ***************************************, ThreadID = 7
00000115 11:23:36 [1224] [CA_CryptoProDefault]Policy.ProcessExtRules Executing: Found total 2 OCSP and AIA points, ThreadID = 7
00000116 11:23:36 [1224] [CA_CryptoProDefault]mRules.GetURLWithValuesVars Started: Url ***************************************, ThreadID = 7
00000117 11:23:36 [1224] [CA_CryptoProDefault]mRules.GetURLWithValuesVars Started: Url ***************************************, ThreadID = 7
00000118 11:23:36 [1224] [CA_CryptoProDefault]Policy.ProcessExtRules Finished: , ThreadID = 7
00000119 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_ProcessCDPAndAIA Executing: Adding CDP..., ThreadID = 7
00000120 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_ProcessCDPAndAIA Executing: Adding AIA..., ThreadID = 7
00000121 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_ProcessCDPAndAIA Finished: , ThreadID = 7
00000122 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_ProcessSAN Started: , ThreadID = 7
00000123 11:23:36 [1224] [CA_CryptoProDefault]Policy.AddRDNComponentsToSAN Started: , ThreadID = 7
00000124 11:23:36 [1224] [CA_CryptoProDefault]Policy.AddRDNComponentsToSAN Finished: , ThreadID = 7
00000125 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_ProcessSAN Executing: cert template extension absent, ThreadID = 7
00000126 11:23:36 [1224] [CA_CryptoProDefault]Policy.Step_ProcessSAN Finished: , ThreadID = 7
00000127 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: Проверка уникальности открытого ключа запроса, длина ключа 66, ThreadID = 7
00000128 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: Ключ:
00000129 11:23:36 [1224] , ThreadID = 7
00000130 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: BEDGypyT+NAynEXuk5R0WAYJ/u31qVjRgsLyBJLhXiOn32kUOsNcRfmN1acfvZFuBwc6X32lBSMtYKwiGhzyzc5h, ThreadID = 7
00000131 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.RegisterNewRequest Started: , ThreadID = 7
00000132 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.GetConnection Started: connection is null, creating, ThreadID = 7
00000133 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.GetConnection Executing: DbConnectionStringBuilder created, ThreadID = 7
00000134 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.GetConnection Executing: SqlConnectionStringBuilder created, ThreadID = 7
00000135 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.GetConnection Executing: connection created, ThreadID = 7
00000136 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.GetConnection Finished: connection opened, ThreadID = 7
00000137 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.InsertPublicKey Started: , ThreadID = 7
00000138 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.InsertPublicKey Finished: result = True, ThreadID = 7
00000139 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.RegisterNewRequest Executing: Регистрация пользователя в БД ЦС (указан subject сертификата)., ThreadID = 7
00000140 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.InsertUser Started: , ThreadID = 7
00000141 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.InsertUser Finished: result = True, ThreadID = 7
00000142 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.RegisterNewRequest Executing: Пользователь 334C1A5E60A8A2CE63E66EB94A0AA356888F30B5 добавлен в в БД ЦС., ThreadID = 7
00000143 11:23:36 [1224] [CA_CryptoProDefault]CADatabaseAccessor.RegisterNewRequest Finished: , ThreadID = 7
00000144 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: Проверка уникальности открытого ключа запроса завершена успешно., ThreadID = 7
00000145 11:23:36 [1224] [CA_CryptoProDefault]Policy.VerifyRequest Executing: Policy.VerifyRequest Done, ThreadID = 7
00000146 11:23:50 [1224] [CA_CryptoProExitModule]Exit.Notify Started: , ThreadID = 7
00000147 11:23:50 [1224] [CA_CryptoProExitModule]Exit.Notify Finished: , ThreadID = 7
00000148 11:23:50 [3756] {CA} CertRequest.Submit Done Disposition=3
00000149 11:23:50 [3756] {CA}Request.GetCertificateEx Done.
|