SonarQube 5.1.1 Read Timed out Error occurs while loading project repositories.

991 views
Skip to first unread message

vijay...@gmail.com

unread,
Jul 15, 2015, 1:13:52 PM7/15/15
to sona...@googlegroups.com
While using the sonar runner to analyze the multi module project I frequently get the “Read Timed Out” error. I have Jenkins configured with sonarqube 5.1.1. Our project is kind of web project, we have a couple of sonar plugins for the analysis like web, css, java, findbugs, js, xml and jacoco. If I load the given link "http://172.21.145.84:9000/batch/project?key=webportal&preview=false" from browser it works, but sometimes it takes quite long to load the response data. Can anyone please give me any suggestion regarding this failure? I have shared the sonar log below.



//Console Log

SonarQube Runner 2.4
Java 1.7.0_45 Oracle Corporation (64-bit)
Windows 7 6.1 amd64
INFO: Runner configuration file: C:\sonar-runner-2.4\conf\sonar-runner.properties
INFO: Project configuration file: D:\Jenkins\jobs\Webportal_2.0_Drop1Branch\workspace\DataModel..\ucfed_webportal_plugin_att\sonar-project.properties
INFO: Default locale: "en_US", source code encoding: "UTF-8"
INFO: Work directory: D:\Jenkins\jobs\Webportal_2.0_Drop1Branch\workspace\DataModel..\ucfed_webportal_plugin_att.sonar
INFO: SonarQube Server 5.1.1
17:11:29.762 INFO - Load global repositories
17:11:30.027 INFO - Load global repositories (done) | time=281ms
17:11:30.042 INFO - Server id: 20150707155744
17:11:30.042 INFO - User cache: C:\Users\Administrator.sonar\cache
17:11:30.042 INFO - Install plugins
17:11:30.479 INFO - Install JDBC driver
17:11:30.495 INFO - Create JDBC datasource for jdbc:h2:tcp://localhost/sonar
17:11:32.538 INFO - Initializing Hibernate
17:11:35.409 INFO - Load project repositories
INFO: ------------------------------------------------------------------------
INFO: EXECUTION FAILURE
INFO: ------------------------------------------------------------------------
Total time: 26.832s
Final Memory: 46M/303M
INFO: ------------------------------------------------------------------------
ERROR: Error during Sonar runner execution
ERROR: Unable to execute Sonar
ERROR: Caused by: Unable to request: /batch/project?key=webportal &preview=false
ERROR: Caused by: Read timed out
ERROR:
ERROR: To see the full stack trace of the errors, re-run SonarQube Runner with the -e switch.
ERROR: Re-run SonarQube Runner using the -X switch to enable full debug logging.
Build step 'Invoke Standalone Sonar Analysis' marked build as failure Extended Email Publisher is currently disabled in project settings Finished: FAILURE

Julien Lancelot

unread,
Jul 16, 2015, 3:59:07 AM7/16/15
to vijay...@gmail.com, sona...@googlegroups.com
Hi,

This problem seems to relate to http://jira.sonarsource.com/browse/SONAR-6604.
Just to be sure, can you do the followings : 
  • In conf/sonar.properties, activate property sonar.log.level=TRACE
  • Relaunch the server
  • Run an analysis of your project
  • Send us last server logs (starting from DEBUG web[http] GET /batch/global) from file logs/sonar.log
Thanks.

Regards,

Julien LANCELOT | SonarSource

--
You received this message because you are subscribed to the Google Groups "SonarQube" group.
To unsubscribe from this group and stop receiving emails from it, send an email to sonarqube+...@googlegroups.com.
To view this discussion on the web visit https://groups.google.com/d/msgid/sonarqube/60f1bc02-4fda-47ee-9b76-4b245bbc55c1%40googlegroups.com.
For more options, visit https://groups.google.com/d/optout.

Vijay Raj

unread,
Jul 22, 2015, 3:54:06 AM7/22/15
to SonarQube
Sorry for late response, actually I was not able to reproduce the error after doing some performance tweak in the PC on which I’m running the analysis. But luckily I tackled that scenario today. Please have a look at this log.



