В интернет очень мало информации о журналах событий (логах), формируемых на сервере альфреско, поэтому в рамках этой небольшой статьи, я постараюсь постепенно подготовить некую справку о том, какие журналы введутся на сервере Alfresco, где они расположен и для чего нужны.

Информация о журналах событий (логах) буду приводить на примере сервера Alfresco Community 5.0.d.

Для начала я приведу список файлов журналов (логов), которые заполняются серверов Alfresco Community:

1
2
3
4
5
6
7
8
/opt/alfresco/alfresco.log
/opt/alfresco/solr.log
/opt/alfresco/share.log
/opt/alfresco/postgresql/postgresql.log
/opt/alfresco/tomcat/logs/localhost.2016-06-23.log
/opt/alfresco/tomcat/logs/manager.2016-06-23.log
/opt/alfresco/tomcat/logs/catalina.2016-06-23.log
/opt/alfresco/tomcat/logs/host-manager.2016-06-23.log

Об этих файлах и пойдет речь далее.

В ввиду того, что сервер Alfresco имеет модульную архитектуру, в которую входят в виде контейнеров веб-приложения Alfresco, Share и Solr4 (об этом написано здесь), то и система логов устроена так, что для каждого такого модуля ведуться свои журналы. Кроме этого отдельно ведется журнал событий для СУБД и отдельно для веб-сервера Apache Tomcat.

  • alfresco.log

В файл записывается общая информация, по ядру сервера альфреско.

Пример файла alfresco.log после загрузки системы:

1
2
3
4
5
2016-06-23 11:58:10,113 INFO  [org.alfresco.repo.admin] [localhost-startStop-1] Using database URL 'jdbc:postgresql://localhost:5433/alfresco' with user 'alfresco'.
2016-06-23 11:58:10,583 INFO  [org.alfresco.repo.admin] [localhost-startStop-1] Connected to database PostgreSQL version 9.3.6
2016-06-23 11:58:17,061 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
2016-06-23 11:58:17,080 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
2016-06-23 11:58:17,089 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] [localhost-startStop-1] Ignoring script patch (post-Hibernate): patch.db-V4.2-metadata-query-indexes

  • share.log

В файл записывается общая информация, котороая относится к интерфесу пользователя.

Пример файла share.log после загрузки системы:

1
2
3
4
5
2016-06-23 11:57:50,979 INFO  [org.springframework.extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2016-06-23 11:57:50,982 INFO  [org.springframework.extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2016-06-23 11:57:50,982 INFO  [org.springframework.extensions.webscripts.TemplateProcessorRegistry] [localhost-startStop-1] Registered template processor freemarker for extension ftl
2016-06-23 11:57:50,983 INFO  [org.springframework.extensions.webscripts.ScriptProcessorRegistry] [localhost-startStop-1] Registered script processor javascript for extension js
2016-06-23 11:58:00,007 INFO  [org.springframework.extensions.webscripts.DeclarativeRegistry] [localhost-startStop-1] Registered 386 Web Scripts (+0 failed), 400 URLs

Пример записи при первоначальной загрузки страница авторизации через браузер

1
2016-06-23 12:18:53,028  INFO  [web.site.EditionInterceptor] [http-apr-8080-exec-2] Successfully retrieved license information from Alfresco.

  • solr.log

В файл записывается информация обо всех вложенных документах (например прикреплённый скан договора).

Пример файла solr.log после загрузки системы:

1
2
3
4
2016-06-23 11:57:37,911 INFO  [org.alfresco.solr.component.AsyncBuildSuggestComponent] Initializing SuggestComponent
2016-06-23 11:57:37,912 INFO  [org.alfresco.solr.component.AsyncBuildSuggestComponent] Initializing SuggestComponent
2016-06-23 11:57:38,068 INFO  [org.alfresco.solr.component.AsyncBuildSuggestComponent] Loading suggester index for: shingleBasedSuggestions
2016-06-23 11:57:41,494 INFO  [org.alfresco.solr.component.AsyncBuildSuggestComponent] Loaded suggester shingleBasedSuggestions, took 3426 ms

Пример файла solr.log после добавления вложенного файла:

1
2
2016-06-23 17:42:16,824 INFO  [org.alfresco.solr.component.AsyncBuildSuggestComponent] Building suggester index for: shingleBasedSuggestions 
2016-06-23 17:42:19,165 INFO  [org.alfresco.solr.component.AsyncBuildSuggestComponent] Built suggester shingleBasedSuggestions, took 2341 ms

  • postgresql.log

В файл записывается информация обо всех ошибках связанных с СУБД.


  • catalina.out

Файл catalina.out является галвным журналом событий веб-сервера Apache Tomcat и наиболее полным в части отображения информации о событй сервера Альфреско в целом.

В файл записывается информация о работе самого веб-сервера (ошибочных параметрах настройки, конфликтах портов, отсутствии доступа к Java-среде и т.д.),  а также события в работе web приложений, расположенных в каталоге webapps, основанных на Java и их компонентах.

Пример фрагмента файла catalina.out после загрузки системы:

1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
июн 23, 2016 11:57:25 AM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: Loaded APR based Apache Tomcat Native library 1.1.32 using APR version 1.4.6.
июн 23, 2016 11:57:25 AM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true].
июн 23, 2016 11:57:25 AM org.apache.catalina.core.AprLifecycleListener initializeSSL
INFO: OpenSSL successfully initialized (OpenSSL 0.9.8y 5 Feb 2013)
июн 23, 2016 11:57:25 AM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-apr-8080"]
июн 23, 2016 11:57:25 AM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-apr-8009"]
июн 23, 2016 11:57:25 AM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8443"]
июн 23, 2016 11:57:26 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 1573 ms
июн 23, 2016 11:57:26 AM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
июн 23, 2016 11:57:26 AM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.59
июн 23, 2016 11:57:26 AM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor /opt/alfresco-5.0.d/tomcat/conf/Catalina/localhost/solr4.xml

Ниже првиеден ример записи при первоначальной загрузки страница авторизации через браузер.

Как видно из первой строки catalina.out агрегирет некоторые данные полученные из других логов. Конкретно первая строка получена из файла share.log.

1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
2016-06-23 12:18:53,028  INFO  [web.site.EditionInterceptor] [http-apr-8080-exec-2] Successfully retrieved license information from Alfresco.
2016-06-23 12:20:00,159  INFO  [security.sync.ChainingUserRegistrySynchronizer] [DefaultScheduler_Worker-3] Synchronizing users and groups with user registry 'ldap-ad1'
org.alfresco.repo.security.authentication.AuthenticationException: 05230011 Не удалось связаться с ldap://domaincontroller.company.com:389. Причина: javax.naming.CommunicationException, domaincontroller.company.com:389, java.net.UnknownHostException, domaincontroller.company.com
	at org.alfresco.repo.security.authentication.ldap.LDAPInitialDirContextFactoryImpl.buildInitialDirContext(LDAPInitialDirContextFactoryImpl.java:229)
	at org.alfresco.repo.security.authentication.ldap.LDAPInitialDirContextFactoryImpl.getDefaultIntialDirContext(LDAPInitialDirContextFactoryImpl.java:159)
	at org.alfresco.repo.security.authentication.ldap.LDAPInitialDirContextFactoryImpl.getDefaultIntialDirContext(LDAPInitialDirContextFactoryImpl.java:140)
	at org.alfresco.repo.security.sync.ldap.LDAPUserRegistry$3.(LDAPUserRegistry.java:689)
	at org.alfresco.repo.security.sync.ldap.LDAPUserRegistry.getGroups(LDAPUserRegistry.java:686)
	at org.alfresco.repo.security.sync.ChainingUserRegistrySynchronizer.syncWithPlugin(ChainingUserRegistrySynchronizer.java:954)
	at org.alfresco.repo.security.sync.ChainingUserRegistrySynchronizer.synchronizeInternal(ChainingUserRegistrySynchronizer.java:700)
	at org.alfresco.repo.security.sync.ChainingUserRegistrySynchronizer.synchronize(ChainingUserRegistrySynchronizer.java:429)
	at org.alfresco.repo.security.sync.UserRegistrySynchronizerJob$1.doWork(UserRegistrySynchronizerJob.java:46)
	at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:548)
	at org.alfresco.repo.security.sync.UserRegistrySynchronizerJob.execute(UserRegistrySynchronizerJob.java:42)
	at org.quartz.core.JobRunShell.run(JobRunShell.java:216)
	at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:563)

После выполнения провесса авторизации и входа на вебсервер Альфреско, журнал логов дополнятеся следующими записями.