2015.07.22 11:47:54 DEBUG web[http] GET /batch/global | time=78ms
2015.07.22 11:47:54 DEBUG web[http] GET /deploy/plugins/index.txt | time=0ms
2015.07.22 11:47:56 DEBUG web[http] GET /deploy/jdbc-driver.txt | time=0ms
2015.07.22 11:48:00 DEBUG web[http] GET /api/server | time=31ms
2015.07.22 11:48:01 TRACE web[sql] time=0ms | sql=select ar.id from analysis_reports ar where ar.report_status=? and not exists( select 1 from analysis_reports ar2 where ar.project_key = ar2.project_key and ar2.report_status=? ) order by ar.created_at asc, ar.id asc
2015.07.22 11:48:02 TRACE web[sql] time=0ms | sql=SELECT gr.role FROM group_roles gr WHERE gr.resource_id is null and gr.group_id is null
2015.07.22 11:48:02 TRACE web[sql] time=15ms | sql=SELECT p.id, p.uuid as uuid, p.project_uuid as projectUuid, p.module_uuid as moduleUuid, p.module_uuid_path as moduleUuidPath, p.kee as kee, p.deprecated_kee as deprecatedKey, p.name as name, p.long_name as longName, p.qualifier as qualifier, p.scope as scope, p.language as language, p.root_id as parentProjectId, p.path as path, p.enabled as enabled, p.copy_resource_id as copyResourceId, p.authorization_updated_at as authorizationUpdatedAt, p.created_at as createdAt FROM projects p WHERE p.kee=?
2015.07.22 11:48:02 TRACE web[sql] time=32ms | sql=SELECT p.id, p.uuid as uuid, p.project_uuid as projectUuid, p.module_uuid as moduleUuid, p.module_uuid_path as moduleUuidPath, p.kee as kee, p.deprecated_kee as deprecatedKey, p.name as name, p.long_name as longName, p.qualifier as qualifier, p.scope as scope, p.language as language, p.root_id as parentProjectId, p.path as path, p.enabled as enabled, p.copy_resource_id as copyResourceId, p.authorization_updated_at as authorizationUpdatedAt, p.created_at as createdAt FROM projects p INNER JOIN projects root_project ON root_project.uuid = p.project_uuid AND root_project.enabled = true INNER JOIN projects module ON module.project_uuid = root_project.uuid AND module.uuid = ? AND module.scope='PRJ' AND module.enabled = true WHERE p.enabled = true AND p.scope = ? AND p.module_uuid_path LIKE module.module_uuid_path || '%'
2015.07.22 11:48:02 TRACE web[sql] time=0ms | sql=SELECT prop.id as id, prop.prop_key as "key", prop.text_value as value, prop.resource_id as resourceId, prop.user_id as userId FROM properties prop INNER JOIN (SELECT p.id FROM projects p INNER JOIN projects root_project ON root_project.uuid = p.project_uuid AND root_project.enabled = true INNER JOIN projects module ON module.project_uuid = root_project.uuid AND module.uuid = ? AND module.scope='PRJ' AND module.enabled = true WHERE p.enabled = true AND p.scope = ? AND p.module_uuid_path LIKE module.module_uuid_path || '%' ) modules on modules.id=prop.resource_id WHERE prop.user_id IS NULL
2015.07.22 11:48:03 TRACE web[sql] time=280ms | sql=SELECT p.uuid, p.path, p.module_uuid as moduleUuid, fs.src_hash as srcHash FROM projects p INNER JOIN file_sources fs ON fs.file_uuid=p.uuid INNER JOIN projects root_project ON root_project.uuid = p.project_uuid AND root_project.enabled = true INNER JOIN projects module ON module.project_uuid = root_project.uuid AND module.uuid = ? AND module.scope='PRJ' AND module.enabled = true WHERE p.enabled = true AND p.scope = ? AND p.module_uuid_path LIKE module.module_uuid_path || '%'
2015.07.22 11:48:03 TRACE web[sql] time=0ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p, projects project INNER JOIN properties prop ON prop.resource_id=project.id WHERE p.language=? AND prop.prop_key LIKE ? AND prop.text_value LIKE p.name AND project.kee=?
2015.07.22 11:48:03 TRACE web[sql] time=16ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p INNER JOIN properties prop ON prop.prop_key=? AND prop.resource_id IS NULL AND prop.text_value LIKE p.name AND p.language=?
2015.07.22 11:48:03 TRACE web[sql] time=0ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p, projects project INNER JOIN properties prop ON prop.resource_id=project.id WHERE p.language=? AND prop.prop_key LIKE ? AND prop.text_value LIKE p.name AND project.kee=?
2015.07.22 11:48:03 TRACE web[sql] time=0ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p INNER JOIN properties prop ON prop.prop_key=? AND prop.resource_id IS NULL AND prop.text_value LIKE p.name AND p.language=?
2015.07.22 11:48:03 TRACE web[sql] time=0ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p, projects project INNER JOIN properties prop ON prop.resource_id=project.id WHERE p.language=? AND prop.prop_key LIKE ? AND prop.text_value LIKE p.name AND project.kee=?
2015.07.22 11:48:03 TRACE web[sql] time=0ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p INNER JOIN properties prop ON prop.prop_key=? AND prop.resource_id IS NULL AND prop.text_value LIKE p.name AND p.language=?
2015.07.22 11:48:03 TRACE web[sql] time=0ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p, projects project INNER JOIN properties prop ON prop.resource_id=project.id WHERE p.language=? AND prop.prop_key LIKE ? AND prop.text_value LIKE p.name AND project.kee=?
2015.07.22 11:48:03 TRACE web[sql] time=0ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p, projects project INNER JOIN properties prop ON prop.resource_id=project.id WHERE p.language=? AND prop.prop_key LIKE ? AND prop.text_value LIKE p.name AND project.kee=?
2015.07.22 11:48:03 TRACE web[sql] time=0ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p INNER JOIN properties prop ON prop.prop_key=? AND prop.resource_id IS NULL AND prop.text_value LIKE p.name AND p.language=?
2015.07.22 11:48:03 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["js"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=140ms
2015.07.22 11:48:03 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@6fcd0aa8' | time=31ms
2015.07.22 11:48:03 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@7e76d4ef' | time=47ms
2015.07.22 11:48:03 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"js-sonar-way-68531"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=47ms
2015.07.22 11:48:03 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2b95d7c6' | time=31ms
2015.07.22 11:48:03 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@7a891e84' | time=0ms
2015.07.22 11:48:03 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["java"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=0ms
2015.07.22 11:48:03 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@4032aaf5' | time=16ms
2015.07.22 11:48:03 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@3e75dbc0' | time=62ms
2015.07.22 11:48:03 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2bae3a49' | time=94ms
2015.07.22 11:48:03 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@3b74a506' | time=78ms
2015.07.22 11:48:03 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@66fe4b68' | time=78ms
2015.07.22 11:49:28 INFO web[o.s.s.es.BulkIndexer] 561 requests processed (0 items/sec)
2015.07.22 11:49:28 INFO web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.07.22 11:49:28 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@6e145bac' | time=31ms
2015.07.22 11:49:28 TRACE web[sql] time=0ms | sql=select id, data from notifications order by id asc limit ?
2015.07.22 11:49:28 TRACE web[sql] time=0ms | sql=select ar.id from analysis_reports ar where ar.report_status=? and not exists( select 1 from analysis_reports ar2 where ar.project_key = ar2.project_key and ar2.report_status=? ) order by ar.created_at asc, ar.id asc
2015.07.22 11:49:28 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@26b836f6' | time=172ms
2015.07.22 11:49:28 TRACE web[sql] time=0ms | sql=select ar.id from analysis_reports ar where ar.report_status=? and not exists( select 1 from analysis_reports ar2 where ar.project_key = ar2.project_key and ar2.report_status=? ) order by ar.created_at asc, ar.id asc
2015.07.22 11:49:28 TRACE web[sql] time=0ms | sql=select ar.id from analysis_reports ar where ar.report_status=? and not exists( select 1 from analysis_reports ar2 where ar.project_key = ar2.project_key and ar2.report_status=? ) order by ar.created_at asc, ar.id asc
2015.07.22 11:49:28 TRACE web[sql] time=0ms | sql=select ar.id from analysis_reports ar where ar.report_status=? and not exists( select 1 from analysis_reports ar2 where ar.project_key = ar2.project_key and ar2.report_status=? ) order by ar.created_at asc, ar.id asc
2015.07.22 11:49:28 TRACE web[sql] time=0ms | sql=select ar.id from analysis_reports ar where ar.report_status=? and not exists( select 1 from analysis_reports ar2 where ar.project_key = ar2.project_key and ar2.report_status=? ) order by ar.created_at asc, ar.id asc
2015.07.22 11:49:28 TRACE web[sql] time=0ms | sql=select ar.idid





I hope the given log is sufficient.

Regards,
Vijay Raj R
Message has been deleted

Julien Lancelot

unread,
Jul 23, 2015, 8:00:23 AM7/23/15
to SonarQube
Hi Vijay,

Unfortunately, the first log you gave is not enough to understand your issue.
Could you please send log starting from DEBUG web[http] GET /batch/global and ending at  DEBUG web[http] GET /batch/project?... ?

Concerning the second log you've send, I'm not sure it's related to the same issue. Also, I don't understand which plugin have you uninstalled ?

Regards,

Julien LANCELOT | SonarSource

On 22 July 2015 at 10:03, Vijay Raj <vijay...@gmail.com> wrote:
Also have a look at this log, got similar error while accessing the “/batch/..” link using http request plugin. I call this plugin just before invoking the sonar analysis, to make sure the link is working properly or not. Now I have removed the plugin to get the failure during sonar analysis process, which I have posted above.




ils$$call_53C2561E82BE13BC6589E138533EBF83460FC237642081854.__file__(file:/C:/sonarqube-5.1.1/lib/server/jruby-rack-1.1.13.2.jar!/rack/adapter/rails.rb) [jruby-complete-1.7.9.jar:na]
        at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:181) [jruby-complete-1.7.9.jar:na]
        at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) [jruby-complete-1.7.9.jar:na]
        at rubyjit.Rack::Handler::Servlet$$call_8E7740794C2C9B2AA48C4FF36A45D7CC91DA0560642081854.__file__(file:/C:/sonarqube-5.1.1/lib/server/jruby-rack-1.1.13.2.jar!/rack/handler/servlet.rb:22) [jruby-complete-1.7.9.jar:na]
        at rubyjit.Rack::Handler::Servlet$$call_8E7740794C2C9B2AA48C4FF36A45D7CC91DA0560642081854.__file__(file:/C:/sonarqube-5.1.1/lib/server/jruby-rack-1.1.13.2.jar!/rack/handler/servlet.rb) [jruby-complete-1.7.9.jar:na]
        at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:181) [jruby-complete-1.7.9.jar:na]
        at org.jruby.RubyClass.finvoke(RubyClass.java:690) [jruby-complete-1.7.9.jar:na]
        at org.jruby.runtime.Helpers.invoke(Helpers.java:498) [jruby-complete-1.7.9.jar:na]
        at org.jruby.RubyBasicObject.callMethod(RubyBasicObject.java:363) [jruby-complete-1.7.9.jar:na]
        at org.jruby.javasupport.JavaEmbedUtils$1.callMethod(JavaEmbedUtils.java:143) [jruby-complete-1.7.9.jar:na]
        at org.jruby.rack.DefaultRackApplication.call(DefaultRackApplication.java:64) [jruby-rack-1.1.13.2.jar:na]
        at org.jruby.rack.AbstractRackDispatcher.process(AbstractRackDispatcher.java:33) [jruby-rack-1.1.13.2.jar:na]
        at org.jruby.rack.AbstractFilter.doFilter(AbstractFilter.java:66) [jruby-rack-1.1.13.2.jar:na]
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.sonar.server.platform.SecurityServletFilter.doFilter(SecurityServletFilter.java:41) [sonar-server-5.1.1.jar:na]
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.sonar.server.platform.MasterServletFilter.doFilter(MasterServletFilter.java:80) [sonar-server-5.1.1.jar:na]
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.sonar.server.user.UserSessionFilter.doFilter(UserSessionFilter.java:42) [sonar-server-5.1.1.jar:na]
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.sonar.server.user.DatabaseSessionFilter.doFilter(DatabaseSessionFilter.java:36) [sonar-server-5.1.1.jar:na]
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.sonar.server.platform.ProfilingFilter.doFilter(ProfilingFilter.java:85) [sonar-server-5.1.1.jar:na]
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:219) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:106) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:142) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:79) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at ch.qos.logback.access.tomcat.LogbackValve.invoke(LogbackValve.java:189) [logback-access-1.1.2.jar:na]
        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:88) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:516) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1086) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:659) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:223) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1558) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:1515) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_45]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_45]
        at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) [tomcat-embed-core-8.0.18.jar:8.0.18]
        at java.lang.Thread.run(Thread.java:744) [na:1.7.0_45]
Caused by: org.elasticsearch.client.transport.NoNodeAvailableException: None of the configured nodes are available: []
        at org.elasticsearch.client.transport.TransportClientNodesService.ensureNodesAreAvailable(TransportClientNodesService.java:278) ~[elasticsearch-1.4.4.jar:na]
        at org.elasticsearch.client.transport.TransportClientNodesService.execute(TransportClientNodesService.java:197) ~[elasticsearch-1.4.4.jar:na]
        at org.elasticsearch.client.transport.support.InternalTransportClient.execute(InternalTransportClient.java:106) ~[elasticsearch-1.4.4.jar:na]
        at org.elasticsearch.client.support.AbstractClient.searchScroll(AbstractClient.java:349) ~[elasticsearch-1.4.4.jar:na]
        at org.elasticsearch.client.transport.TransportClient.searchScroll(TransportClient.java:434) ~[elasticsearch-1.4.4.jar:na]
        at org.elasticsearch.action.search.SearchScrollRequestBuilder.doExecute(SearchScrollRequestBuilder.java:83) ~[elasticsearch-1.4.4.jar:na]
        at org.elasticsearch.action.ActionRequestBuilder.execute(ActionRequestBuilder.java:91) ~[elasticsearch-1.4.4.jar:na]
        at org.elasticsearch.action.ActionRequestBuilder.execute(ActionRequestBuilder.java:65) ~[elasticsearch-1.4.4.jar:na]
        at org.sonar.server.es.request.ProxySearchScrollRequestBuilder.get(ProxySearchScrollRequestBuilder.java:41) ~[sonar-server-5.1.1.jar:na]
        ... 256 common frames omitted
2015.07.21 05:29:06 DEBUG web[http] GET /batch/project?key=ucfed_webportal_plugin_att&preview=false | time=17690ms
2015.07.21 05:29:11 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.07.21 05:29:21 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.07.21 05:29:27 INFO  web[o.s.s.es.BulkIndexer] 561 requests processed (0 items/sec)
2015.07.21 05:29:31 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.07.21 05:29:41 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.07.21 05:29:50 TRACE web[sql] time=0ms | sql=select id, data    from notifications    order by id asc    limit ?
2015.07.21 05:29:51 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.07.21 05:30:01 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.07.21 05:30:11 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.07.21 05:30:21 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.07.21 05:30:27 INFO  web[o.s.s.es.BulkIndexer] 561 requests processed (0 items/sec)




Regards,
Vijay Raj R


--
You received this message because you are subscribed to the Google Groups "SonarQube" group.
To unsubscribe from this group and stop receiving emails from it, send an email to sonarqube+...@googlegroups.com.

Vijay Raj

unread,
Jul 23, 2015, 11:03:10 PM7/23/15
to SonarQube, julien....@sonarsource.com
Hi Julien,

Please ignore the second log it was not copied completely, that's why i have deleted it from the group post. Have a look at the below log its quite different from the first one. This time it shows few errors in between the queries.



2015.07.23 16:47:59 DEBUG web[http] GET /batch/global | time=795ms
2015.07.23 16:48:00 DEBUG web[http] GET /deploy/plugins/index.txt | time=0ms
2015.07.23 16:48:01 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.07.23 16:48:01 DEBUG web[http] GET /deploy/jdbc-driver.txt | time=0ms
2015.07.23 16:48:04 DEBUG web[http] GET /api/server | time=47ms
2015.07.23 16:48:07 TRACE web[sql] time=0ms | sql=SELECT gr.role        FROM group_roles gr         WHERE  gr.resource_id is null          and gr.group_id is null
2015.07.23 16:48:08 TRACE web[sql] time=31ms | sql=SELECT      p.id,    p.uuid as uuid,    p.project_uuid as projectUuid,    p.module_uuid as moduleUuid,    p.module_uuid_path as moduleUuidPath,    p.kee as kee,    p.deprecated_kee as deprecatedKey,    p.name as name,    p.long_name as longName,    p.qualifier as qualifier,    p.scope as scope,    p.language as language,    p.root_id as parentProjectId,    p.path as path,    p.enabled as enabled,    p.copy_resource_id as copyResourceId,    p.authorization_updated_at as authorizationUpdatedAt,    p.created_at as createdAt       FROM projects p     WHERE  p.kee=?
2015.07.23 16:48:08 TRACE web[sql] time=359ms | sql=SELECT      p.id,    p.uuid as uuid,    p.project_uuid as projectUuid,    p.module_uuid as moduleUuid,    p.module_uuid_path as moduleUuidPath,    p.kee as kee,    p.deprecated_kee as deprecatedKey,    p.name as name,    p.long_name as longName,    p.qualifier as qualifier,    p.scope as scope,    p.language as language,    p.root_id as parentProjectId,    p.path as path,    p.enabled as enabled,    p.copy_resource_id as copyResourceId,    p.authorization_updated_at as authorizationUpdatedAt,    p.created_at as createdAt       FROM projects p         INNER JOIN projects root_project ON root_project.uuid = p.project_uuid AND root_project.enabled = true    INNER JOIN projects module ON module.project_uuid = root_project.uuid AND module.uuid = ? AND module.scope='PRJ' AND module.enabled = true     WHERE p.enabled = true            AND p.scope = ?      AND                p.module_uuid_path LIKE module.module_uuid_path || '%'
2015.07.23 16:48:08 TRACE web[sql] time=0ms | sql=SELECT prop.id as id, prop.prop_key as "key", prop.text_value as value, prop.resource_id as resourceId, prop.user_id as userId    FROM properties prop    INNER JOIN (SELECT p.id FROM projects p     INNER JOIN projects root_project ON root_project.uuid = p.project_uuid AND root_project.enabled = true    INNER JOIN projects module ON module.project_uuid = root_project.uuid AND module.uuid = ? AND module.scope='PRJ' AND module.enabled = true     WHERE p.enabled = true            AND p.scope = ?      AND                p.module_uuid_path LIKE module.module_uuid_path || '%'    ) modules on modules.id=prop.resource_id    WHERE prop.user_id IS NULL
2015.07.23 16:48:08 TRACE web[sql] time=218ms | sql=SELECT p.uuid, p.path, p.module_uuid as moduleUuid, fs.src_hash as srcHash    FROM projects p    INNER JOIN file_sources fs ON fs.file_uuid=p.uuid         INNER JOIN projects root_project ON root_project.uuid = p.project_uuid AND root_project.enabled = true    INNER JOIN projects module ON module.project_uuid = root_project.uuid AND module.uuid = ? AND module.scope='PRJ' AND module.enabled = true     WHERE p.enabled = true            AND p.scope = ?      AND                p.module_uuid_path LIKE module.module_uuid_path || '%'
2015.07.23 16:48:08 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p, projects project    INNER JOIN properties prop ON prop.resource_id=project.id     WHERE  p.language=?      AND prop.prop_key LIKE ?      AND prop.text_value LIKE p.name      AND project.kee=?
2015.07.23 16:48:08 TRACE web[sql] time=47ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p    INNER JOIN properties prop ON prop.prop_key=?      AND prop.resource_id IS NULL      AND prop.text_value LIKE p.name      AND p.language=?
2015.07.23 16:48:08 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p, projects project    INNER JOIN properties prop ON prop.resource_id=project.id     WHERE  p.language=?      AND prop.prop_key LIKE ?      AND prop.text_value LIKE p.name      AND project.kee=?
2015.07.23 16:48:08 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p    INNER JOIN properties prop ON prop.prop_key=?      AND prop.resource_id IS NULL      AND prop.text_value LIKE p.name      AND p.language=?
2015.07.23 16:48:08 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p, projects project    INNER JOIN properties prop ON prop.resource_id=project.id     WHERE  p.language=?      AND prop.prop_key LIKE ?      AND prop.text_value LIKE p.name      AND project.kee=?
2015.07.23 16:48:08 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p    INNER JOIN properties prop ON prop.prop_key=?      AND prop.resource_id IS NULL      AND prop.text_value LIKE p.name      AND p.language=?
2015.07.23 16:48:08 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p, projects project    INNER JOIN properties prop ON prop.resource_id=project.id     WHERE  p.language=?      AND prop.prop_key LIKE ?      AND prop.text_value LIKE p.name      AND project.kee=?
2015.07.23 16:48:08 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p, projects project    INNER JOIN properties prop ON prop.resource_id=project.id     WHERE  p.language=?      AND prop.prop_key LIKE ?      AND prop.text_value LIKE p.name      AND project.kee=?
2015.07.23 16:48:08 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p    INNER JOIN properties prop ON prop.prop_key=?      AND prop.resource_id IS NULL      AND prop.text_value LIKE p.name      AND p.language=?
2015.07.23 16:48:08 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["js"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=202ms
2015.07.23 16:48:09 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@5e0cd03' | time=172ms
2015.07.23 16:48:09 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@69b382f8' | time=62ms
2015.07.23 16:48:09 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"js-sonar-way-68531"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=78ms
2015.07.23 16:48:09 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@85b9dff' | time=78ms
2015.07.23 16:48:09 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@36e1d4a2' | time=0ms
2015.07.23 16:48:09 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["java"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=47ms
2015.07.23 16:48:09 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@244ef3d4' | time=187ms
2015.07.23 16:48:09 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@3b69f5eb' | time=78ms
2015.07.23 16:48:09 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@299035c6' | time=32ms
2015.07.23 16:48:09 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@3aba6f7b' | time=78ms
2015.07.23 16:48:09 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@25ed125c' | time=93ms
2015.07.23 16:48:09 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@6f506977' | time=47ms
2015.07.23 16:48:10 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@83c3cb9' | time=78ms
2015.07.23 16:48:11 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.07.23 16:48:14 INFO  web[o.s.s.es.BulkIndexer] 561 requests processed (0 items/sec)
2015.07.23 16:48:14 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.07.23 16:48:19 INFO  web[o.e.client.transport] [sonar-1436876101599] failed to get node info for [#transport#-1][GURPC16][inet[/127.0.0.1:49609]], disconnecting...
org.elasticsearch.transport.ReceiveTimeoutTransportException: [][inet[/127.0.0.1:49609]][cluster:monitor/nodes/info] request_id [157760] timed out after [5008ms]
    at org.elasticsearch.transport.TransportService$TimeoutHandler.run(TransportService.java:366) ~[elasticsearch-1.4.4.jar:na]

    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_45]
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_45]
    at java.lang.Thread.run(Thread.java:744) [na:1.7.0_45]
2015.07.23 16:48:21 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.07.23 16:48:21 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@6282087b' | time=11435ms
2015.07.23 16:48:21 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@46faf5c3' | time=187ms
2015.07.23 16:48:23 WARN   es[o.e.monitor.jvm]  [sonar-1436876101599] [gc][young][761895][161] duration [9.5s], collections [1]/[11.6s], total [9.5s]/[11.8m], memory [84.4mb]->[23.6mb]/[990.7mb], all_pools {[young] [61.9mb]->[542.3kb]/[266.2mb]}{[survivor] [905.8kb]->[1.4mb]/[33.2mb]}{[old] [21.6mb]->[21.6mb]/[691.2mb]}
2015.07.23 16:48:26 ERROR web[o.s.s.w.WebServiceEngine] Fail to process request http://172.21.145.84:9000/batch/project?key=ucfed_webportal&amp;preview=false
java.lang.IllegalStateException: Fail to execute ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@46faf5c3'
    at org.sonar.server.es.request.ProxySearchScrollRequestBuilder.get(ProxySearchScrollRequestBuilder.java:43) ~[sonar-server-5.1.1.jar:na]
    at org.sonar.server.es.request.ProxySearchScrollRequestBuilder.get(ProxySearchScrollRequestBuilder.java:31) ~[sonar-server-5.1.1.jar:na]
    at org.sonar.server.search.BaseIndex$1.hasNext(BaseIndex.java:123) ~[sonar-server-5.1.1.jar:na]
    at com.google.common.collect.Maps.uniqueIndex(Maps.java:567) ~[guava-10.0.1.jar:na]
    at org.sonar.server.batch.ProjectRepositoryLoader.ruleByRuleKey(ProjectRepositoryLoader.java:263) ~[sonar-server-5.1.1.jar:na]
    at org.sonar.server.batch.ProjectRepositoryLoader.addActiveRules(ProjectRepositoryLoader.java:232) ~[sonar-server-5.1.1.jar:na]
    at org.sonar.server.batch.ProjectRepositoryLoader.load(ProjectRepositoryLoader.java:123) ~[sonar-server-5.1.1.jar:na]
    at org.sonar.server.batch.ProjectRepositoryAction.handle(ProjectRepositoryAction.java:70) ~[sonar-server-5.1.1.jar:na]
    at org.sonar.server.ws.WebServiceEngine.execute(WebServiceEngine.java:84) ~[sonar-server-5.1.1.jar:na]
    at sun.reflect.GeneratedMethodAccessor231.invoke(Unknown Source) ~[na:na]
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.7.0_45]
    at java.lang.reflect.Method.invoke(Method.java:606) ~[na:1.7.0_45]
    at org.jruby.javasupport.JavaMethod.invokeDirectWithExceptionHandling(JavaMethod.java:425) [jruby-complete-1.7.9.jar:na]
    at org.jruby.javasupport.JavaMethod.invokeDirect(JavaMethod.java:292) [jruby-complete-1.7.9.jar:na]
    at org.jruby.java.invokers.InstanceMethodInvoker.call(InstanceMethodInvoker.java:44) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:70) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Api::JavaWsController$$index_18AF0797CD66B4E310384A14E2B9EE369902E67F642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/app/controllers/api/java_ws_controller.rb:30) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Api::JavaWsController$$index_18AF0797CD66B4E310384A14E2B9EE369902E67F642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/app/controllers/api/java_ws_controller.rb) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:161) [jruby-complete-1.7.9.jar:na]
    at org.jruby.RubyClass.finvoke(RubyClass.java:527) [jruby-complete-1.7.9.jar:na]
    at org.jruby.RubyBasicObject.send(RubyBasicObject.java:2590) [jruby-complete-1.7.9.jar:na]
    at org.jruby.RubyKernel.send(RubyKernel.java:2223) [jruby-complete-1.7.9.jar:na]
    at org.jruby.RubyKernel$INVOKER$s$send.call(RubyKernel$INVOKER$s$send.gen) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JavaMethod$JavaMethodOneOrNBlock.call(JavaMethod.java:350) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.cacheAndCall(CachingCallSite.java:326) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:170) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Base$$perform_action_DA0FC0A901DD1257EC969DB0C482DD8514B8F04B642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/base.rb:1333) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Base$$perform_action_DA0FC0A901DD1257EC969DB0C482DD8514B8F04B642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/base.rb) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:141) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.DefaultMethod.call(DefaultMethod.java:185) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.AliasMethod.call(AliasMethod.java:56) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.cacheAndCall(CachingCallSite.java:306) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:136) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Filters::InstanceMethods$$call_filters_A7E1D3FFBF56E3CB7C0EBA850688505249A05AE9642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/filters.rb:617) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Filters::InstanceMethods$$call_filters_A7E1D3FFBF56E3CB7C0EBA850688505249A05AE9642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/filters.rb) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:261) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.cacheAndCall(CachingCallSite.java:366) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:238) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Filters::InstanceMethods$$perform_action_with_filters_4C7C67B4A5564FF5DC76344AF2372660F608839C642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/filters.rb:610) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Filters::InstanceMethods$$perform_action_with_filters_4C7C67B4A5564FF5DC76344AF2372660F608839C642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/filters.rb) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:141) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.DefaultMethod.call(DefaultMethod.java:185) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.AliasMethod.call(AliasMethod.java:56) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.AliasMethod.call(AliasMethod.java:56) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.cacheAndCall(CachingCallSite.java:306) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:136) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Benchmarking$$perform_action_with_benchmark_303676EADF39D1B66CBA6FF2131B0D6C74E04345642081854.block_0$RUBY$__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/benchmarking.rb:68) [jruby-complete-1.7.9.jar:na]
    at rubyjit$ActionController::Benchmarking$$perform_action_with_benchmark_303676EADF39D1B66CBA6FF2131B0D6C74E04345642081854$block_0$RUBY$__file__.call(rubyjit$ActionController::Benchmarking$$perform_action_with_benchmark_303676EADF39D1B66CBA6FF2131B0D6C74E04345642081854$block_0$RUBY$__file__) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.CompiledBlock.yield(CompiledBlock.java:112) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.CompiledBlock.yield(CompiledBlock.java:95) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.CompiledBlock.yieldSpecific(CompiledBlock.java:75) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.Block.yieldSpecific(Block.java:111) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Module$$ms_A9C6E2920290A1221EDA9CE357DE380BBA9B1BD9642081854.block_0$RUBY$__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/activesupport-2.3.15/lib/active_support/core_ext/benchmark.rb:17) [jruby-complete-1.7.9.jar:na]
    at rubyjit$Module$$ms_A9C6E2920290A1221EDA9CE357DE380BBA9B1BD9642081854$block_0$RUBY$__file__.call(rubyjit$Module$$ms_A9C6E2920290A1221EDA9CE357DE380BBA9B1BD9642081854$block_0$RUBY$__file__) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.CompiledBlock.yield(CompiledBlock.java:112) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.CompiledBlock.yield(CompiledBlock.java:95) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.CompiledBlock.yieldSpecific(CompiledBlock.java:75) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.Block.yieldSpecific(Block.java:111) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Benchmark$$realtime_D80759D5ED3DED92A37F972B3598DFDF9CC64E2A642081854.__file__(jar:file:/C:/sonarqube-5.1.1/lib/server/jruby-complete-1.7.9.jar!/META-INF/jruby.home/lib/ruby/1.8/benchmark.rb:308) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Benchmark$$realtime_D80759D5ED3DED92A37F972B3598DFDF9CC64E2A642081854.__file__(jar:file:/C:/sonarqube-5.1.1/lib/server/jruby-complete-1.7.9.jar!/META-INF/jruby.home/lib/ruby/1.8/benchmark.rb) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:161) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.DefaultMethod.call(DefaultMethod.java:193) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.WrapperMethod.call(WrapperMethod.java:74) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.callBlock(CachingCallSite.java:143) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.callIter(CachingCallSite.java:154) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Module$$ms_A9C6E2920290A1221EDA9CE357DE380BBA9B1BD9642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/activesupport-2.3.15/lib/active_support/core_ext/benchmark.rb:17) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Module$$ms_A9C6E2920290A1221EDA9CE357DE380BBA9B1BD9642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/activesupport-2.3.15/lib/active_support/core_ext/benchmark.rb) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:161) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.callBlock(CachingCallSite.java:143) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.callIter(CachingCallSite.java:154) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Benchmarking$$perform_action_with_benchmark_303676EADF39D1B66CBA6FF2131B0D6C74E04345642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/benchmarking.rb:68) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Benchmarking$$perform_action_with_benchmark_303676EADF39D1B66CBA6FF2131B0D6C74E04345642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/benchmarking.rb) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:141) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.DefaultMethod.call(DefaultMethod.java:185) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.AliasMethod.call(AliasMethod.java:56) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.AliasMethod.call(AliasMethod.java:56) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.cacheAndCall(CachingCallSite.java:306) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:136) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Rescue$$perform_action_with_rescue_C881D7FD614DB6154FDE67AADAA1B18CFBA9E44D642081854.chained_0_rescue_1$RUBY$SYNTHETIC__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/rescue.rb:160) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Rescue$$perform_action_with_rescue_C881D7FD614DB6154FDE67AADAA1B18CFBA9E44D642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/rescue.rb) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Rescue$$perform_action_with_rescue_C881D7FD614DB6154FDE67AADAA1B18CFBA9E44D642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/rescue.rb) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:141) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.DefaultMethod.call(DefaultMethod.java:185) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.AliasMethod.call(AliasMethod.java:56) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.AliasMethod.call(AliasMethod.java:56) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.cacheAndCall(CachingCallSite.java:306) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:136) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Flash::InstanceMethods$$perform_action_with_flash_00190F39E4A509D41D1870DF577ADEFF4F2D86E0642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/flash.rb:151) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Flash::InstanceMethods$$perform_action_with_flash_00190F39E4A509D41D1870DF577ADEFF4F2D86E0642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/flash.rb) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:161) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.DefaultMethod.call(DefaultMethod.java:193) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.AliasMethod.call(AliasMethod.java:81) [jruby-complete-1.7.9.jar:na]
    at org.jruby.RubyClass.finvoke(RubyClass.java:527) [jruby-complete-1.7.9.jar:na]
    at org.jruby.RubyBasicObject.send(RubyBasicObject.java:2590) [jruby-complete-1.7.9.jar:na]
    at org.jruby.RubyKernel.send(RubyKernel.java:2223) [jruby-complete-1.7.9.jar:na]
    at org.jruby.RubyKernel$INVOKER$s$send.call(RubyKernel$INVOKER$s$send.gen) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JavaMethod$JavaMethodOneOrNBlock.call(JavaMethod.java:350) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.cacheAndCall(CachingCallSite.java:326) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:170) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.callVarargs(CachingCallSite.java:103) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Base$$process_0CD993B4C06E435A5AD30338E2A0816E50206655642081854.chained_0_ensure_1$RUBY$__ensure__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/base.rb:532) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Base$$process_0CD993B4C06E435A5AD30338E2A0816E50206655642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/base.rb) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Base$$process_0CD993B4C06E435A5AD30338E2A0816E50206655642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/base.rb) [jruby-complete-1.7.9.jar:na]
    at org.jruby.ast.executable.AbstractScript.__file__(AbstractScript.java:50) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:261) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.DefaultMethod.call(DefaultMethod.java:233) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.AliasMethod.call(AliasMethod.java:71) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.cacheAndCall(CachingCallSite.java:366) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:238) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.callVarargs(CachingCallSite.java:105) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Filters::InstanceMethods$$process_with_filters_77F73D0F3CFC9652FC1D8F99FD6ECB9ACFAEB2BE642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/filters.rb:606) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Filters::InstanceMethods$$process_with_filters_77F73D0F3CFC9652FC1D8F99FD6ECB9ACFAEB2BE642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/filters.rb) [jruby-complete-1.7.9.jar:na]
    at org.jruby.ast.executable.AbstractScript.__file__(AbstractScript.java:46) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:221) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.DefaultMethod.call(DefaultMethod.java:217) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.AliasMethod.call(AliasMethod.java:66) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.cacheAndCall(CachingCallSite.java:346) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:204) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Base$$process_D1BAE9F2F7D6D00DAEDE9A175F15297655D71871642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/base.rb:391) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Base$$process_D1BAE9F2F7D6D00DAEDE9A175F15297655D71871642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/base.rb) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:221) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.cacheAndCall(CachingCallSite.java:346) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:204) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Base$$call_8944B8D4A6D3AF4F57DE7885AE3EA09ADFDAED2A642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/base.rb:386) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Base$$call_8944B8D4A6D3AF4F57DE7885AE3EA09ADFDAED2A642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/base.rb) [jruby-complete-1.7.9.jar:na]

    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:181) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.cacheAndCall(CachingCallSite.java:326) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:170) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Routing::RouteSet$$call_426E45DA86B30E16B94E5379DB9F140F78AE4BF4642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/routing/route_set.rb:450) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Routing::RouteSet$$call_426E45DA86B30E16B94E5379DB9F140F78AE4BF4642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/routing/route_set.rb) [jruby-complete-1.7.9.jar:na]

    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:181) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Dispatcher$$dispatch_8AA750B78C06FEA2C9269E628FA684BF9C301479642081854.chained_1_rescue_1$RUBY$SYNTHETIC__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/dispatcher.rb:87) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Dispatcher$$dispatch_8AA750B78C06FEA2C9269E628FA684BF9C301479642081854.chained_0_ensure_1$RUBY$__ensure__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/dispatcher.rb) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Dispatcher$$dispatch_8AA750B78C06FEA2C9269E628FA684BF9C301479642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/dispatcher.rb:85) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Dispatcher$$dispatch_8AA750B78C06FEA2C9269E628FA684BF9C301479642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/dispatcher.rb) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:141) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:134) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Dispatcher$$_call_CD38443163AC875A33B6E9F44AC41E096715B208642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/dispatcher.rb:121) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Dispatcher$$_call_CD38443163AC875A33B6E9F44AC41E096715B208642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/dispatcher.rb) [jruby-complete-1.7.9.jar:na]

    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:181) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) [jruby-complete-1.7.9.jar:na]
    at org.jruby.ast.CallOneArgNode.interpret(CallOneArgNode.java:57) [jruby-complete-1.7.9.jar:na]
    at org.jruby.ast.NewlineNode.interpret(NewlineNode.java:105) [jruby-complete-1.7.9.jar:na]
    at org.jruby.evaluator.ASTInterpreter.INTERPRET_BLOCK(ASTInterpreter.java:112) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.InterpretedBlock.evalBlockBody(InterpretedBlock.java:384) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.InterpretedBlock.yield(InterpretedBlock.java:336) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.BlockBody.call(BlockBody.java:73) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.Block.call(Block.java:101) [jruby-complete-1.7.9.jar:na]
    at org.jruby.RubyProc.call(RubyProc.java:290) [jruby-complete-1.7.9.jar:na]
    at org.jruby.RubyProc.call(RubyProc.java:224) [jruby-complete-1.7.9.jar:na]
    at org.jruby.RubyProc$INVOKER$i$0$0$call.call(RubyProc$INVOKER$i$0$0$call.gen) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.DynamicMethod.call(DynamicMethod.java:210) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.DynamicMethod.call(DynamicMethod.java:206) [jruby-complete-1.7.9.jar:na]

    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActiveRecord::QueryCache$$call_A14988755E65051CA93890DA44DDBD1292F52201642081854.block_0$RUBY$__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/activerecord-2.3.15/lib/active_record/query_cache.rb:29) [jruby-complete-1.7.9.jar:na]
    at rubyjit$ActiveRecord::QueryCache$$call_A14988755E65051CA93890DA44DDBD1292F52201642081854$block_0$RUBY$__file__.call(rubyjit$ActiveRecord::QueryCache$$call_A14988755E65051CA93890DA44DDBD1292F52201642081854$block_0$RUBY$__file__) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.CompiledBlock.yield(CompiledBlock.java:112) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.CompiledBlock.yield(CompiledBlock.java:95) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.CompiledBlock.yieldSpecific(CompiledBlock.java:75) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.Block.yieldSpecific(Block.java:111) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActiveRecord::ConnectionAdapters::QueryCache$$cache_A633D695A7F60465017E9EF9D11E22D24BBB2F4C642081854.chained_0_ensure_1$RUBY$__ensure__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/activerecord-2.3.15/lib/active_record/connection_adapters/abstract/query_cache.rb:34) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActiveRecord::ConnectionAdapters::QueryCache$$cache_A633D695A7F60465017E9EF9D11E22D24BBB2F4C642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/activerecord-2.3.15/lib/active_record/connection_adapters/abstract/query_cache.rb) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActiveRecord::ConnectionAdapters::QueryCache$$cache_A633D695A7F60465017E9EF9D11E22D24BBB2F4C642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/activerecord-2.3.15/lib/active_record/connection_adapters/abstract/query_cache.rb) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:161) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.callBlock(CachingCallSite.java:143) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:149) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActiveRecord::QueryCache::ClassMethods$$cache_F67A1FFCC8AF52449A8884B5FC24C1FD70A7AFD2642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/activerecord-2.3.15/lib/active_record/query_cache.rb:9) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActiveRecord::QueryCache::ClassMethods$$cache_F67A1FFCC8AF52449A8884B5FC24C1FD70A7AFD2642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/activerecord-2.3.15/lib/active_record/query_cache.rb) [jruby-complete-1.7.9.jar:na]
    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:161) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.callBlock(CachingCallSite.java:143) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.callIter(CachingCallSite.java:154) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActiveRecord::QueryCache$$call_A14988755E65051CA93890DA44DDBD1292F52201642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/activerecord-2.3.15/lib/active_record/query_cache.rb:28) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActiveRecord::QueryCache$$call_A14988755E65051CA93890DA44DDBD1292F52201642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/activerecord-2.3.15/lib/active_record/query_cache.rb) [jruby-complete-1.7.9.jar:na]

    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:181) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActiveRecord::ConnectionAdapters::ConnectionManagement$$call_90D2EFA9453A4B4B189AB976C186F4E8E3F4ECFD642081854.chained_0_ensure_1$RUBY$__ensure__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/activerecord-2.3.15/lib/active_record/connection_adapters/abstract/connection_pool.rb:361) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActiveRecord::ConnectionAdapters::ConnectionManagement$$call_90D2EFA9453A4B4B189AB976C186F4E8E3F4ECFD642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/activerecord-2.3.15/lib/active_record/connection_adapters/abstract/connection_pool.rb) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActiveRecord::ConnectionAdapters::ConnectionManagement$$call_90D2EFA9453A4B4B189AB976C186F4E8E3F4ECFD642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/activerecord-2.3.15/lib/active_record/connection_adapters/abstract/connection_pool.rb) [jruby-complete-1.7.9.jar:na]

    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:181) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::StringCoercion$$call_D51288B7BF2BE6EC36483A8B41C5A7EAE6E2E5E7642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/string_coercion.rb:25) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::StringCoercion$$call_D51288B7BF2BE6EC36483A8B41C5A7EAE6E2E5E7642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/string_coercion.rb) [jruby-complete-1.7.9.jar:na]

    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:181) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Rack::Head$$call_11E01435D8E4F85C3AFDDE7D971052B5110D0779642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/rack-1.1.6/lib/rack/head.rb:9) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Rack::Head$$call_11E01435D8E4F85C3AFDDE7D971052B5110D0779642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/rack-1.1.6/lib/rack/head.rb) [jruby-complete-1.7.9.jar:na]

    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:181) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Rack::MethodOverride$$call_A192A02E0A99D7CFCD654C9DE135FDC02C454D65642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/rack-1.1.6/lib/rack/methodoverride.rb:24) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Rack::MethodOverride$$call_A192A02E0A99D7CFCD654C9DE135FDC02C454D65642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/rack-1.1.6/lib/rack/methodoverride.rb) [jruby-complete-1.7.9.jar:na]

    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:181) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::ParamsParser$$call_FD7B93C776B6EA91369F872184F90A76E29E01DF642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/params_parser.rb:15) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::ParamsParser$$call_FD7B93C776B6EA91369F872184F90A76E29E01DF642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/params_parser.rb) [jruby-complete-1.7.9.jar:na]

    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:181) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) [jruby-complete-1.7.9.jar:na]
    at rubyjit.JRuby::Rack::Session::SessionStore$$context_0E1F4BFDA407ED836479344660DBC64CEA03CE01642081854.__file__(file:/C:/sonarqube-5.1.1/lib/server/jruby-rack-1.1.13.2.jar!/jruby/rack/session_store.rb:70) [jruby-complete-1.7.9.jar:na]
    at rubyjit.JRuby::Rack::Session::SessionStore$$context_0E1F4BFDA407ED836479344660DBC64CEA03CE01642081854.__file__(file:/C:/sonarqube-5.1.1/lib/server/jruby-rack-1.1.13.2.jar!/jruby/rack/session_store.rb) [jruby-complete-1.7.9.jar:na]
    at org.jruby.ast.executable.AbstractScript.__file__(AbstractScript.java:42) [jruby-complete-1.7.9.jar:na]

    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:181) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Rack::Session::Abstract::ID$$call_995B04BBF18C14C6F594A803A25290E93788D13E642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/rack-1.1.6/lib/rack/session/abstract/id.rb:58) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Rack::Session::Abstract::ID$$call_995B04BBF18C14C6F594A803A25290E93788D13E642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/rack-1.1.6/lib/rack/session/abstract/id.rb) [jruby-complete-1.7.9.jar:na]

    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:181) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Failsafe$$call_C56662FB7D6C93EA6FAA101961E670BAD61A3096642081854.chained_0_rescue_1$RUBY$SYNTHETIC__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/failsafe.rb:26) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Failsafe$$call_C56662FB7D6C93EA6FAA101961E670BAD61A3096642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/failsafe.rb) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Failsafe$$call_C56662FB7D6C93EA6FAA101961E670BAD61A3096642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/failsafe.rb) [jruby-complete-1.7.9.jar:na]

    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:181) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Dispatcher$$call_E48E93D5AB66D9883FF2780317A0C66F0B9A3F18642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/dispatcher.rb:106) [jruby-complete-1.7.9.jar:na]
    at rubyjit.ActionController::Dispatcher$$call_E48E93D5AB66D9883FF2780317A0C66F0B9A3F18642081854.__file__(C:/sonarqube-5.1.1/web/WEB-INF/gems/gems/actionpack-2.3.15/lib/action_controller/dispatcher.rb) [jruby-complete-1.7.9.jar:na]

    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:181) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Rack::Adapter::Rails$$serve_rails_7BB0718E629FB97D01DE161C75D4AB1B2287DB20642081854.__file__(file:/C:/sonarqube-5.1.1/lib/server/jruby-rack-1.1.13.2.jar!/rack/adapter/rails.rb:34) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Rack::Adapter::Rails$$serve_rails_7BB0718E629FB97D01DE161C75D4AB1B2287DB20642081854.__file__(file:/C:/sonarqube-5.1.1/lib/server/jruby-rack-1.1.13.2.jar!/rack/adapter/rails.rb) [jruby-complete-1.7.9.jar:na]

    at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:181) [jruby-complete-1.7.9.jar:na]
    at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Rack::Adapter::Rails$$call_53C2561E82BE13BC6589E138533EBF83460FC237642081854.__file__(file:/C:/sonarqube-5.1.1/lib/server/jruby-rack-1.1.13.2.jar!/rack/adapter/rails.rb:39) [jruby-complete-1.7.9.jar:na]
    at rubyjit.Rack::Adapter::Rails$$call_53C2561E82BE13BC6589E138533EBF83460FC237642081854.__file__(file:/C:/sonarqube-5.1.1/lib/server/jruby-rack-1.1.13.2.jar!/rack/adapter/rails.rb) [jruby-complete-1.7.9.jar:na]
    ... 265 common frames omitted