1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
Run GetAvailableActionsWebScript
Run SearchPostWebScript
RequestContent: {"objectType": "btl-utils:workScheduleDictionary", "qualifyStr": ""}
Query: TYPE:"btl-utils:workScheduleDictionary" 
Query results count: 6
Run SearchGetWebScript
Query: TYPE:"btl-prStatus:ProjectStatus-content" 
Query results count: 5
Run SearchPostWebScript
RequestContent: {"objectType": "btl-project:projectDataType", "qualifyStr": ""}
Query: TYPE:"btl-project:projectDataType" 
Query results count: 0
2016-06-23 12:30:00,286  INFO  [security.sync.ChainingUserRegistrySynchronizer] [DefaultScheduler_Worker-1] Synchronizing users and groups with user registry 'ldap-ad1'
2016-06-23 12:30:00,319  INFO  [security.sync.ChainingUserRegistrySynchronizer] [DefaultScheduler_Worker-1] Retrieving all groups from user registry 'ldap-ad1'
	...
	at org.alfresco.repo.security.authentication.ldap.LDAPInitialDirContextFactoryImpl.buildInitialDirContext(LDAPInitialDirContextFactoryImpl.java:229)
	at org.alfresco.repo.security.authentication.ldap.LDAPInitialDirContextFactoryImpl.getDefaultIntialDirContext(LDAPInitialDirContextFactoryImpl.java:159)
	at org.alfresco.repo.security.authentication.ldap.LDAPInitialDirContextFactoryImpl.getDefaultIntialDirContext(LDAPInitialDirContextFactoryImpl.java:140)
	at org.alfresco.repo.security.sync.ldap.LDAPUserRegistry$3.(LDAPUserRegistry.java:689)
	at org.alfresco.repo.security.sync.ldap.LDAPUserRegistry.getGroups(LDAPUserRegistry.java:686)
	at org.alfresco.repo.security.sync.ChainingUserRegistrySynchronizer.syncWithPlugin(ChainingUserRegistrySynchronizer.java:954)
	at org.alfresco.repo.security.sync.ChainingUserRegistrySynchronizer.synchronizeInternal(ChainingUserRegistrySynchronizer.java:700)
	at org.alfresco.repo.security.sync.ChainingUserRegistrySynchronizer.synchronize(ChainingUserRegistrySynchronizer.java:429)
	at org.alfresco.repo.security.sync.UserRegistrySynchronizerJob$1.doWork(UserRegistrySynchronizerJob.java:46)
	at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:548)
	at org.alfresco.repo.security.sync.UserRegistrySynchronizerJob.execute(UserRegistrySynchronizerJob.java:42)
	at org.quartz.core.JobRunShell.run(JobRunShell.java:216)
	... 1 more
Run ListAllProjects
Query: TYPE:"{http://www.****.ru/model/project/1.0}projectDataType" 
Query results count: 0

  • localhost_access_log.2016-06-23.txt

В файл записываются запросы, которые генерирует веб-браузер при работе с веб-сервером Apache Tomcat. В файле фиксируются все запросы, не только те которые происходят при непосредственном взаимодействии пользователя с системой, но и другие запросы которые выполняются например по таймингу, например обновление формы страницы, дозагрузка каких-то элементов, т.е. отрабатываются скрипты которые посылают запросы (GET или POST) и все они фиксируются в этом журнале.

Пример файла localhost_access_log.2016-06-23.txt после загрузки системы:

1
2
3
4
127.0.0.1 - CN=Alfresco Repository Client, OU=Unknown, O=Alfresco Software Ltd., L=Maidenhead, ST=UK, C=GB [23/Jun/2016:11:59:22 +0300] "POST /alfresco/service/api/solr/modelsdiff HTTP/1.1" 200 473
127.0.0.1 - CN=Alfresco Repository Client, OU=Unknown, O=Alfresco Software Ltd., L=Maidenhead, ST=UK, C=GB [23/Jun/2016:11:59:22 +0300] "GET /alfresco/service/api/solr/model?modelQName=%7Bhttp%3A%2F%2Fwww.btlab.ru%2Fmodel%2FnegotiationWF%2F1.0%7DnegotiationWFModel HTTP$
127.0.0.1 - CN=Alfresco Repository Client, OU=Unknown, O=Alfresco Software Ltd., L=Maidenhead, ST=UK, C=GB [23/Jun/2016:11:59:22 +0300] "GET /alfresco/service/api/solr/model?modelQName=%7Bhttp%3A%2F%2Fwww.btlab.ru%2Fmodel%2FtaskWF%2F1.0%7DtaskModelwf HTTP/1.1" 200 10553
127.0.0.1 - CN=Alfresco Repository Client, OU=Unknown, O=Alfresco Software Ltd., L=Maidenhead, ST=UK, C=GB [23/Jun/2016:11:59:22 +0300] "POST /alfresco/service/api/solr/modelsdiff HTTP/1.1" 200 473

23rd Июн 2016
Теги:
Загрузка Все права защищены © 2016 ИТ-Инженер (Краснодар)
 
把手拿回