2015.07.23 16:48:27 DEBUG web[http] GET /batch/project?key=ucfed_webportal&amp;preview=false | time=19204ms



Regards,
Vijay Raj R

Vijay Raj

unread,
Jul 27, 2015, 1:49:29 AM7/27/15
to SonarQube
Hi Lancelot,

Once again i got the failure, but this time there is no error log.


2015.07.26 05:32:06 DEBUG web[http] GET /batch/global | time=1607ms
2015.07.26 05:32:06 DEBUG web[http] GET /deploy/plugins/index.txt | time=0ms
2015.07.26 05:32:08 TRACE web[sql] time=0ms | sql=select id, data from notifications order by id asc limit ?
2015.07.26 05:32:08 DEBUG web[http] GET /deploy/jdbc-driver.txt | time=0ms
2015.07.26 05:32:10 DEBUG web[http] GET /api/server | time=78ms
2015.07.26 05:32:11 TRACE web[sql] time=0ms | sql=select ar.id from analysis_reports ar where ar.report_status=? and not exists( select 1 from analysis_reports ar2 where ar.project_key = ar2.project_key and ar2.report_status=? ) order by ar.created_at asc, ar.id asc
2015.07.26 05:32:14 TRACE web[sql] time=0ms | sql=SELECT gr.role FROM group_roles gr WHERE gr.resource_id is null and gr.group_id is null
2015.07.26 05:32:15 TRACE web[sql] time=63ms | sql=SELECT p.id, p.uuid as uuid, p.project_uuid as projectUuid, p.module_uuid as moduleUuid, p.module_uuid_path as moduleUuidPath, p.kee as kee, p.deprecated_kee as deprecatedKey, p.name as name, p.long_name as longName, p.qualifier as qualifier, p.scope as scope, p.language as language, p.root_id as parentProjectId, p.path as path, p.enabled as enabled, p.copy_resource_id as copyResourceId, p.authorization_updated_at as authorizationUpdatedAt, p.created_at as createdAt FROM projects p WHERE p.kee=?
2015.07.26 05:32:16 TRACE web[sql] time=1029ms | sql=SELECT p.id, p.uuid as uuid, p.project_uuid as projectUuid, p.module_uuid as moduleUuid, p.module_uuid_path as moduleUuidPath, p.kee as kee, p.deprecated_kee as deprecatedKey, p.name as name, p.long_name as longName, p.qualifier as qualifier, p.scope as scope, p.language as language, p.root_id as parentProjectId, p.path as path, p.enabled as enabled, p.copy_resource_id as copyResourceId, p.authorization_updated_at as authorizationUpdatedAt, p.created_at as createdAt FROM projects p INNER JOIN projects root_project ON root_project.uuid = p.project_uuid AND root_project.enabled = true INNER JOIN projects module ON module.project_uuid = root_project.uuid AND module.uuid = ? AND module.scope='PRJ' AND module.enabled = true WHERE p.enabled = true AND p.scope = ? AND p.module_uuid_path LIKE module.module_uuid_path || '%'
2015.07.26 05:32:16 TRACE web[sql] time=63ms | sql=SELECT prop.id as id, prop.prop_key as "key", prop.text_value as value, prop.resource_id as resourceId, prop.user_id as userId FROM properties prop INNER JOIN (SELECT p.id FROM projects p INNER JOIN projects root_project ON root_project.uuid = p.project_uuid AND root_project.enabled = true INNER JOIN projects module ON module.project_uuid = root_project.uuid AND module.uuid = ? AND module.scope='PRJ' AND module.enabled = true WHERE p.enabled = true AND p.scope = ? AND p.module_uuid_path LIKE module.module_uuid_path || '%' ) modules on modules.id=prop.resource_id WHERE prop.user_id IS NULL
2015.07.26 05:32:17 TRACE web[sql] time=530ms | sql=SELECT p.uuid, p.path, p.module_uuid as moduleUuid, fs.src_hash as srcHash FROM projects p INNER JOIN file_sources fs ON fs.file_uuid=p.uuid INNER JOIN projects root_project ON root_project.uuid = p.project_uuid AND root_project.enabled = true INNER JOIN projects module ON module.project_uuid = root_project.uuid AND module.uuid = ? AND module.scope='PRJ' AND module.enabled = true WHERE p.enabled = true AND p.scope = ? AND p.module_uuid_path LIKE module.module_uuid_path || '%'
2015.07.26 05:32:18 TRACE web[sql] time=16ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p, projects project INNER JOIN properties prop ON prop.resource_id=project.id WHERE p.language=? AND prop.prop_key LIKE ? AND prop.text_value LIKE p.name AND project.kee=?
2015.07.26 05:32:18 TRACE web[sql] time=171ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p INNER JOIN properties prop ON prop.prop_key=? AND prop.resource_id IS NULL AND prop.text_value LIKE p.name AND p.language=?
2015.07.26 05:32:18 TRACE web[sql] time=62ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p, projects project INNER JOIN properties prop ON prop.resource_id=project.id WHERE p.language=? AND prop.prop_key LIKE ? AND prop.text_value LIKE p.name AND project.kee=?
2015.07.26 05:32:18 TRACE web[sql] time=0ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p INNER JOIN properties prop ON prop.prop_key=? AND prop.resource_id IS NULL AND prop.text_value LIKE p.name AND p.language=?
2015.07.26 05:32:18 TRACE web[sql] time=125ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p, projects project INNER JOIN properties prop ON prop.resource_id=project.id WHERE p.language=? AND prop.prop_key LIKE ? AND prop.text_value LIKE p.name AND project.kee=?
2015.07.26 05:32:18 TRACE web[sql] time=47ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p INNER JOIN properties prop ON prop.prop_key=? AND prop.resource_id IS NULL AND prop.text_value LIKE p.name AND p.language=?
2015.07.26 05:32:18 TRACE web[sql] time=31ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p, projects project INNER JOIN properties prop ON prop.resource_id=project.id WHERE p.language=? AND prop.prop_key LIKE ? AND prop.text_value LIKE p.name AND project.kee=?
2015.07.26 05:32:19 TRACE web[sql] time=500ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p, projects project INNER JOIN properties prop ON prop.resource_id=project.id WHERE p.language=? AND prop.prop_key LIKE ? AND prop.text_value LIKE p.name AND project.kee=?
2015.07.26 05:32:19 TRACE web[sql] time=187ms | sql=SELECT p.id as id, p.kee as kee, p.name as name, p.language as language, p.parent_kee as parentKee, p.created_at as createdAt, p.updated_at as updatedAt, p.rules_updated_at as rulesUpdatedAt FROM rules_profiles p INNER JOIN properties prop ON prop.prop_key=? AND prop.resource_id IS NULL AND prop.text_value LIKE p.name AND p.language=?
2015.07.26 05:32:21 TRACE web[sql] time=0ms | sql=select ar.id from analysis_reports ar where ar.report_status=? and not exists( select 1 from analysis_reports ar2 where ar.project_key = ar2.project_key and ar2.report_status=? ) order by ar.created_at asc, ar.id asc
2015.07.26 05:32:23 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["js"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=2886ms
2015.07.26 05:32:24 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@7f783ab4' | time=1233ms
2015.07.26 05:32:28 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2e5640cd' | time=3759ms
2015.07.26 05:32:28 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"js-sonar-way-68531"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=452ms
2015.07.26 05:32:29 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@20b86a7f' | time=624ms
2015.07.26 05:32:30 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2b57c8a1' | time=16ms
2015.07.26 05:32:30 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["java"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=47ms
2015.07.26 05:32:30 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@1034d842' | time=219ms
2015.07.26 05:32:31 TRACE web[sql] time=0ms | sql=select ar.id from analysis_reports ar where ar.report_status=? and not exists( select 1 from analysis_reports ar2 where ar.project_key = ar2.project_key and ar2.report_status=? ) order by ar.created_at asc, ar.id asc
2015.07.26 05:32:32 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@7d61dd5d' | time=702ms
2015.07.26 05:32:34 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@4e77116' | time=656ms
2015.07.26 05:32:36 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@6f3102e0' | time=1029ms
2015.07.26 05:32:39 INFO web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.07.26 05:32:39 INFO web[o.s.s.es.BulkIndexer] 561 requests processed (0 items/sec)
2015.07.26 05:32:57 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@51124233' | time=20888ms
2015.07.26 05:32:57 TRACE web[sql] time=31ms | sql=select ar.id from analysis_reports ar where ar.report_status=? and not exists( select 1 from analysis_reports ar2 where ar.project_key = ar2.project_key and ar2.report_status=? ) order by ar.created_at asc, ar.id asc
2015.07.26 05:32:57 TRACE web[sql] time=0ms | sql=select ar.id from analysis_reports ar where ar.report_status=? and not exists( select 1 from analysis_reports ar2 where ar.project_key = ar2.project_key and ar2.report_status=? ) order by ar.created_at asc, ar.id asc
2015.07.26 05:32:59 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@5b84bda' | time=2308ms
2015.07.26 05:33:00 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@67bd825' | time=327ms
2015.07.26 05:33:00 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2cab6876' | time=93ms
2015.07.26 05:33:01 TRACE web[sql] time=63ms | sql=select ar.id from analysis_reports ar where ar.report_status=? and not exists( select 1 from analysis_reports ar2 where ar.project_key = ar2.project_key and ar2.report_status=? ) order by ar.created_at asc, ar.id asc
2015.07.26 05:33:01 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@295731e3' | time=1389ms
2015.07.26 05:33:02 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"java-findinherit-79774"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=63ms
2015.07.26 05:33:02 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@1b8c4349' | time=62ms
2015.07.26 05:33:02 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@15957bc0' | time=63ms
2015.07.26 05:33:02 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@8ef8552' | time=124ms
2015.07.26 05:33:02 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2519cf13' | time=234ms
2015.07.26 05:33:02 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@4169fb6c' | time=172ms
2015.07.26 05:33:02 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@14f99101' | time=0ms
2015.07.26 05:33:02 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["web"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=0ms
2015.07.26 05:33:02 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@c9ee369' | time=62ms
2015.07.26 05:33:02 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@136408f8' | time=0ms
2015.07.26 05:33:02 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"web-sonar-way-91342"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=47ms
2015.07.26 05:33:02 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@c88dbba' | time=0ms
2015.07.26 05:33:02 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@6d1196c3' | time=0ms
2015.07.26 05:33:02 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["xml"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=31ms
2015.07.26 05:33:02 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@1dc11426' | time=16ms
2015.07.26 05:33:02 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@6f11da0c' | time=0ms
2015.07.26 05:33:02 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"xml-sonar-way-62420"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=31ms
2015.07.26 05:33:02 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@7d0282cb' | time=15ms
2015.07.26 05:33:02 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2fd91d8b' | time=0ms
2015.07.26 05:33:02 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["css"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=0ms
2015.07.26 05:33:04 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@5722a937' | time=1311ms
2015.07.26 05:33:04 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@24938692' | time=0ms
2015.07.26 05:33:04 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"css-sonar-way-50776"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=31ms
2015.07.26 05:33:04 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@5167ee00' | time=47ms
2015.07.26 05:33:04 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@7e3a6bc0' | time=0ms
2015.07.26 05:33:04 TRACE web[es] ES search request '{"from":0,"size":10,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"repo":["manual"]}}]}}}},"_source":{"includes":["name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=0ms
2015.07.26 05:33:04 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2d784afe' | time=0ms
2015.07.26 05:33:05 DEBUG web[http] GET /batch/project?key=ucfed_webportal&amp;preview=false | time=50716ms


Regards,
Vijay Raj R

Vijay Raj

unread,
Aug 20, 2015, 11:18:35 PM8/20/15
to SonarQube
Hi Julien,

Can you please have a look at this fresh log, i am frequently getting this error from the last 2 days. I have also changed the db to MySQL but still the problem persists. Could you please suggest me any workaround to avoid this problem.

//Log1
---------
 2015.08.20 00:08:41 DEBUG web[http] GET /batch/global | time=1887ms
2015.08.20 00:08:42 DEBUG web[http] GET /deploy/plugins/index.txt | time=32ms
2015.08.20 00:08:46 DEBUG web[http] GET /deploy/jdbc-driver.txt | time=0ms
2015.08.20 00:08:48 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.08.20 00:08:49 DEBUG web[http] GET /api/server | time=269ms
2015.08.20 00:08:53 TRACE web[sql] time=0ms | sql=SELECT gr.role        FROM group_roles gr         WHERE  gr.resource_id is null          and gr.group_id is null
2015.08.20 00:08:54 TRACE web[sql] time=89ms | sql=SELECT      p.id,    p.uuid as uuid,    p.project_uuid as projectUuid,    p.module_uuid as moduleUuid,    p.module_uuid_path as moduleUuidPath,    p.kee as kee,    p.deprecated_kee as deprecatedKey,    p.name as name,    p.long_name as longName,    p.qualifier as qualifier,    p.scope as scope,    p.language as language,    p.root_id as parentProjectId,    p.path as path,    p.enabled as enabled,    p.copy_resource_id as copyResourceId,    p.authorization_updated_at as authorizationUpdatedAt,    p.created_at as createdAt       FROM projects p     WHERE  p.kee=?
2015.08.20 00:08:56 TRACE web[sql] time=1932ms | sql=SELECT      p.id,    p.uuid as uuid,    p.project_uuid as projectUuid,    p.module_uuid as moduleUuid,    p.module_uuid_path as moduleUuidPath,    p.kee as kee,    p.deprecated_kee as deprecatedKey,    p.name as name,    p.long_name as longName,    p.qualifier as qualifier,    p.scope as scope,    p.language as language,    p.root_id as parentProjectId,    p.path as path,    p.enabled as enabled,    p.copy_resource_id as copyResourceId,    p.authorization_updated_at as authorizationUpdatedAt,    p.created_at as createdAt       FROM projects p         INNER JOIN projects root_project ON root_project.uuid = p.project_uuid AND root_project.enabled = true    INNER JOIN projects module ON module.project_uuid = root_project.uuid AND module.uuid = ? AND module.scope='PRJ' AND module.enabled = true     WHERE p.enabled = true            AND p.scope = ?      AND                p.module_uuid_path LIKE module.module_uuid_path || '%'
2015.08.20 00:08:56 TRACE web[sql] time=47ms | sql=SELECT prop.id as id, prop.prop_key as "key", prop.text_value as value, prop.resource_id as resourceId, prop.user_id as userId    FROM properties prop    INNER JOIN (SELECT p.id FROM projects p     INNER JOIN projects root_project ON root_project.uuid = p.project_uuid AND root_project.enabled = true    INNER JOIN projects module ON module.project_uuid = root_project.uuid AND module.uuid = ? AND module.scope='PRJ' AND module.enabled = true     WHERE p.enabled = true            AND p.scope = ?      AND                p.module_uuid_path LIKE module.module_uuid_path || '%'    ) modules on modules.id=prop.resource_id    WHERE prop.user_id IS NULL
2015.08.20 00:08:57 TRACE web[sql] time=451ms | sql=SELECT p.uuid, p.path, p.module_uuid as moduleUuid, fs.src_hash as srcHash    FROM projects p    INNER JOIN file_sources fs ON fs.file_uuid=p.uuid         INNER JOIN projects root_project ON root_project.uuid = p.project_uuid AND root_project.enabled = true    INNER JOIN projects module ON module.project_uuid = root_project.uuid AND module.uuid = ? AND module.scope='PRJ' AND module.enabled = true     WHERE p.enabled = true            AND p.scope = ?      AND                p.module_uuid_path LIKE module.module_uuid_path || '%'
2015.08.20 00:08:58 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p, projects project    INNER JOIN properties prop ON prop.resource_id=project.id     WHERE  p.language=?      AND prop.prop_key LIKE ?      AND prop.text_value LIKE p.name      AND project.kee=?
2015.08.20 00:08:58 TRACE web[sql] time=13ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p    INNER JOIN properties prop ON prop.prop_key=?      AND prop.resource_id IS NULL      AND prop.text_value LIKE p.name      AND p.language=?
2015.08.20 00:08:58 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p, projects project    INNER JOIN properties prop ON prop.resource_id=project.id     WHERE  p.language=?      AND prop.prop_key LIKE ?      AND prop.text_value LIKE p.name      AND project.kee=?
2015.08.20 00:08:58 TRACE web[sql] time=1ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p    INNER JOIN properties prop ON prop.prop_key=?      AND prop.resource_id IS NULL      AND prop.text_value LIKE p.name      AND p.language=?
2015.08.20 00:08:58 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p, projects project    INNER JOIN properties prop ON prop.resource_id=project.id     WHERE  p.language=?      AND prop.prop_key LIKE ?      AND prop.text_value LIKE p.name      AND project.kee=?
2015.08.20 00:08:58 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p    INNER JOIN properties prop ON prop.prop_key=?      AND prop.resource_id IS NULL      AND prop.text_value LIKE p.name      AND p.language=?
2015.08.20 00:08:58 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p, projects project    INNER JOIN properties prop ON prop.resource_id=project.id     WHERE  p.language=?      AND prop.prop_key LIKE ?      AND prop.text_value LIKE p.name      AND project.kee=?
2015.08.20 00:08:58 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p, projects project    INNER JOIN properties prop ON prop.resource_id=project.id     WHERE  p.language=?      AND prop.prop_key LIKE ?      AND prop.text_value LIKE p.name      AND project.kee=?
2015.08.20 00:08:58 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p    INNER JOIN properties prop ON prop.prop_key=?      AND prop.resource_id IS NULL      AND prop.text_value LIKE p.name      AND p.language=?
2015.08.20 00:08:58 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.08.20 00:09:07 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["js"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=9504ms
2015.08.20 00:09:08 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.08.20 00:09:10 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@222e44ad' | time=2342ms
2015.08.20 00:09:10 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@b9ecb63' | time=339ms
2015.08.20 00:09:12 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"js-sonar-way-68531"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=1656ms
2015.08.20 00:09:13 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@663ad68a' | time=786ms
2015.08.20 00:09:13 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@20db3275' | time=250ms
2015.08.20 00:09:13 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["java"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=111ms
2015.08.20 00:09:14 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@5626c2e0' | time=724ms
2015.08.20 00:09:15 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2361e40d' | time=1189ms
2015.08.20 00:09:16 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@6fc323a0' | time=1070ms
2015.08.20 00:09:17 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@745816b2' | time=750ms
2015.08.20 00:09:18 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.08.20 00:09:18 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@433a3bca' | time=896ms
2015.08.20 00:09:19 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@242b6d41' | time=625ms
2015.08.20 00:09:20 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@1f11d8f4' | time=602ms
2015.08.20 00:09:20 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@4cba8b1b' | time=601ms
2015.08.20 00:09:20 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@6b02c0f7' | time=1ms
2015.08.20 00:09:21 INFO  web[o.s.s.es.BulkIndexer] 61998 requests processed (0 items/sec)
2015.08.20 00:09:21 INFO  web[o.s.s.es.BulkIndexer] 11360 requests processed (0 items/sec)
2015.08.20 00:09:21 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"java-findinherit-79774"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=435ms
2015.08.20 00:09:21 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@4536d806' | time=326ms
2015.08.20 00:09:22 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@13a0956a' | time=252ms
2015.08.20 00:09:23 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@13b16c37' | time=318ms
2015.08.20 00:09:23 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@653798fa' | time=150ms
2015.08.20 00:09:24 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@251c7900' | time=301ms
2015.08.20 00:09:24 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@704c0208' | time=18ms
2015.08.20 00:09:24 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["web"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=90ms
2015.08.20 00:09:25 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@e1994d1' | time=526ms
2015.08.20 00:09:25 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@31792c92' | time=16ms
2015.08.20 00:09:25 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"web-sonar-way-91342"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=132ms
2015.08.20 00:09:25 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@a5d3e9e' | time=41ms
2015.08.20 00:09:25 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@6fb9fc62' | time=1ms
2015.08.20 00:09:25 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["xml"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=44ms
2015.08.20 00:09:25 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@1b68bf3c' | time=88ms
2015.08.20 00:09:25 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@79e30a32' | time=0ms
2015.08.20 00:09:25 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"xml-sonar-way-62420"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=41ms
2015.08.20 00:09:25 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@28b29722' | time=26ms
2015.08.20 00:09:25 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@64e04e0d' | time=1ms
2015.08.20 00:09:25 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["css"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=82ms
2015.08.20 00:09:25 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@42246b38' | time=237ms
2015.08.20 00:09:25 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@64fb4b74' | time=1ms
2015.08.20 00:09:25 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"css-sonar-way-50776"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=99ms
2015.08.20 00:09:26 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2e16e077' | time=336ms
2015.08.20 00:09:26 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@5feabc44' | time=1ms
2015.08.20 00:09:26 TRACE web[es] ES search request '{"from":0,"size":10,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"repo":["manual"]}}]}}}},"_source":{"includes":["name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=121ms
2015.08.20 00:09:26 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2bee458e' | time=52ms
2015.08.20 00:09:28 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.08.20 00:09:29 DEBUG web[http] GET /batch/project?key=ucfed_webportal&amp;preview=false | time=35539ms


//Log2
2015.08.20 05:27:26 DEBUG web[http] GET /batch/global | time=792ms
2015.08.20 05:27:27 DEBUG web[http] GET /deploy/plugins/index.txt | time=32ms
2015.08.20 05:27:28 TRACE web[sql] time=1ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.08.20 05:27:33 DEBUG web[http] GET /deploy/jdbc-driver.txt | time=0ms
2015.08.20 05:27:35 DEBUG web[http] GET /api/server | time=253ms
2015.08.20 05:27:38 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.08.20 05:27:38 TRACE web[sql] time=0ms | sql=SELECT gr.role        FROM group_roles gr         WHERE  gr.resource_id is null          and gr.group_id is null
2015.08.20 05:27:38 TRACE web[sql] time=46ms | sql=SELECT      p.id,    p.uuid as uuid,    p.project_uuid as projectUuid,    p.module_uuid as moduleUuid,    p.module_uuid_path as moduleUuidPath,    p.kee as kee,    p.deprecated_kee as deprecatedKey,    p.name as name,    p.long_name as longName,    p.qualifier as qualifier,    p.scope as scope,    p.language as language,    p.root_id as parentProjectId,    p.path as path,    p.enabled as enabled,    p.copy_resource_id as copyResourceId,    p.authorization_updated_at as authorizationUpdatedAt,    p.created_at as createdAt       FROM projects p     WHERE  p.kee=?
2015.08.20 05:27:39 TRACE web[sql] time=248ms | sql=SELECT      p.id,    p.uuid as uuid,    p.project_uuid as projectUuid,    p.module_uuid as moduleUuid,    p.module_uuid_path as moduleUuidPath,    p.kee as kee,    p.deprecated_kee as deprecatedKey,    p.name as name,    p.long_name as longName,    p.qualifier as qualifier,    p.scope as scope,    p.language as language,    p.root_id as parentProjectId,    p.path as path,    p.enabled as enabled,    p.copy_resource_id as copyResourceId,    p.authorization_updated_at as authorizationUpdatedAt,    p.created_at as createdAt       FROM projects p         INNER JOIN projects root_project ON root_project.uuid = p.project_uuid AND root_project.enabled = true    INNER JOIN projects module ON module.project_uuid = root_project.uuid AND module.uuid = ? AND module.scope='PRJ' AND module.enabled = true     WHERE p.enabled = true            AND p.scope = ?      AND                p.module_uuid_path LIKE module.module_uuid_path || '%'
2015.08.20 05:27:39 TRACE web[sql] time=111ms | sql=SELECT prop.id as id, prop.prop_key as "key", prop.text_value as value, prop.resource_id as resourceId, prop.user_id as userId    FROM properties prop    INNER JOIN (SELECT p.id FROM projects p     INNER JOIN projects root_project ON root_project.uuid = p.project_uuid AND root_project.enabled = true    INNER JOIN projects module ON module.project_uuid = root_project.uuid AND module.uuid = ? AND module.scope='PRJ' AND module.enabled = true     WHERE p.enabled = true            AND p.scope = ?      AND                p.module_uuid_path LIKE module.module_uuid_path || '%'    ) modules on modules.id=prop.resource_id    WHERE prop.user_id IS NULL
2015.08.20 05:27:40 TRACE web[sql] time=368ms | sql=SELECT p.uuid, p.path, p.module_uuid as moduleUuid, fs.src_hash as srcHash    FROM projects p    INNER JOIN file_sources fs ON fs.file_uuid=p.uuid         INNER JOIN projects root_project ON root_project.uuid = p.project_uuid AND root_project.enabled = true    INNER JOIN projects module ON module.project_uuid = root_project.uuid AND module.uuid = ? AND module.scope='PRJ' AND module.enabled = true     WHERE p.enabled = true            AND p.scope = ?      AND                p.module_uuid_path LIKE module.module_uuid_path || '%'
2015.08.20 05:27:40 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p, projects project    INNER JOIN properties prop ON prop.resource_id=project.id     WHERE  p.language=?      AND prop.prop_key LIKE ?      AND prop.text_value LIKE p.name      AND project.kee=?
2015.08.20 05:27:40 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p    INNER JOIN properties prop ON prop.prop_key=?      AND prop.resource_id IS NULL      AND prop.text_value LIKE p.name      AND p.language=?
2015.08.20 05:27:40 TRACE web[sql] time=1ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p, projects project    INNER JOIN properties prop ON prop.resource_id=project.id     WHERE  p.language=?      AND prop.prop_key LIKE ?      AND prop.text_value LIKE p.name      AND project.kee=?
2015.08.20 05:27:40 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p    INNER JOIN properties prop ON prop.prop_key=?      AND prop.resource_id IS NULL      AND prop.text_value LIKE p.name      AND p.language=?
2015.08.20 05:27:40 TRACE web[sql] time=18ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p, projects project    INNER JOIN properties prop ON prop.resource_id=project.id     WHERE  p.language=?      AND prop.prop_key LIKE ?      AND prop.text_value LIKE p.name      AND project.kee=?
2015.08.20 05:27:40 TRACE web[sql] time=1ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p    INNER JOIN properties prop ON prop.prop_key=?      AND prop.resource_id IS NULL      AND prop.text_value LIKE p.name      AND p.language=?
2015.08.20 05:27:40 TRACE web[sql] time=1ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p, projects project    INNER JOIN properties prop ON prop.resource_id=project.id     WHERE  p.language=?      AND prop.prop_key LIKE ?      AND prop.text_value LIKE p.name      AND project.kee=?
2015.08.20 05:27:40 TRACE web[sql] time=0ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p, projects project    INNER JOIN properties prop ON prop.resource_id=project.id     WHERE  p.language=?      AND prop.prop_key LIKE ?      AND prop.text_value LIKE p.name      AND project.kee=?
2015.08.20 05:27:40 TRACE web[sql] time=1ms | sql=SELECT      p.id as id,    p.kee as kee,    p.name as name,    p.language as language,    p.parent_kee as parentKee,    p.created_at as createdAt,    p.updated_at as updatedAt,    p.rules_updated_at as rulesUpdatedAt       FROM rules_profiles p    INNER JOIN properties prop ON prop.prop_key=?      AND prop.resource_id IS NULL      AND prop.text_value LIKE p.name      AND p.language=?
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 3981 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 5778 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 11360 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 2254 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 1691 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 27637 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 1682 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 3949 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 61998 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 2894 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 26201 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 26846 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 8173 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 43336 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 50735 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 6745 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 26943 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 47820 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 4955 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 40783 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 35920 requests processed (0 items/sec)
2015.08.20 05:27:44 INFO  web[o.s.s.es.BulkIndexer] 0 requests processed (0 items/sec)
2015.08.20 05:27:48 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["js"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=7317ms
2015.08.20 05:27:48 TRACE web[sql] time=11ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.08.20 05:27:50 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@506f9721' | time=2266ms
2015.08.20 05:27:50 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@4e2b20c8' | time=202ms
2015.08.20 05:27:52 TRACE web[sql] time=1ms | sql=select id, data    from notifications    order by id asc    limit ?
2015.08.20 05:27:53 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"js-sonar-way-68531"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=2704ms
2015.08.20 05:27:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@1698b0d' | time=742ms
2015.08.20 05:27:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@38280334' | time=56ms
2015.08.20 05:27:54 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["java"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=84ms
2015.08.20 05:27:55 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@4aea67b9' | time=452ms
2015.08.20 05:27:55 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@633fc91b' | time=528ms
2015.08.20 05:27:56 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@70ac3e84' | time=381ms
2015.08.20 05:27:56 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@3c3c85e6' | time=281ms
2015.08.20 05:27:56 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@297addef' | time=289ms
2015.08.20 05:27:56 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@41808b2f' | time=178ms
2015.08.20 05:27:57 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@5e31dc41' | time=226ms
2015.08.20 05:27:57 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@1b735fb7' | time=191ms
2015.08.20 05:27:57 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@3390f575' | time=10ms
2015.08.20 05:27:57 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"java-findinherit-79774"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=98ms
2015.08.20 05:27:57 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@71e2eec5' | time=204ms
2015.08.20 05:27:57 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@69aa01ec' | time=100ms
2015.08.20 05:27:57 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@18646326' | time=143ms
2015.08.20 05:27:58 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@66a9604c' | time=59ms
2015.08.20 05:27:58 TRACE web[sql] time=0ms | sql=select ar.id    from analysis_reports ar    where ar.report_status=?    and not exists(    select 1    from analysis_reports ar2    where ar.project_key = ar2.project_key    and ar2.report_status=?    )    order by ar.created_at asc, ar.id asc
2015.08.20 05:27:58 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@762c096e' | time=293ms
2015.08.20 05:27:58 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@451f515e' | time=0ms
2015.08.20 05:27:58 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["web"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=67ms
2015.08.20 05:27:59 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@7fa5437c' | time=913ms
2015.08.20 05:27:59 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@3af1c35c' | time=1ms
2015.08.20 05:27:59 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"web-sonar-way-91342"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=159ms
2015.08.20 05:28:00 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@397e50bf' | time=129ms
2015.08.20 05:28:00 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@13e74916' | time=0ms
2015.08.20 05:28:00 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["xml"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=84ms
2015.08.20 05:28:00 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@356f4d8f' | time=87ms
2015.08.20 05:28:00 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@1e4d02b1' | time=28ms
2015.08.20 05:28:00 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"xml-sonar-way-62420"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=107ms
2015.08.20 05:28:00 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@51ebc201' | time=73ms
2015.08.20 05:28:00 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@4d0df9ab' | time=46ms
2015.08.20 05:28:00 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"lang":["css"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=174ms
2015.08.20 05:28:00 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@50827112' | time=299ms
2015.08.20 05:28:01 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@23cd7e09' | time=1ms
2015.08.20 05:28:01 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"css-sonar-way-50776"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=58ms
2015.08.20 05:28:01 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@80d933c' | time=66ms
2015.08.20 05:28:01 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@333d4c45' | time=24ms
2015.08.20 05:28:01 TRACE web[es] ES search request '{"from":0,"size":10,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"bool":{"must_not":{"term":{"status":"REMOVED"}}}},{"terms":{"repo":["manual"]}}]}}}},"_source":{"includes":["name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=77ms
2015.08.20 05:28:01 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@7d5a604a' | time=1ms
2015.08.20 05:28:03 DEBUG web[http] GET /batch/project?key=ucfed_webportal&amp;preview=false | time=25373ms


Regards,
Vijay Raj R

Julien Lancelot

unread,
Aug 25, 2015, 11:55:55 AM8/25/15
to Vijay Raj, SonarQube
Hi,

From what I see in your first log, Elasticsearch queries are very slow, that's why a timeout is generated.
The second log seems ok, I think that they were no timeout here.

What's the difference between these 2 logs ?

Thanks.
Regards,

Julien LANCELOT | SonarSource

--
You received this message because you are subscribed to the Google Groups "SonarQube" group.
To unsubscribe from this group and stop receiving emails from it, send an email to sonarqube+...@googlegroups.com.
Reply all
Reply to author
Forward
0 new messages