Read time out error .

classic Classic list List threaded Threaded
39 messages Options
12
Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

The_Gentleman
Hi!
Today in the morning I updated the jars of my sonar 5.1 install with those two files provided and restarted the machine. The result seems currently much better than before. One or two jobs keep having some issues like before, but it is much less often that the problem occurs.

Maybe for statistical issues:
- We are running about 20 JAVA-projects / -branches using much legacy code and having about 1.2 million LOC and 130k issues.
- There are about 30 JAVA-projects module projects having 1k-20k LOC and less than 2k issues.
- There are about 50 custom JAVA-projects having less than 200k LOC and less than 10k issues.

All of these are managed by Jenkins and the Jenkins Sonar Runner Plugin. The machine does not seem to have load issues. The failing issues are primarily from group 1 (1.2m LOC/>125k Issues). But as shown in the log above mostly the responses arrive after less than 5 seconds, even on these projects.

Is there a possibility to set the timeout for the project repository load phase?
Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

Julien Lancelot
Thanks for your feedback and for all the information you gave.
In order for me to better handle this issue, could you send me the log, in order for me to see which SQL query is slow.

Thanks.
Regards,

Julien LANCELOT | SonarSource

On 19 May 2015 at 11:22, The_Gentleman <[hidden email]> wrote:
Hi!
Today in the morning I updated the jars of my sonar 5.1 install with those
two files provided and restarted the machine. The result seems currently
much better than before. One or two jobs keep having some issues like
before, but it is much less often that the problem occurs.

Maybe for statistical issues:
- We are running about 20 JAVA-projects / -branches using much legacy code
and having about 1.2 million LOC and 130k issues.
- There are about 30 JAVA-projects module projects having 1k-20k LOC and
less than 2k issues.
- There are about 50 custom JAVA-projects having less than 200k LOC and less
than 10k issues.

All of these are managed by Jenkins and the Jenkins Sonar Runner Plugin. The
machine does not seem to have load issues. The failing issues are primarily
from group 1 (1.2m LOC/>125k Issues). But as shown in the log above mostly
the responses arrive after less than 5 seconds, even on these projects.

Is there a possibility to set the timeout for the project repository load
phase?



--
View this message in context: http://sonarqube.15.x6.nabble.com/Read-time-out-error-tp5034188p5035504.html
Sent from the SonarQube Users mailing list archive at Nabble.com.

---------------------------------------------------------------------
To unsubscribe from this list, please visit:

    http://xircles.codehaus.org/manage_email



Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

The_Gentleman
Thanks. I will report back, when the issue reoccurs.
Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

Julien Lancelot
Hi,

Did the patch I send you fix all timeouts ?
Thanks for your feedback.

Regards,

Julien LANCELOT | SonarSource

On 19 May 2015 at 12:41, The_Gentleman <[hidden email]> wrote:
Thanks. I will report back, when the issue reoccurs.



--
View this message in context: http://sonarqube.15.x6.nabble.com/Read-time-out-error-tp5034188p5035507.html
Sent from the SonarQube Users mailing list archive at Nabble.com.

---------------------------------------------------------------------
To unsubscribe from this list, please visit:

    http://xircles.codehaus.org/manage_email



Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

The_Gentleman
Hi!

One time the problem did occur shortly after the patch. The last two days I was waiting for the problem to reoccur. But it did not happen since. So it seems to be fixed. Thanks very much for your assistance!
Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

The_Gentleman
In reply to this post by Julien Lancelot
Too early.

One project seems to have additional issues:

INFO: SonarQube Server 5.1
13:09:50.083 INFO  - Load global repositories
13:09:51.215 INFO  - Load global repositories (done) | time=1150ms
13:09:51.225 INFO  - Server id: 20150519121856
13:09:51.234 INFO  - User cache: C:\.sonar\cache
13:09:51.275 INFO  - Install plugins
13:09:55.994 INFO  - Install JDBC driver
13:09:56.025 INFO  - Create JDBC datasource for jdbc:postgresql://localhost:5432/postgres
13:10:03.781 INFO  - Initializing Hibernate
13:10:12.687 INFO  - Load project repositories
INFO: ------------------------------------------------------------------------
INFO: EXECUTION FAILURE
INFO: ------------------------------------------------------------------------
Total time: 47.344s
Final Memory: 38M/269M
INFO: ------------------------------------------------------------------------
ERROR: Error during Sonar runner execution
org.sonar.runner.impl.RunnerException: Unable to execute Sonar
	at org.sonar.runner.impl.BatchLauncher$1.delegateExecution(BatchLauncher.java:91)
	at org.sonar.runner.impl.BatchLauncher$1.run(BatchLauncher.java:75)
	at java.security.AccessController.doPrivileged(Native Method)
	at org.sonar.runner.impl.BatchLauncher.doExecute(BatchLauncher.java:69)
	at org.sonar.runner.impl.BatchLauncher.execute(BatchLauncher.java:50)
	at org.sonar.runner.api.EmbeddedRunner.doExecute(EmbeddedRunner.java:102)
	at org.sonar.runner.api.Runner.execute(Runner.java:100)
	at org.sonar.runner.Main.executeTask(Main.java:70)
	at org.sonar.runner.Main.execute(Main.java:59)
	at org.sonar.runner.Main.main(Main.java:53)
Caused by: java.lang.IllegalStateException: Unable to request: /batch/project?key=project%3AmyBranch&preview=false

Here the logs of the 3 minutes before the timeout:
2015.05.21 13:07:45 DEBUG web[http] GET /api/issues/search?p=1&ps=50&s=FILE_LINE&asc=true&extra_fields=actions%2Ctransitions%2CassigneeName%2CreporterName%2CactionPlanName&facets=severities%2Cresolutions%2Cresolutions%2Cseverities&resolved=false&severities=BLOCKER&componentUuids=9bb3b3eb-4a8c-4588-9e1b-da05574b5899 | time=1412ms
2015.05.21 13:07:48 DEBUG web[http] GET /api/server/index | time=22ms
2015.05.21 13:07:48 TRACE web[sql] time=17ms | sql=SELECT * FROM "projects" WHERE ("projects"."kee" = 'project:project_server:src/main/config/***/server-standard-***.xml')  LIMIT 1
2015.05.21 13:07:48 DEBUG web[http] GET /api/resources?resource=project%3Aproject_server%3Asrc%2Fmain%2Fconfig%2F***%2Fserver-standard-***.xml&verbose=false& | time=47ms
2015.05.21 13:07:48 TRACE web[sql] time=1ms | sql=SELECT * FROM "projects" WHERE ("projects"."kee" = 'project:project_server')  LIMIT 1
2015.05.21 13:07:48 TRACE web[sql] time=1ms | sql=SELECT * FROM "snapshots" WHERE ("snapshots"."islast" = 't' AND "snapshots"."project_id" = 17902)  LIMIT 1
2015.05.21 13:07:48 TRACE web[sql] time=1ms | sql=SELECT resource_id FROM group_roles WHERE role='user' and group_id is null and resource_id in (17900)
2015.05.21 13:07:48 TRACE web[sql] time=2ms | sql=SELECT project_measures.id,project_measures.value,project_measures.metric_id,project_measures.snapshot_id,project_measures.rule_id,project_measures.rule_priority,project_measures.text_value,project_measures.characteristic_id,project_measures.measure_data FROM "project_measures"   INNER JOIN "snapshots" ON "snapshots".id = "project_measures".snapshot_id  WHERE (snapshots.islast='t' AND snapshots.id=8125864 AND project_measures.metric_id=-1 AND project_measures.person_id IS NULL AND project_measures.rule_id IS NULL AND project_measures.rule_priority IS NULL AND project_measures.characteristic_id IS NULL) 
2015.05.21 13:07:48 TRACE web[sql] time=1ms | sql=SELECT * FROM "snapshots" WHERE (snapshots.islast='t' AND snapshots.id=8125864) 
2015.05.21 13:07:48 TRACE web[sql] time=1ms | sql=SELECT * FROM "projects" WHERE ("projects"."id" = 17902) 
2015.05.21 13:07:48 DEBUG web[http] GET /api/resources?resource=project%3Aproject_server&metrics=&verbose=false& | time=45ms
2015.05.21 13:07:50 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.05.21 13:08:00 TRACE web[sql] time=2ms | 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.05.21 13:08:09 WARN   es[o.e.c.r.a.decider]  [sonar-1432030691761] high disk watermark [10%] exceeded on [UeytEteUQhCbYUzEJ3KKwg][sonar-1432030691761] free: 12.7gb[6.3%], shards will be relocated away from this node
2015.05.21 13:08:10 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.05.21 13:08:13 DEBUG web[o.s.p.l.LdapUsersProvider] Requesting details for user 
2015.05.21 13:08:13 DEBUG web[o.s.p.l.LdapSearch] Search: LdapSearch{baseDn=DC=GOETTINGEN,DC=***,DC=local, scope=subtree, request=(&(objectClass=user)(sAMAccountName={0})), parameters=[], attributes=[mail, name]}
2015.05.21 13:08:13 DEBUG web[o.s.p.l.LdapContextFactory] Initializing LDAP context {java.naming.provider.url=ldap://***dc01/, java.naming.factory.initial=com.sun.jndi.ldap.LdapCtxFactory, java.naming.security.principal=cn=LDAP\, Reader,OU=SBSUsers,OU=Users,OU=MyBusiness,DC=GOETTINGEN,DC=***,DC=local, com.sun.jndi.ldap.connect.pool=true, java.naming.security.authentication=simple, java.naming.security.sasl.realm=goettingen.***.local, java.naming.referral=follow}
2015.05.21 13:08:14 DEBUG web[o.s.p.l.LdapUsersProvider] User  not found in <default>
2015.05.21 13:08:14 DEBUG web[o.s.p.l.LdapUsersProvider] Requesting details for user 
2015.05.21 13:08:14 DEBUG web[o.s.p.l.LdapSearch] Search: LdapSearch{baseDn=DC=GOETTINGEN,DC=***,DC=local, scope=subtree, request=(&(objectClass=user)(sAMAccountName={0})), parameters=[], attributes=[mail, name]}
2015.05.21 13:08:14 DEBUG web[o.s.p.l.LdapContextFactory] Initializing LDAP context {java.naming.provider.url=ldap://***dc01/, java.naming.factory.initial=com.sun.jndi.ldap.LdapCtxFactory, java.naming.security.principal=cn=LDAP\, Reader,OU=SBSUsers,OU=Users,OU=MyBusiness,DC=GOETTINGEN,DC=***,DC=local, com.sun.jndi.ldap.connect.pool=true, java.naming.security.authentication=simple, java.naming.security.sasl.realm=goettingen.***.local, java.naming.referral=follow}
2015.05.21 13:08:15 DEBUG web[o.s.p.l.LdapUsersProvider] User  not found in <default>
2015.05.21 13:08:15 TRACE web[sql] time=5ms | sql=SELECT * FROM "rules" WHERE (plugin_name='findbugs' and plugin_rule_key='OBL_UNSATISFIED_OBLIGATION_EXCEPTION_EDGE')  LIMIT 1
2015.05.21 13:08:15 DEBUG web[o.s.p.l.LdapUsersProvider] Requesting details for user 
2015.05.21 13:08:15 DEBUG web[o.s.p.l.LdapSearch] Search: LdapSearch{baseDn=DC=GOETTINGEN,DC=***,DC=local, scope=subtree, request=(&(objectClass=user)(sAMAccountName={0})), parameters=[], attributes=[mail, name]}
2015.05.21 13:08:15 DEBUG web[o.s.p.l.LdapContextFactory] Initializing LDAP context {java.naming.provider.url=ldap://***dc01/, java.naming.factory.initial=com.sun.jndi.ldap.LdapCtxFactory, java.naming.security.principal=cn=LDAP\, Reader,OU=SBSUsers,OU=Users,OU=MyBusiness,DC=GOETTINGEN,DC=***,DC=local, com.sun.jndi.ldap.connect.pool=true, java.naming.security.authentication=simple, java.naming.security.sasl.realm=goettingen.***.local, java.naming.referral=follow}
2015.05.21 13:08:16 DEBUG web[o.s.p.l.LdapUsersProvider] User  not found in <default>
2015.05.21 13:08:16 DEBUG web[o.s.p.l.LdapUsersProvider] Requesting details for user 
2015.05.21 13:08:16 DEBUG web[o.s.p.l.LdapSearch] Search: LdapSearch{baseDn=DC=GOETTINGEN,DC=***,DC=local, scope=subtree, request=(&(objectClass=user)(sAMAccountName={0})), parameters=[], attributes=[mail, name]}
2015.05.21 13:08:16 DEBUG web[o.s.p.l.LdapContextFactory] Initializing LDAP context {java.naming.provider.url=ldap://***dc01/, java.naming.factory.initial=com.sun.jndi.ldap.LdapCtxFactory, java.naming.security.principal=cn=LDAP\, Reader,OU=SBSUsers,OU=Users,OU=MyBusiness,DC=GOETTINGEN,DC=***,DC=local, com.sun.jndi.ldap.connect.pool=true, java.naming.security.authentication=simple, java.naming.security.sasl.realm=goettingen.***.local, java.naming.referral=follow}
2015.05.21 13:08:17 DEBUG web[o.s.p.l.LdapUsersProvider] User  not found in <default>
2015.05.21 13:08:17 DEBUG web[o.s.p.l.LdapUsersProvider] Requesting details for user 
2015.05.21 13:08:17 DEBUG web[o.s.p.l.LdapSearch] Search: LdapSearch{baseDn=DC=GOETTINGEN,DC=***,DC=local, scope=subtree, request=(&(objectClass=user)(sAMAccountName={0})), parameters=[], attributes=[mail, name]}
2015.05.21 13:08:17 DEBUG web[o.s.p.l.LdapContextFactory] Initializing LDAP context {java.naming.provider.url=ldap://***dc01/, java.naming.factory.initial=com.sun.jndi.ldap.LdapCtxFactory, java.naming.security.principal=cn=LDAP\, Reader,OU=SBSUsers,OU=Users,OU=MyBusiness,DC=GOETTINGEN,DC=***,DC=local, com.sun.jndi.ldap.connect.pool=true, java.naming.security.authentication=simple, java.naming.security.sasl.realm=goettingen.***.local, java.naming.referral=follow}
2015.05.21 13:08:18 DEBUG web[o.s.p.l.LdapUsersProvider] User  not found in <default>
2015.05.21 13:08:18 DEBUG web[o.s.p.l.LdapUsersProvider] Requesting details for user 
2015.05.21 13:08:18 DEBUG web[o.s.p.l.LdapSearch] Search: LdapSearch{baseDn=DC=GOETTINGEN,DC=***,DC=local, scope=subtree, request=(&(objectClass=user)(sAMAccountName={0})), parameters=[], attributes=[mail, name]}
2015.05.21 13:08:18 DEBUG web[o.s.p.l.LdapContextFactory] Initializing LDAP context {java.naming.provider.url=ldap://***dc01/, java.naming.factory.initial=com.sun.jndi.ldap.LdapCtxFactory, java.naming.security.principal=cn=LDAP\, Reader,OU=SBSUsers,OU=Users,OU=MyBusiness,DC=GOETTINGEN,DC=***,DC=local, com.sun.jndi.ldap.connect.pool=true, java.naming.security.authentication=simple, java.naming.security.sasl.realm=goettingen.***.local, java.naming.referral=follow}
2015.05.21 13:08:19 DEBUG web[o.s.p.l.LdapUsersProvider] User  not found in <default>
2015.05.21 13:08:19 TRACE web[sql] time=2ms | sql=SELECT * FROM "active_dashboards" WHERE ("active_dashboards"."user_id" IS NULL)  ORDER BY order_index
2015.05.21 13:08:19 TRACE web[sql] time=1ms | sql=SELECT * FROM "dashboards" WHERE ("dashboards"."id" IN (1,5,2,3,4)) 
2015.05.21 13:08:19 TRACE web[sql] time=1ms | sql=SELECT * FROM "properties" WHERE ((("properties"."resource_id" IS NULL AND "properties"."user_id" IS NULL)) AND ("properties"."prop_key" = 'sonar.lf.logoUrl'))  LIMIT 1
2015.05.21 13:08:19 TRACE web[sql] time=1ms | sql=SELECT * FROM "properties" WHERE ((("properties"."resource_id" IS NULL AND "properties"."user_id" IS NULL)) AND ("properties"."prop_key" = 'sonar.lf.logoWidthPx'))  LIMIT 1
2015.05.21 13:08:19 DEBUG web[o.s.p.l.LdapUsersProvider] Requesting details for user 
2015.05.21 13:08:19 DEBUG web[o.s.p.l.LdapSearch] Search: LdapSearch{baseDn=DC=GOETTINGEN,DC=***,DC=local, scope=subtree, request=(&(objectClass=user)(sAMAccountName={0})), parameters=[], attributes=[mail, name]}
2015.05.21 13:08:19 DEBUG web[o.s.p.l.LdapContextFactory] Initializing LDAP context {java.naming.provider.url=ldap://***dc01/, java.naming.factory.initial=com.sun.jndi.ldap.LdapCtxFactory, java.naming.security.principal=cn=LDAP\, Reader,OU=SBSUsers,OU=Users,OU=MyBusiness,DC=G***,DC=***,DC=local, com.sun.jndi.ldap.connect.pool=true, java.naming.security.authentication=simple, java.naming.security.sasl.realm=g***.***.local, java.naming.referral=follow}
2015.05.21 13:08:20 TRACE web[sql] time=2ms | 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.05.21 13:08:20 DEBUG web[o.s.p.l.LdapUsersProvider] User  not found in <default>
2015.05.21 13:08:20 TRACE web[sql] time=2ms | sql=SELECT role FROM "group_roles" WHERE (resource_id is null and group_id is null) 
2015.05.21 13:08:20 DEBUG web[http] GET /rules/show/findbugs:OBL_UNSATISFIED_OBLIGATION_EXCEPTION_EDGE?layout=false | time=7149ms
2015.05.21 13:08:20 DEBUG web[http] GET /css/sonar.css?v=5.1 | time=1ms
2015.05.21 13:08:20 DEBUG web[http] GET /js/require.js?v=5.1 | time=1ms
2015.05.21 13:08:20 DEBUG web[http] GET /js/sonar.js?v=5.1 | time=0ms
2015.05.21 13:08:20 DEBUG web[http] GET /fonts/Roboto-Light-webfont.eot? | time=0ms
2015.05.21 13:08:20 DEBUG web[http] GET /fonts/Roboto-Regular-webfont.eot? | time=0ms
2015.05.21 13:08:20 DEBUG web[http] GET /fonts/Roboto-Medium-webfont.eot? | time=1ms
2015.05.21 13:08:21 DEBUG web[http] GET /fonts/Roboto-Bold-webfont.eot? | time=0ms
2015.05.21 13:08:21 DEBUG web[http] GET /js/nav/app.js?v=5.1 | time=1ms
2015.05.21 13:08:21 DEBUG web[http] GET /api/l10n/index?locale=de&ts=2015-05-21T10%3A59%3A12%2B0000 | time=20ms
2015.05.21 13:08:22 TRACE web[sql] time=16ms | sql=select id, data    from notifications    order by id asc    limit ?
2015.05.21 13:08:30 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.05.21 13:08:39 WARN   es[o.e.c.r.a.decider]  [sonar-1432030691761] high disk watermark [10%] exceeded on [UeytEteUQhCbYUzEJ3KKwg][sonar-1432030691761] free: 12.5gb[6.2%], shards will be relocated away from this node
2015.05.21 13:08:40 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.05.21 13:08:50 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.05.21 13:09:00 TRACE web[sql] time=2ms | 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.05.21 13:09:09 WARN   es[o.e.c.r.a.decider]  [sonar-1432030691761] high disk watermark [10%] exceeded on [UeytEteUQhCbYUzEJ3KKwg][sonar-1432030691761] free: 12.5gb[6.2%], shards will be relocated away from this node
2015.05.21 13:09:09 INFO   es[o.e.c.r.a.decider]  [sonar-1432030691761] high disk watermark exceeded on one or more nodes, rerouting shards
2015.05.21 13:09:10 TRACE web[sql] time=2ms | 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.05.21 13:09:20 TRACE web[sql] time=2ms | 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.05.21 13:09:22 TRACE web[sql] time=1ms | sql=select id, data    from notifications    order by id asc    limit ?
2015.05.21 13:09:30 TRACE web[sql] time=2ms | 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.05.21 13:09:35 TRACE web[sql] time=63ms | sql=SELECT * FROM "projects" WHERE ("projects"."kee" = 'project:project_client:src/main/java/com/myCompany/project/client/mvc/document/c***/modules/e***/e***BestellPanel.java')  LIMIT 1
2015.05.21 13:09:35 DEBUG web[http] GET /api/resources?resource=project%3Aproject_client%3Asrc%2Fmain%2Fjava%2Fcom%2FmyCompany%2Fproject%2Fclient%2Fmvc%2Fdocument%2Fc***%2Fmodules%2Fe***%2Fe***BestellPanel.java&verbose=false& | time=93ms
2015.05.21 13:09:35 TRACE web[sql] time=16ms | sql=SELECT * FROM "projects" WHERE ("projects"."kee" = 'project:project_client')  LIMIT 1
2015.05.21 13:09:35 TRACE web[sql] time=104ms | sql=SELECT * FROM "snapshots" WHERE ("snapshots"."islast" = 't' AND "snapshots"."project_id" = 17903)  LIMIT 1
2015.05.21 13:09:35 TRACE web[sql] time=1ms | sql=SELECT resource_id FROM group_roles WHERE role='user' and group_id is null and resource_id in (17900)
2015.05.21 13:09:35 TRACE web[sql] time=10ms | sql=SELECT project_measures.id,project_measures.value,project_measures.metric_id,project_measures.snapshot_id,project_measures.rule_id,project_measures.rule_priority,project_measures.text_value,project_measures.characteristic_id,project_measures.measure_data FROM "project_measures"   INNER JOIN "snapshots" ON "snapshots".id = "project_measures".snapshot_id  WHERE (snapshots.islast='t' AND snapshots.id=8125862 AND project_measures.metric_id=-1 AND project_measures.person_id IS NULL AND project_measures.rule_id IS NULL AND project_measures.rule_priority IS NULL AND project_measures.characteristic_id IS NULL) 
2015.05.21 13:09:35 TRACE web[sql] time=1ms | sql=SELECT * FROM "snapshots" WHERE (snapshots.islast='t' AND snapshots.id=8125862) 
2015.05.21 13:09:35 TRACE web[sql] time=1ms | sql=SELECT * FROM "projects" WHERE ("projects"."id" = 17903) 
2015.05.21 13:09:35 DEBUG web[http] GET /api/resources?resource=project%3Aproject_client&metrics=&verbose=false& | time=178ms
2015.05.21 13:09:39 WARN   es[o.e.c.r.a.decider]  [sonar-1432030691761] high disk watermark [10%] exceeded on [UeytEteUQhCbYUzEJ3KKwg][sonar-1432030691761] free: 12.3gb[6.1%], shards will be relocated away from this node
2015.05.21 13:09:40 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.05.21 13:09:45 DEBUG web[http] GET /api/server/version | time=20ms
2015.05.21 13:09:46 DEBUG web[http] GET /batch_bootstrap/index | time=20ms
2015.05.21 13:09:50 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.05.21 13:09:50 TRACE web[sql] time=2ms | sql=SELECT * FROM "users" WHERE (login='admin' AND active='t')  LIMIT 1
2015.05.21 13:09:50 TRACE web[sql] time=1ms | sql=SELECT * FROM "groups"  INNER JOIN "groups_users" ON "groups".id = "groups_users".group_id WHERE ("groups_users".user_id = 1 ) 
2015.05.21 13:09:50 TRACE web[sql] time=10ms | sql=SELECT gr.role        FROM group_roles gr        INNER JOIN groups_users gu on gu.group_id=gr.group_id        INNER JOIN users u on u.id=gu.user_id         WHERE  u.login=?          and gr.resource_id is null         UNION        SELECT gr.role        FROM group_roles gr        WHERE gr.group_id IS NULL AND gr.resource_id IS NULL        UNION        SELECT ur.role        FROM user_roles ur        INNER JOIN users u on u.id=ur.user_id         WHERE  u.login=?          and ur.resource_id is null
2015.05.21 13:09:50 TRACE web[sql] time=4ms | sql=SELECT         m.id,    m.name,    m.val_type as valueType,    m.description,    m.direction,    m.qualitative,    m.user_managed as userManaged,    m.worst_value as worstValue,    m.best_value as bestValue,    m.optimized_best_value as optimizedBestValue,    m.enabled       FROM metrics m     WHERE  m.enabled=true
2015.05.21 13:09:50 TRACE web[sql] time=2ms | sql=select p.id as id, p.prop_key as "key", p.text_value as value, p.resource_id as resourceId, p.user_id as userId    from properties p    where p.resource_id is null and p.user_id is null
2015.05.21 13:09:50 DEBUG web[http] GET /batch/global | time=64ms
2015.05.21 13:09:51 DEBUG web[http] GET /deploy/plugins/index.txt | time=1ms
2015.05.21 13:09:55 DEBUG web[http] GET /deploy/jdbc-driver.txt | time=2ms
2015.05.21 13:09:58 TRACE web[sql] time=1ms | sql=SELECT * FROM "users" WHERE ("users"."remember_token" = '337a17ee3a3ed29f6aa1aa79777c3e9ad0c3d8fd')  LIMIT 1
2015.05.21 13:09:58 TRACE web[sql] time=1ms | sql=UPDATE "users" SET "remember_token" = 'c8195cec6829e467ef1bac5916b7aa2f77b64eb1' WHERE "id" = 25
2015.05.21 13:09:58 TRACE web[sql] time=1ms | sql=SELECT * FROM "groups"  INNER JOIN "groups_users" ON "groups".id = "groups_users".group_id WHERE ("groups_users".user_id = 25 ) 
2015.05.21 13:09:58 TRACE web[es] ES get request for key 'findbugs:OBL_UNSATISFIED_OBLIGATION_EXCEPTION_EDGE' on index 'rules' on type 'rule' | time=2ms
2015.05.21 13:09:58 DEBUG web[http] GET /api/rules/show?key=findbugs%3AOBL_UNSATISFIED_OBLIGATION_EXCEPTION_EDGE | time=42ms
2015.05.21 13:10:00 TRACE web[sql] time=2ms | 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.05.21 13:10:00 TRACE web[sql] time=2ms | sql=SELECT * FROM "projects" WHERE ("projects"."kee" = 'r***:r***_server:src/main/config/server-r***-vm.xml')  LIMIT 1
2015.05.21 13:10:00 DEBUG web[http] GET /api/resources?resource=r***%3Ar***_server%3Asrc%2Fmain%2Fconfig%2Fserver-r***-vm.xml&verbose=false& | time=29ms
2015.05.21 13:10:01 TRACE web[sql] time=1ms | sql=SELECT * FROM "projects" WHERE ("projects"."kee" = 'r***:r***_server')  LIMIT 1
2015.05.21 13:10:01 TRACE web[sql] time=1ms | sql=SELECT * FROM "snapshots" WHERE ("snapshots"."islast" = 't' AND "snapshots"."project_id" = 30908)  LIMIT 1
2015.05.21 13:10:01 TRACE web[sql] time=1ms | sql=SELECT resource_id FROM group_roles WHERE role='user' and group_id is null and resource_id in (30906)
2015.05.21 13:10:01 TRACE web[sql] time=2ms | sql=SELECT project_measures.id,project_measures.value,project_measures.metric_id,project_measures.snapshot_id,project_measures.rule_id,project_measures.rule_priority,project_measures.text_value,project_measures.characteristic_id,project_measures.measure_data FROM "project_measures"   INNER JOIN "snapshots" ON "snapshots".id = "project_measures".snapshot_id  WHERE (snapshots.islast='t' AND snapshots.id=8252225 AND project_measures.metric_id=-1 AND project_measures.person_id IS NULL AND project_measures.rule_id IS NULL AND project_measures.rule_priority IS NULL AND project_measures.characteristic_id IS NULL) 
2015.05.21 13:10:01 TRACE web[sql] time=1ms | sql=SELECT * FROM "snapshots" WHERE (snapshots.islast='t' AND snapshots.id=8252225) 
2015.05.21 13:10:01 TRACE web[sql] time=1ms | sql=SELECT * FROM "projects" WHERE ("projects"."id" = 30908) 
2015.05.21 13:10:01 DEBUG web[http] GET /api/resources?resource=r***%3Ar***_server&metrics=&verbose=false& | time=50ms
2015.05.21 13:10:03 TRACE web[sql] time=2ms | sql=SELECT * FROM "users" WHERE (login='admin' AND active='t')  LIMIT 1
2015.05.21 13:10:03 TRACE web[sql] time=0ms | sql=SELECT * FROM "groups"  INNER JOIN "groups_users" ON "groups".id = "groups_users".group_id WHERE ("groups_users".user_id = 1 ) 
2015.05.21 13:10:03 DEBUG web[http] GET /api/server | time=32ms
2015.05.21 13:10:09 WARN   es[o.e.c.r.a.decider]  [sonar-1432030691761] high disk watermark [10%] exceeded on [UeytEteUQhCbYUzEJ3KKwg][sonar-1432030691761] free: 12.2gb[6.1%], shards will be relocated away from this node
2015.05.21 13:10:10 TRACE web[sql] time=2ms | 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.05.21 13:10:12 TRACE web[sql] time=2ms | sql=SELECT * FROM "users" WHERE (login='admin' AND active='t')  LIMIT 1
2015.05.21 13:10:12 TRACE web[sql] time=2ms | sql=SELECT * FROM "groups"  INNER JOIN "groups_users" ON "groups".id = "groups_users".group_id WHERE ("groups_users".user_id = 1 ) 
2015.05.21 13:10:12 TRACE web[sql] time=3ms | sql=SELECT gr.role        FROM group_roles gr        INNER JOIN groups_users gu on gu.group_id=gr.group_id        INNER JOIN users u on u.id=gu.user_id         WHERE  u.login=?          and gr.resource_id is null         UNION        SELECT gr.role        FROM group_roles gr        WHERE gr.group_id IS NULL AND gr.resource_id IS NULL        UNION        SELECT ur.role        FROM user_roles ur        INNER JOIN users u on u.id=ur.user_id         WHERE  u.login=?          and ur.resource_id is null
2015.05.21 13:10:12 TRACE web[sql] time=26ms | 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.05.21 13:10:14 TRACE web[sql] time=1555ms | 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 module ON module.project_uuid = p.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.05.21 13:10:14 TRACE web[sql] time=6ms | 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 module ON module.project_uuid = p.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.05.21 13:10:20 TRACE web[sql] time=3ms | sql=SELECT * FROM "projects" WHERE ("projects"."kee" = 'r***:r***_server:src/main/config/server-r***-***-internal.xml')  LIMIT 1
2015.05.21 13:10:20 DEBUG web[http] GET /api/resources?resource=r***%3Ar***_server%3Asrc%2Fmain%2Fconfig%2Fserver-r***-***-internal.xml&verbose=false& | time=37ms
2015.05.21 13:10:20 TRACE web[sql] time=1ms | sql=SELECT * FROM "projects" WHERE ("projects"."kee" = 'r***:r***_server')  LIMIT 1
2015.05.21 13:10:20 TRACE web[sql] time=1ms | sql=SELECT * FROM "snapshots" WHERE ("snapshots"."islast" = 't' AND "snapshots"."project_id" = 30908)  LIMIT 1
2015.05.21 13:10:20 TRACE web[sql] time=2ms | sql=SELECT resource_id FROM group_roles WHERE role='user' and group_id is null and resource_id in (30906)
2015.05.21 13:10:20 TRACE web[sql] time=2ms | sql=SELECT project_measures.id,project_measures.value,project_measures.metric_id,project_measures.snapshot_id,project_measures.rule_id,project_measures.rule_priority,project_measures.text_value,project_measures.characteristic_id,project_measures.measure_data FROM "project_measures"   INNER JOIN "snapshots" ON "snapshots".id = "project_measures".snapshot_id  WHERE (snapshots.islast='t' AND snapshots.id=8252225 AND project_measures.metric_id=-1 AND project_measures.person_id IS NULL AND project_measures.rule_id IS NULL AND project_measures.rule_priority IS NULL AND project_measures.characteristic_id IS NULL) 
2015.05.21 13:10:20 TRACE web[sql] time=2ms | sql=SELECT * FROM "snapshots" WHERE (snapshots.islast='t' AND snapshots.id=8252225) 
2015.05.21 13:10:20 TRACE web[sql] time=1ms | sql=SELECT * FROM "projects" WHERE ("projects"."id" = 30908) 
2015.05.21 13:10:20 DEBUG web[http] GET /api/resources?resource=r***%3Ar***_server&metrics=&verbose=false& | time=47ms
2015.05.21 13:10:20 TRACE web[sql] time=2ms | 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.05.21 13:10:22 TRACE web[sql] time=1ms | sql=select id, data    from notifications    order by id asc    limit ?
2015.05.21 13:10:30 TRACE web[sql] time=2ms | 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.05.21 13:10:34 TRACE web[sql] time=2ms | sql=SELECT * FROM "projects" WHERE ("projects"."kee" = 'r***:r***_webclient')  LIMIT 1
2015.05.21 13:10:34 TRACE web[sql] time=2ms | sql=SELECT * FROM "snapshots" WHERE ("snapshots"."islast" = 't' AND "snapshots"."project_id" = 30910)  LIMIT 1
2015.05.21 13:10:34 TRACE web[sql] time=2ms | sql=SELECT resource_id FROM group_roles WHERE role='user' and group_id is null and resource_id in (30906)
2015.05.21 13:10:34 TRACE web[sql] time=2ms | sql=SELECT project_measures.id,project_measures.value,project_measures.metric_id,project_measures.snapshot_id,project_measures.rule_id,project_measures.rule_priority,project_measures.text_value,project_measures.characteristic_id,project_measures.measure_data FROM "project_measures"   INNER JOIN "snapshots" ON "snapshots".id = "project_measures".snapshot_id  WHERE (snapshots.islast='t' AND snapshots.id=8252224 AND project_measures.metric_id=-1 AND project_measures.person_id IS NULL AND project_measures.rule_id IS NULL AND project_measures.rule_priority IS NULL AND project_measures.characteristic_id IS NULL) 
2015.05.21 13:10:34 TRACE web[sql] time=1ms | sql=SELECT * FROM "snapshots" WHERE (snapshots.islast='t' AND snapshots.id=8252224) 
2015.05.21 13:10:34 TRACE web[sql] time=1ms | sql=SELECT * FROM "projects" WHERE ("projects"."id" = 30910) 
2015.05.21 13:10:34 DEBUG web[http] GET /api/resources?resource=r***%3Ar***_webclient&metrics=&verbose=false& | time=52ms
2015.05.21 13:10:34 TRACE web[sql] time=13ms | sql=SELECT * FROM "projects" WHERE ("projects"."kee" = 'r***:r***_webclient:src/main/java/com/myCompany/project/web/r***/server/r***RemoteServiceImpl.java')  LIMIT 1
2015.05.21 13:10:34 DEBUG web[http] GET /api/resources?resource=r***%3Ar***_webclient%3Asrc%2Fmain%2Fjava%2Fcom%2FmyCompany%2Fproject%2Fweb%2Fr***%2Fserver%2Fr***RemoteServiceImpl.java&verbose=false& | time=42ms
2015.05.21 13:10:34 TRACE web[sql] time=2ms | sql=SELECT * FROM "projects" WHERE ("projects"."kee" = 'r***:r***_webclient')  LIMIT 1
2015.05.21 13:10:34 TRACE web[sql] time=1ms | sql=SELECT * FROM "snapshots" WHERE ("snapshots"."islast" = 't' AND "snapshots"."project_id" = 30910)  LIMIT 1
2015.05.21 13:10:34 TRACE web[sql] time=1ms | sql=SELECT resource_id FROM group_roles WHERE role='user' and group_id is null and resource_id in (30906)
2015.05.21 13:10:34 TRACE web[sql] time=1ms | sql=SELECT project_measures.id,project_measures.value,project_measures.metric_id,project_measures.snapshot_id,project_measures.rule_id,project_measures.rule_priority,project_measures.text_value,project_measures.characteristic_id,project_measures.measure_data FROM "project_measures"   INNER JOIN "snapshots" ON "snapshots".id = "project_measures".snapshot_id  WHERE (snapshots.islast='t' AND snapshots.id=8252224 AND project_measures.metric_id=-1 AND project_measures.person_id IS NULL AND project_measures.rule_id IS NULL AND project_measures.rule_priority IS NULL AND project_measures.characteristic_id IS NULL) 
2015.05.21 13:10:34 TRACE web[sql] time=1ms | sql=SELECT * FROM "snapshots" WHERE (snapshots.islast='t' AND snapshots.id=8252224) 
2015.05.21 13:10:34 TRACE web[sql] time=1ms | sql=SELECT * FROM "projects" WHERE ("projects"."id" = 30910) 
2015.05.21 13:10:34 DEBUG web[http] GET /api/resources?resource=r***%3Ar***_webclient&metrics=&verbose=false& | time=43ms
2015.05.21 13:10:39 WARN   es[o.e.c.r.a.decider]  [sonar-1432030691761] high disk watermark [10%] exceeded on [UeytEteUQhCbYUzEJ3KKwg][sonar-1432030691761] free: 12.2gb[6.1%], shards will be relocated away from this node
2015.05.21 13:10:39 INFO   es[o.e.c.r.a.decider]  [sonar-1432030691761] high disk watermark exceeded on one or more nodes, rerouting shards
2015.05.21 13:10:39 TRACE web[sql] time=2ms | sql=SELECT * FROM "projects" WHERE ("projects"."kee" = 'r***:r***_webclient')  LIMIT 1
2015.05.21 13:10:39 TRACE web[sql] time=1ms | sql=SELECT * FROM "snapshots" WHERE ("snapshots"."islast" = 't' AND "snapshots"."project_id" = 30910)  LIMIT 1
2015.05.21 13:10:39 TRACE web[sql] time=1ms | sql=SELECT resource_id FROM group_roles WHERE role='user' and group_id is null and resource_id in (30906)
2015.05.21 13:10:39 TRACE web[sql] time=2ms | sql=SELECT project_measures.id,project_measures.value,project_measures.metric_id,project_measures.snapshot_id,project_measures.rule_id,project_measures.rule_priority,project_measures.text_value,project_measures.characteristic_id,project_measures.measure_data FROM "project_measures"   INNER JOIN "snapshots" ON "snapshots".id = "project_measures".snapshot_id  WHERE (snapshots.islast='t' AND snapshots.id=8252224 AND project_measures.metric_id=-1 AND project_measures.person_id IS NULL AND project_measures.rule_id IS NULL AND project_measures.rule_priority IS NULL AND project_measures.characteristic_id IS NULL) 
2015.05.21 13:10:39 TRACE web[sql] time=0ms | sql=SELECT * FROM "snapshots" WHERE (snapshots.islast='t' AND snapshots.id=8252224) 
2015.05.21 13:10:39 TRACE web[sql] time=1ms | sql=SELECT * FROM "projects" WHERE ("projects"."id" = 30910) 
2015.05.21 13:10:39 DEBUG web[http] GET /api/resources?resource=r***%3Ar***_webclient&metrics=&verbose=false& | time=49ms
2015.05.21 13:10:40 TRACE web[sql] time=2ms | 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.05.21 13:10:41 TRACE web[sql] time=26774ms | 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     WHERE  p.project_uuid=?      AND p.enabled=true      AND p.scope='FIL'
2015.05.21 13:10:41 TRACE web[sql] time=4ms | 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.05.21 13:10:41 TRACE web[sql] time=2ms | 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.05.21 13:10:41 TRACE web[sql] time=2ms | 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.05.21 13:10:41 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.05.21 13:10:41 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":["grvy"]}}]}}}},"_source":{"includes":["templateKey","internalKey","name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=4ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@6ada4baf' | time=19ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@219f04f7' | time=19ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@4eff055f' | time=20ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@48b9c87d' | time=9ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@1d19abd8' | time=1ms
2015.05.21 13:10:41 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"grvy-sonar-way-84469"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=3ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@6100d5' | time=6ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@607da024' | time=2ms
2015.05.21 13:10:41 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=2ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@579cccf9' | time=16ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@77551c5e' | time=17ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@74b99c86' | time=16ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@45d35d48' | time=19ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@379145c8' | time=14ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@227c69f8' | time=19ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@16112a78' | time=19ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@40fb3799' | time=15ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2cff3f23' | time=18ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@298a29e9' | time=18ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2dc5954d' | time=16ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@6cd09b5' | time=18ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@7f384d8f' | time=17ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@496c2889' | time=20ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@5bd96007' | time=7ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@1c2f9aad' | time=1ms
2015.05.21 13:10:41 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"java-dirk-profile-42934"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=4ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@7953e24f' | time=7ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@722eeec3' | time=8ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@673d7ec5' | time=7ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@764dec76' | time=7ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@75df73a7' | time=9ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@21221566' | time=3ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@44aaec88' | time=1ms
2015.05.21 13:10:41 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=3ms
2015.05.21 13:10:41 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@318ef7f0' | time=1ms
2015.05.21 13:10:41 DEBUG web[http] GET /batch/project?key=project%3AmyBranch&amp;preview=false | time=28977ms
Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

Julien Lancelot
Hi,

Ok could you then execute the following SQL queries : 
  • SELECT uuid FROM projects WHERE kee=<PROJECT KEY> (Replace <PROJECT KEY> by the key of your project)
  • EXPLAIN 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 WHERE p.project_uuid=<PROJECT UUID> AND p.enabled=true AND p.scope='FIL' (replace <PROJECT UUID> by uuid returned by previous query)
And send me the result of the explain plan.

Then, could you also execute the 2 following queries to rebuild the index :
  • OPTIMIZE TABLE projects;
  • OPTIMIZE TABLE file_sources;
And relaunch the analysis, and tell me of the timeout is still there.

Thanks.

Regards,

Julien LANCELOT | SonarSource

On 21 May 2015 at 13:38, The_Gentleman <[hidden email]> wrote:
Too early.

One project seems to have additional issues:



Here the logs of the 3 minutes before the timeout:




--
View this message in context: http://sonarqube.15.x6.nabble.com/Read-time-out-error-tp5034188p5035582.html
Sent from the SonarQube Users mailing list archive at Nabble.com.

---------------------------------------------------------------------
To unsubscribe from this list, please visit:

    http://xircles.codehaus.org/manage_email



Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

The_Gentleman
Here is the query plan for the project always throwing the timeout. Some others throw it one time, another time they work fine. This project always has the issue.
 
postgres=# EXPLAIN SELECT p.uuid, p.path, p.module_uuid as moduleUuid, fs.src_ha
sh FROM projects p INNER JOIN file_sources fs ON fs.file_uuid=p.uuid WHERE p.pro
ject_uuid='5c4fb6d7-5c00-41f6-ba14-c748b801acf2' AND p.enabled=true AND p.scope=
'FIL';
                                                QUERY PLAN

----------------------------------------------------------------------------------------------------------
 Nested Loop  (cost=563.59..65469.29 rows=5518 width=194)
   ->  Bitmap Heap Scan on projects p  (cost=563.59..18044.95 rows=6523 width=161)
         Recheck Cond: ((project_uuid)::text = '5c4fb6d7-5c00-41f6-ba14-c748b801acf2'::text)
         Filter: (enabled AND ((scope)::text = 'FIL'::text))
         ->  Bitmap Index Scan on projects_project_uuid  (cost=0.00..561.96 rows=7678 width=0)
               Index Cond: ((project_uuid)::text = '5c4fb6d7-5c00-41f6-ba14-c748b801acf2'::text)
   ->  Index Scan using file_sources_file_uuid_uniq on file_sources fs  (cost=0.00..7.26 rows=1 width=70)
         Index Cond: ((file_uuid)::text = (p.uuid)::text)
(8 Zeilen)

The 'OPTIMIZE TABLE' instruction is not found for my PostGre 9.1 installation:
postgres=# OPTIMIZE TABLE projects;
ERROR:  syntax error at or near "OPTIMIZE"
ZEILE 1: OPTIMIZE TABLE projects;
I think the equivalent in Postgre will be 'VACUUM ;'.

Because of analysis on these tables a FREEZE (exclusive DB lock) will be needed for the vacuum job, so I will need to do this Tuesday in the morning. I'll report the results of the VACUUM then.
Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

Julien Lancelot
Opus, indeed it's not "OPTIMIZE TABLE" (it's for MySQL) but "REINDEX TABLE projects" and REINDEX TABLE file_sources" (as VACUUM will not rebuild the table indexes).

I'll wait for tuesday if rebuilding the indexes has fix this issue, in the meantime I will analyse the execution plan.



Julien LANCELOT | SonarSource

On 22 May 2015 at 12:12, The_Gentleman <[hidden email]> wrote:
Here is the query plan for the project always throwing the timeout. Some
others throw it one time, another time they work fine. This project always
has the issue.



The 'OPTIMIZE TABLE' instruction is not found for my PostGre 9.1
installation:

I think the equivalent in Postgre will be 'VACUUM
;'.

Because of analysis on these tables a FREEZE (exclusive DB lock) will be
needed for the vacuum job, so I will need to do this Tuesday in the morning.
I'll report the results of the VACUUM then.



--
View this message in context: http://sonarqube.15.x6.nabble.com/Read-time-out-error-tp5034188p5035606.html
Sent from the SonarQube Users mailing list archive at Nabble.com.

---------------------------------------------------------------------
To unsubscribe from this list, please visit:

    http://xircles.codehaus.org/manage_email



Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

Julien Lancelot
I forget to ask you which version of postgresql you're using.
Thanks.

Julien LANCELOT | SonarSource

On 22 May 2015 at 12:33, Julien Lancelot <[hidden email]> wrote:
Opus, indeed it's not "OPTIMIZE TABLE" (it's for MySQL) but "REINDEX TABLE projects" and REINDEX TABLE file_sources" (as VACUUM will not rebuild the table indexes).

I'll wait for tuesday if rebuilding the indexes has fix this issue, in the meantime I will analyse the execution plan.



Julien LANCELOT | SonarSource

On 22 May 2015 at 12:12, The_Gentleman <[hidden email]> wrote:
Here is the query plan for the project always throwing the timeout. Some
others throw it one time, another time they work fine. This project always
has the issue.



The 'OPTIMIZE TABLE' instruction is not found for my PostGre 9.1
installation:

I think the equivalent in Postgre will be 'VACUUM
;'.

Because of analysis on these tables a FREEZE (exclusive DB lock) will be
needed for the vacuum job, so I will need to do this Tuesday in the morning.
I'll report the results of the VACUUM then.



--
View this message in context: http://sonarqube.15.x6.nabble.com/Read-time-out-error-tp5034188p5035606.html
Sent from the SonarQube Users mailing list archive at Nabble.com.

---------------------------------------------------------------------
To unsubscribe from this list, please visit:

    http://xircles.codehaus.org/manage_email




Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

The_Gentleman
This post has NOT been accepted by the mailing list yet.
Hi!

We are using PostGre 9.1. Reindexing and Vacuuming both tables seems to have solved the issue for now. Currently all projects doing a proper sonar analysis.
Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

saikiran
This post has NOT been accepted by the mailing list yet.
This post was updated on .
In reply to this post by Julien Lancelot
Hi Julien ,
sry for the late reply
Here is the logs.. As i said the build is getting failed every alternate day.
Should i also replace the core-5.1 and server-5.1.jar  and send you the logs??


2015.06.02 02:04:43 DEBUG web[http] GET /sonarabc/api/server/version | time=19750ms
2015.06.02 02:04:51 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.06.02 02:04:52 INFO  web[o.s.s.es.BulkIndexer] 2418 requests processed (0 items/sec)
2015.06.02 02:04:53 WARN   es[o.e.c.r.a.decider]  [sonar-1432896351638] high disk watermark [10%] exceeded on [C_72fNGATGGJUvew74xhzw][sonar-1432896351638] free: 4.1gb[6.8%], shards will be relocated away from this node
2015.06.02 02:04:53 INFO   es[o.e.c.r.a.decider]  [sonar-1432896351638] high disk watermark exceeded on one or more nodes, rerouting shards
2015.06.02 02:05:01 TRACE web[sql] time=15ms | 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.06.02 02:05:05 DEBUG web[http] GET /sonarabc/api/server/version | time=1036ms
2015.06.02 02:05:07 DEBUG web[http] GET /sonarabc/batch_bootstrap/index | time=1586ms
2015.06.02 02:05:10 TRACE web[sql] time=429ms | sql=SELECT * FROM `users` WHERE (login='admin' AND active=1)  LIMIT 1
2015.06.02 02:05:11 TRACE web[sql] time=3ms | 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.06.02 02:05:11 TRACE web[sql] time=96ms | sql=SELECT * FROM `groups`  INNER JOIN `groups_users` ON `groups`.id = `groups_users`.group_id WHERE (`groups_users`.user_id = 1 )
2015.06.02 02:05:11 TRACE web[sql] time=253ms | sql=SELECT gr.role        FROM group_roles gr        INNER JOIN groups_users gu on gu.group_id=gr.group_id        INNER JOIN users u on u.id=gu.user_id         WHERE  u.login=?          and gr.resource_id is null         UNION        SELECT gr.role        FROM group_roles gr        WHERE gr.group_id IS NULL AND gr.resource_id IS NULL        UNION        SELECT ur.role        FROM user_roles ur        INNER JOIN users u on u.id=ur.user_id         WHERE  u.login=?          and ur.resource_id is null
2015.06.02 02:05:12 TRACE web[sql] time=387ms | sql=SELECT         m.id,    m.name,    m.val_type as valueType,    m.description,    m.direction,    m.qualitative,    m.user_managed as userManaged,    m.worst_value as worstValue,    m.best_value as bestValue,    m.optimized_best_value as optimizedBestValue,    m.enabled       FROM metrics m     WHERE  m.enabled=true
2015.06.02 02:05:13 TRACE web[sql] time=19ms | sql=select p.id as id, p.prop_key as "key", p.text_value as value, p.resource_id as resourceId, p.user_id as userId    from properties p    where p.resource_id is null and p.user_id is null
2015.06.02 02:05:14 TRACE web[sql] time=3ms | sql=select id, data    from notifications    order by id asc    limit ?
2015.06.02 02:05:16 DEBUG web[http] GET /sonarabc/batch/global | time=6513ms
2015.06.02 02:05:16 DEBUG web[http] GET /sonarabc/deploy/plugins/index.txt | time=51ms
2015.06.02 02:05:18 DEBUG web[http] GET /sonarabc/deploy/jdbc-driver.txt | time=1ms
2015.06.02 02:05:21 TRACE web[sql] time=12ms | 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.06.02 02:05:22 TRACE web[sql] time=14ms | sql=SELECT * FROM `users` WHERE (login='admin' AND active=1)  LIMIT 1
2015.06.02 02:05:22 TRACE web[sql] time=1ms | sql=SELECT * FROM `groups`  INNER JOIN `groups_users` ON `groups`.id = `groups_users`.group_id WHERE (`groups_users`.user_id = 1 )
2015.06.02 02:05:23 DEBUG web[http] GET /sonarabc/api/server | time=1709ms
2015.06.02 02:05:23 WARN   es[o.e.c.r.a.decider]  [sonar-1432896351638] high disk watermark [10%] exceeded on [C_72fNGATGGJUvew74xhzw][sonar-1432896351638] free: 4.1gb[6.8%], shards will be relocated away from this node
2015.06.02 02:05:28 TRACE web[sql] time=0ms | sql=SELECT * FROM `users` WHERE (login='admin' AND active=1)  LIMIT 1
2015.06.02 02:05:28 TRACE web[sql] time=1ms | sql=SELECT * FROM `groups`  INNER JOIN `groups_users` ON `groups`.id = `groups_users`.group_id WHERE (`groups_users`.user_id = 1 )
2015.06.02 02:05:28 TRACE web[sql] time=3ms | sql=SELECT gr.role        FROM group_roles gr        INNER JOIN groups_users gu on gu.group_id=gr.group_id        INNER JOIN users u on u.id=gu.user_id         WHERE  u.login=?          and gr.resource_id is null         UNION        SELECT gr.role        FROM group_roles gr        WHERE gr.group_id IS NULL AND gr.resource_id IS NULL        UNION        SELECT ur.role        FROM user_roles ur        INNER JOIN users u on u.id=ur.user_id         WHERE  u.login=?          and ur.resource_id is null
2015.06.02 02:05:29 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     WHERE  p.kee=?
2015.06.02 02:05:30 TRACE web[sql] time=1403ms | 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 concat(module.module_uuid_path, '%')
2015.06.02 02:05:31 TRACE web[sql] time=55ms | 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 concat(module.module_uuid_path, '%')    ) modules on modules.id=prop.resource_id    WHERE prop.user_id IS NULL
2015.06.02 02:05: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.06.02 02:05:33 TRACE web[sql] time=1950ms | 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 concat(module.module_uuid_path, '%')
2015.06.02 02:05:35 TRACE web[sql] time=17ms | 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.06.02 02:05:35 TRACE web[sql] time=40ms | 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.06.02 02:05:41 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"terms":{"lang":["java"]}},{"bool":{"must_not":{"term":{"status":"REMOVED"}}}}]}}}},"_source":{"includes":["name","internalKey","key","templateKey"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=5265ms
2015.06.02 02:05:41 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.06.02 02:05:43 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@4c7b6417' | time=2405ms
2015.06.02 02:05:44 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@410cb69c' | time=843ms
2015.06.02 02:05:45 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2b73e0b1' | time=190ms
2015.06.02 02:05:45 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@255d32b8' | time=122ms
2015.06.02 02:05:46 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@3291b1d8' | time=175ms
2015.06.02 02:05:46 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@160d4940' | time=190ms
2015.06.02 02:05:46 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@224f4558' | time=85ms
2015.06.02 02:05:47 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@6e4b6b5b' | time=351ms
2015.06.02 02:05:48 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"java-abc-sonar-way-51006"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=1416ms
2015.06.02 02:05:49 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@516b083b' | time=317ms
2015.06.02 02:05:49 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2dd00c1f' | time=6ms
2015.06.02 02:05:49 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@756df978' | time=11ms
2015.06.02 02:05:49 TRACE web[es] ES search request '{"from":0,"size":10,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"terms":{"repo":["manual"]}},{"bool":{"must_not":{"term":{"status":"REMOVED"}}}}]}}}},"_source":{"includes":["name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=18ms
2015.06.02 02:05:49 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@4049553d' | time=1ms
2015.06.02 02:05: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.06.02 02:05:51 DEBUG web[http] GET /sonarabc/batch/project?key=Projectabc%3AProjectabc&preview=false | time=23550ms
2015.06.02 02:05:52 INFO  web[o.s.s.es.BulkIndexer] 2418 requests processed (0 items/sec)
2015.06.02 02:05:54 WARN   es[o.e.c.r.a.decider]  [sonar-1432896351638] high disk watermark [10%] exceeded on [C_72fNGATGGJUvew74xhzw][sonar-1432896351638] free: 4.1gb[6.8%], shards will be relocated away from this node
2015.06.02 02:05:54 INFO   es[o.e.c.r.a.decider]  [sonar-1432896351638] high disk watermark exceeded on one or more nodes, rerouting shards
2015.06.02 02:06: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.06.02 02:06: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.06.02 02:06:14 TRACE web[sql] time=0ms | sql=select id, data    from notifications    order by id asc    limit ?







2015.06.02 02:32:20 DEBUG web[http] GET /sonarabc/api/server/version | time=956ms
2015.06.02 02:32:21 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.06.02 02:32:24 WARN   es[o.e.c.r.a.decider]  [sonar-1432896351638] high disk watermark [10%] exceeded on [C_72fNGATGGJUvew74xhzw][sonar-1432896351638] free: 4.1gb[6.7%], shards will be relocated away from this node
2015.06.02 02:32:24 INFO   es[o.e.c.r.a.decider]  [sonar-1432896351638] high disk watermark exceeded on one or more nodes, rerouting shards
2015.06.02 02: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.06.02 02:32:37 DEBUG web[http] GET /sonarabc/api/server/version | time=726ms
2015.06.02 02:32:38 DEBUG web[http] GET /sonarabc/batch_bootstrap/index | time=761ms
2015.06.02 02:32:40 TRACE web[sql] time=52ms | sql=SELECT * FROM `users` WHERE (login='admin' AND active=1)  LIMIT 1
2015.06.02 02:32:41 TRACE web[sql] time=40ms | sql=SELECT * FROM `groups`  INNER JOIN `groups_users` ON `groups`.id = `groups_users`.group_id WHERE (`groups_users`.user_id = 1 )
2015.06.02 02:32:41 TRACE web[sql] time=55ms | sql=SELECT gr.role        FROM group_roles gr        INNER JOIN groups_users gu on gu.group_id=gr.group_id        INNER JOIN users u on u.id=gu.user_id         WHERE  u.login=?          and gr.resource_id is null         UNION        SELECT gr.role        FROM group_roles gr        WHERE gr.group_id IS NULL AND gr.resource_id IS NULL        UNION        SELECT ur.role        FROM user_roles ur        INNER JOIN users u on u.id=ur.user_id         WHERE  u.login=?          and ur.resource_id is null
2015.06.02 02:32:41 TRACE web[sql] time=243ms | sql=SELECT         m.id,    m.name,    m.val_type as valueType,    m.description,    m.direction,    m.qualitative,    m.user_managed as userManaged,    m.worst_value as worstValue,    m.best_value as bestValue,    m.optimized_best_value as optimizedBestValue,    m.enabled       FROM metrics m     WHERE  m.enabled=true
2015.06.02 02:32:42 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.06.02 02:32:42 TRACE web[sql] time=10ms | sql=select p.id as id, p.prop_key as "key", p.text_value as value, p.resource_id as resourceId, p.user_id as userId    from properties p    where p.resource_id is null and p.user_id is null
2015.06.02 02:32:44 DEBUG web[http] GET /sonarabc/batch/global | time=3841ms
2015.06.02 02:32:44 DEBUG web[http] GET /sonarabc/deploy/plugins/index.txt | time=23ms
2015.06.02 02:32:46 DEBUG web[http] GET /sonarabc/deploy/jdbc-driver.txt | time=13ms
2015.06.02 02:32:50 TRACE web[sql] time=0ms | sql=SELECT * FROM `users` WHERE (login='admin' AND active=1)  LIMIT 1
2015.06.02 02:32:50 TRACE web[sql] time=15ms | sql=SELECT * FROM `groups`  INNER JOIN `groups_users` ON `groups`.id = `groups_users`.group_id WHERE (`groups_users`.user_id = 1 )
2015.06.02 02:32:50 DEBUG web[http] GET /sonarabc/api/server | time=1166ms
2015.06.02 02:32:51 TRACE web[sql] time=16ms | 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.06.02 02:32:52 INFO  web[o.s.s.es.BulkIndexer] 2418 requests processed (0 items/sec)
2015.06.02 02:32:54 WARN   es[o.e.c.r.a.decider]  [sonar-1432896351638] high disk watermark [10%] exceeded on [C_72fNGATGGJUvew74xhzw][sonar-1432896351638] free: 4.1gb[6.7%], shards will be relocated away from this node
2015.06.02 02:32:55 TRACE web[sql] time=0ms | sql=SELECT * FROM `users` WHERE (login='admin' AND active=1)  LIMIT 1
2015.06.02 02:32:55 TRACE web[sql] time=0ms | sql=SELECT * FROM `groups`  INNER JOIN `groups_users` ON `groups`.id = `groups_users`.group_id WHERE (`groups_users`.user_id = 1 )
2015.06.02 02:32:55 TRACE web[sql] time=2ms | sql=SELECT gr.role        FROM group_roles gr        INNER JOIN groups_users gu on gu.group_id=gr.group_id        INNER JOIN users u on u.id=gu.user_id         WHERE  u.login=?          and gr.resource_id is null         UNION        SELECT gr.role        FROM group_roles gr        WHERE gr.group_id IS NULL AND gr.resource_id IS NULL        UNION        SELECT ur.role        FROM user_roles ur        INNER JOIN users u on u.id=ur.user_id         WHERE  u.login=?          and ur.resource_id is null
2015.06.02 02:32:55 TRACE web[sql] time=19ms | 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.06.02 02:32:56 TRACE web[sql] time=157ms | 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 concat(module.module_uuid_path, '%')
2015.06.02 02:33:10 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.06.02 02:33:10 TRACE web[sql] time=135ms | 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 concat(module.module_uuid_path, '%')    ) modules on modules.id=prop.resource_id    WHERE prop.user_id IS NULL
2015.06.02 02:33:11 TRACE web[sql] time=131ms | 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 concat(module.module_uuid_path, '%')
2015.06.02 02:33: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.06.02 02:33:11 TRACE web[sql] time=6ms | 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.06.02 02:33:11 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.06.02 02:33:11 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"terms":{"lang":["java"]}},{"bool":{"must_not":{"term":{"status":"REMOVED"}}}}]}}}},"_source":{"includes":["name","internalKey","key","templateKey"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=127ms
2015.06.02 02:33:13 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@5cbfe08c' | time=1547ms
2015.06.02 02:33:13 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@69adeb73' | time=204ms
2015.06.02 02:33:13 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@7f03600d' | time=313ms
2015.06.02 02:33:14 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@776ccfe3' | time=353ms
2015.06.02 02:33:14 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@5474fa02' | time=473ms
2015.06.02 02:33:14 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@5204658c' | time=369ms
2015.06.02 02:33:15 TRACE web[sql] time=0ms | sql=select id, data    from notifications    order by id asc    limit ?
2015.06.02 02:33:15 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@366364c9' | time=716ms
2015.06.02 02:33:15 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2761a631' | time=105ms
2015.06.02 02:33:15 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"java-abc-sonar-way-51006"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=63ms
2015.06.02 02:33:16 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@54b6ec35' | time=201ms
2015.06.02 02:33:16 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@25efcb2a' | time=5ms
2015.06.02 02:33:16 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@887a672' | time=1ms
2015.06.02 02:33:16 TRACE web[es] ES search request '{"from":0,"size":10,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"terms":{"repo":["manual"]}},{"bool":{"must_not":{"term":{"status":"REMOVED"}}}}]}}}},"_source":{"includes":["name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=5ms
2015.06.02 02:33:16 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@22fdd97d' | time=1ms
2015.06.02 02:33:18 DEBUG web[http] GET /sonarabc/batch/project?key=Projectabc%3AProjectabc&preview=false | time=22975ms
2015.06.02 02:33: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.06.02 02:33:24 WARN   es[o.e.c.r.a.decider]  [sonar-1432896351638] high disk watermark [10%] exceeded on [C_72fNGATGGJUvew74xhzw][sonar-1432896351638] free: 4.1gb[6.7%], shards will be relocated away from this node
2015.06.02 02:33: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.06.02 02:33: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.06.02 02:33: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.06.02 02:33:52 INFO  web[o.s.s.es.BulkIndexer] 2418 requests processed (0 items/sec)




2015.06.02 10:31:45 TRACE web[sql] time=124ms | sql=SELECT * FROM `users` WHERE (`users`.`remember_token` = '50b557d1cb909d9ac4e52e058fb9aed844d553d0')  LIMIT 1
2015.06.02 10:31:47 TRACE web[sql] time=123ms | sql=UPDATE `users` SET `remember_token` = '60c3502db3fc4dc77169f6f64199a94243d175ea' WHERE `id` = 16
2015.06.02 10:31:48 TRACE web[sql] time=28ms | sql=SELECT * FROM `groups`  INNER JOIN `groups_users` ON `groups`.id = `groups_users`.group_id WHERE (`groups_users`.user_id = 16 )
2015.06.02 10:31:48 TRACE web[sql] time=269ms | sql=SELECT gr.role        FROM group_roles gr        INNER JOIN groups_users gu on gu.group_id=gr.group_id        INNER JOIN users u on u.id=gu.user_id         WHERE  u.login=?          and gr.resource_id is null         UNION        SELECT gr.role        FROM group_roles gr        WHERE gr.group_id IS NULL AND gr.resource_id IS NULL        UNION        SELECT ur.role        FROM user_roles ur        INNER JOIN users u on u.id=ur.user_id         WHERE  u.login=?          and ur.resource_id is null
2015.06.02 10:31:48 TRACE web[sql] time=44ms | 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.06.02 10:31:51 TRACE web[sql] time=46ms | 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.06.02 10:31:52 TRACE web[sql] time=3646ms | 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 concat(module.module_uuid_path, '%')
2015.06.02 10:31:52 TRACE web[sql] time=25ms | 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 concat(module.module_uuid_path, '%')    ) modules on modules.id=prop.resource_id    WHERE prop.user_id IS NULL
2015.06.02 10:31:53 INFO  web[o.s.s.es.BulkIndexer] 2418 requests processed (0 items/sec)
2015.06.02 10:31:54 TRACE web[sql] time=2070ms | 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 concat(module.module_uuid_path, '%')
2015.06.02 10:31:55 TRACE web[sql] time=3ms | 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.06.02 10:31:55 TRACE web[sql] time=23ms | 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.06.02 10:31:56 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"terms":{"lang":["java"]}},{"bool":{"must_not":{"term":{"status":"REMOVED"}}}}]}}}},"_source":{"includes":["name","internalKey","key","templateKey"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=1304ms
2015.06.02 10:31:57 WARN   es[o.e.c.r.a.decider]  [sonar-1432896351638] high disk watermark [10%] exceeded on [C_72fNGATGGJUvew74xhzw][sonar-1432896351638] free: 4.1gb[6.7%], shards will be relocated away from this node
2015.06.02 10:31:57 INFO   es[o.e.c.r.a.decider]  [sonar-1432896351638] high disk watermark exceeded on one or more nodes, rerouting shards
2015.06.02 10:31:57 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@7d34f837' | time=553ms
2015.06.02 10:31:57 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@492c9bea' | time=87ms
2015.06.02 10:31:57 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2a1d8e3d' | time=61ms
2015.06.02 10:31:57 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2af307ad' | time=5ms
2015.06.02 10:31:57 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@705ca784' | time=346ms
2015.06.02 10:31:58 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@5d0281ae' | time=260ms
2015.06.02 10:31:58 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@1b288055' | time=173ms
2015.06.02 10:31:58 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@29d422f2' | time=144ms
2015.06.02 10:31:59 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"java-abc-sonar-way-51006"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=787ms
2015.06.02 10:31:59 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@3ca1c0cb' | time=116ms
2015.06.02 10:31:59 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@75e73900' | time=65ms
2015.06.02 10:31:59 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2a9235c1' | time=2ms
2015.06.02 10:31:59 TRACE web[es] ES search request '{"from":0,"size":10,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"terms":{"repo":["manual"]}},{"bool":{"must_not":{"term":{"status":"REMOVED"}}}}]}}}},"_source":{"includes":["name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=19ms
2015.06.02 10:31:59 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@22d941d3' | time=2ms
2015.06.02 10:32:00 DEBUG web[http] GET /sonarabc/batch/project?key=Projectabc:Projectabc&preview=false | time=14457ms
2015.06.02 10:32: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.06.02 10:32:07 TRACE web[sql] time=1ms | sql=SELECT * FROM `users` WHERE (`users`.`id` = 16)  LIMIT 1
2015.06.02 10:32:07 TRACE web[sql] time=1ms | sql=SELECT * FROM `groups`  INNER JOIN `groups_users` ON `groups`.id = `groups_users`.group_id WHERE (`groups_users`.user_id = 16 )
2015.06.02 10:32:07 TRACE web[sql] time=15ms | sql=SELECT gr.role        FROM group_roles gr        INNER JOIN groups_users gu on gu.group_id=gr.group_id        INNER JOIN users u on u.id=gu.user_id         WHERE  u.login=?          and gr.resource_id is null         UNION        SELECT gr.role        FROM group_roles gr        WHERE gr.group_id IS NULL AND gr.resource_id IS NULL        UNION        SELECT ur.role        FROM user_roles ur        INNER JOIN users u on u.id=ur.user_id         WHERE  u.login=?          and ur.resource_id is null
2015.06.02 10:32:07 TRACE web[sql] time=0ms | 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.06.02 10:32:07 TRACE web[sql] time=30ms | 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 concat(module.module_uuid_path, '%')
2015.06.02 10:32:07 TRACE web[sql] time=19ms | 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 concat(module.module_uuid_path, '%')    ) modules on modules.id=prop.resource_id    WHERE prop.user_id IS NULL
2015.06.02 10:32:07 TRACE web[sql] time=26ms | 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 concat(module.module_uuid_path, '%')
2015.06.02 10:32:07 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.06.02 10:32:07 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.06.02 10:32:07 TRACE web[es] ES search request '{"from":0,"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"terms":{"lang":["java"]}},{"bool":{"must_not":{"term":{"status":"REMOVED"}}}}]}}}},"_source":{"includes":["name","internalKey","key","templateKey"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=2ms
2015.06.02 10:32:07 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@310e0992' | time=22ms
2015.06.02 10:32:07 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@5642ea26' | time=19ms
2015.06.02 10:32:07 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@3e331dfe' | time=4ms
2015.06.02 10:32:07 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@366983d5' | time=4ms
2015.06.02 10:32:07 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@77ea4e13' | time=4ms
2015.06.02 10:32:07 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@36388912' | time=110ms
2015.06.02 10:32:07 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@1042aec6' | time=5ms
2015.06.02 10:32:07 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@6acff030' | time=96ms
2015.06.02 10:32:07 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"java-abc-sonar-way-51006"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=47ms
2015.06.02 10:32:07 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@4f70a1ac' | time=3ms
2015.06.02 10:32:07 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@3d72d149' | time=0ms
2015.06.02 10:32:07 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@38436cbf' | time=1ms
2015.06.02 10:32:07 TRACE web[es] ES search request '{"from":0,"size":10,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":[{"terms":{"repo":["manual"]}},{"bool":{"must_not":{"term":{"status":"REMOVED"}}}}]}}}},"_source":{"includes":["name","key"],"excludes":[]},"sort":[{"updatedAt":{"order":"desc"}},{"key.sort":{"order":"asc"}}]}' on indices '[rules]' on types '[rule]' | time=0ms
2015.06.02 10:32:07 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2d9845f7' | time=1ms
2015.06.02 10:32:08 DEBUG web[http] GET /sonarabc/batch/project?key=Projectabc:Projectabc&preview=false | time=1043ms
2015.06.02 10: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.06.02 10:32:17 TRACE web[sql] time=1ms | sql=select id, data    from notifications    order by id asc    limit ?
Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

saikiran
This post has NOT been accepted by the mailing list yet.
In reply to this post by Julien Lancelot
How to execute the above SQL query?
Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

The_Gentleman
This post has NOT been accepted by the mailing list yet.
Which type of db are you using? Then the query depends.

For me using postgresql I start the sql shell on my machine and enter the query followed by an ;.

This Mailing list was discontinued a week ago. Currently our problems will be resolved on Stackoverflow using the hashtag sonarqube

Am 4. Juni 2015 10:38:13 MESZ, schrieb "saikiran [via SonarQube]" <[hidden email]>:
How to execute the above SQL query?


If you reply to this email, your message will be added to the discussion below:
http://sonarqube.15.x6.nabble.com/Read-time-out-error-tp5034188p5035689.html
To unsubscribe from Read time out error ., click here.
NAML

--
Diese Nachricht wurde von meinem Android-Mobiltelefon mit K-9 Mail gesendet.
Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

saikiran
This post has NOT been accepted by the mailing list yet.
This post was updated on .
After replacing the jar files also the error continues,
and i am using mysql db
Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

stelos10
This post has NOT been accepted by the mailing list yet.
This post was updated on .
In reply to this post by Julien Lancelot
Hello,

I have the same issue: Read time out error.

I followed all the instructions mentioned from Julien Lancelot: Using the new .jar-files: sonar-core-5.1; sonar-server-5.1;

I'm using SonarQube 5.1 and MySql 5.6.19.

I still get the error and the following log:

Is it possible to raise the maximum time ("GET /batch/project?key=XXX&preview=false | time=23871ms")?

2015.06.11 09:19:03 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.06.11 09:19:13 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.06.11 09:19:23 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.06.11 09:19:32 TRACE web[sql] time=1ms | sql=select id, data    from notifications    order by id asc    limit ?
2015.06.11 09:19:33 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.06.11 09:19:43 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.06.11 09:19:53 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.06.11 09:20:03 TRACE web[sql] time=2ms | 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.06.11 09:20:13 TRACE web[sql] time=2ms | 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.06.11 09:20:22 TRACE web[sql] time=0ms | sql=SET SQL_AUTO_IS_NULL=0
2015.06.11 09:20:22 DEBUG web[http] GET /api/server/version | time=147ms
2015.06.11 09:20:23 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.06.11 09:20:27 DEBUG web[http] GET /batch_bootstrap/index | time=65ms
2015.06.11 09:20:28 TRACE web[sql] time=3ms | sql=SELECT * FROM `users` WHERE (login='admin' AND active=1)  LIMIT 1
2015.06.11 09:20:28 TRACE web[sql] time=2ms | sql=SELECT * FROM `groups`  INNER JOIN `groups_users` ON `groups`.id = `groups_users`.group_id WHERE (`groups_users`.user_id = 1 )
2015.06.11 09:20:28 TRACE web[sql] time=6ms | sql=SELECT gr.role        FROM group_roles gr        INNER JOIN groups_users gu on gu.group_id=gr.group_id        INNER JOIN users u on u.id=gu.user_id         WHERE  u.login=?          and gr.resource_id is null         UNION        SELECT gr.role        FROM group_roles gr        WHERE gr.group_id IS NULL AND gr.resource_id IS NULL        UNION        SELECT ur.role        FROM user_roles ur        INNER JOIN users u on u.id=ur.user_id         WHERE  u.login=?          and ur.resource_id is null
2015.06.11 09:20:28 TRACE web[sql] time=4ms | sql=SELECT         m.id,    m.name,    m.val_type as valueType,    m.description,    m.direction,    m.qualitative,    m.user_managed as userManaged,    m.worst_value as worstValue,    m.best_value as bestValue,    m.optimized_best_value as optimizedBestValue,    m.enabled       FROM metrics m     WHERE  m.enabled=true
2015.06.11 09:20:28 TRACE web[sql] time=1ms | sql=select p.id as id, p.prop_key as "key", p.text_value as value, p.resource_id as resourceId, p.user_id as userId    from properties p    where p.resource_id is null and p.user_id is null
2015.06.11 09:20:28 DEBUG web[http] GET /batch/global | time=276ms
2015.06.11 09:20:28 DEBUG web[http] GET /deploy/plugins/index.txt | time=2ms
2015.06.11 09:20:28 DEBUG web[http] GET /deploy/jdbc-driver.txt | time=1ms
2015.06.11 09:20:30 TRACE web[sql] time=1ms | sql=SELECT * FROM `users` WHERE (login='admin' AND active=1)  LIMIT 1
2015.06.11 09:20:30 TRACE web[sql] time=1ms | sql=SELECT * FROM `groups`  INNER JOIN `groups_users` ON `groups`.id = `groups_users`.group_id WHERE (`groups_users`.user_id = 1 )
2015.06.11 09:20:30 DEBUG web[http] GET /api/server | time=38ms
2015.06.11 09:20:31 TRACE web[sql] time=1ms | sql=SELECT * FROM `users` WHERE (login='admin' AND active=1)  LIMIT 1
2015.06.11 09:20:31 TRACE web[sql] time=1ms | sql=SELECT * FROM `groups`  INNER JOIN `groups_users` ON `groups`.id = `groups_users`.group_id WHERE (`groups_users`.user_id = 1 )
2015.06.11 09:20:31 TRACE web[sql] time=3ms | sql=SELECT gr.role        FROM group_roles gr        INNER JOIN groups_users gu on gu.group_id=gr.group_id        INNER JOIN users u on u.id=gu.user_id         WHERE  u.login=?          and gr.resource_id is null         UNION        SELECT gr.role        FROM group_roles gr        WHERE gr.group_id IS NULL AND gr.resource_id IS NULL        UNION        SELECT ur.role        FROM user_roles ur        INNER JOIN users u on u.id=ur.user_id         WHERE  u.login=?          and ur.resource_id is null
2015.06.11 09:20:31 TRACE web[sql] time=11ms | 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.06.11 09:20:32 TRACE web[sql] time=1ms | sql=select id, data    from notifications    order by id asc    limit ?
2015.06.11 09:20:33 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.06.11 09:20:36 TRACE web[sql] time=4801ms | 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 module ON module.project_uuid = p.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 concat(module.module_uuid_path, '%')
2015.06.11 09:20:39 TRACE web[sql] time=3188ms | 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 module ON module.project_uuid = p.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 concat(module.module_uuid_path, '%')    ) modules on modules.id=prop.resource_id    WHERE prop.user_id IS NULL
2015.06.11 09:20:43 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.06.11 09:20:53 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.06.11 09:20:53 TRACE web[sql] time=13900ms | 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     WHERE  p.project_uuid=?      AND p.enabled=true      AND p.scope='FIL'
2015.06.11 09:20:54 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.06.11 09:20:54 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.06.11 09:20: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=16ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2ad7d18f' | time=110ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@3eff1942' | time=46ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@5dd35c5d' | time=78ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@5f550dca' | time=47ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@48fc79ab' | time=31ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@3797eefc' | time=32ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@57cafee7' | time=31ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@50251cb8' | time=15ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@34809d23' | time=32ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@67cc7fc2' | time=46ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@22d7cecb' | time=16ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@649834c' | time=31ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@444af5cd' | time=31ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@154f88c2' | time=16ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@e54c7a6' | time=0ms
2015.06.11 09:20:54 TRACE web[es] ES search request '{"size":100,"query":{"filtered":{"query":{"match_all":{}},"filter":{"bool":{"must":{"term":{"profile":"xxx-00072"}},"must_not":{"has_parent":{"filter":{"term":{"status":"REMOVED"}},"parent_type":"rule"}}}}}}}' on indices '[rules]' on types '[activeRule]' | time=78ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@4ca416a5' | time=15ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@3ef49950' | time=16ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@2c7d737a' | time=15ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@60286567' | time=16ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@7c197e82' | time=16ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@6dc0c874' | time=0ms
2015.06.11 09:20:54 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.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@ba86ff7' | time=0ms
2015.06.11 09:20:54 TRACE web[es] ES search scroll request for scroll id 'org.elasticsearch.search.Scroll@5f4ec71b' | time=0ms
2015.06.11 09:20:55 DEBUG web[http] GET /batch/project?key=XXXX&preview=false | time=23871ms
2015.06.11 09:21:03 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.06.11 09:21:13 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



Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

saikiran
This post has NOT been accepted by the mailing list yet.
In reply to this post by saikiran
yes,  .. After following all the steps..
It worked for a few days but again the same error araised.
Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

The_Gentleman
This post has NOT been accepted by the mailing list yet.
Same here.

Maybe the issue could be related to the infrastructure?

How are you calling the sonar jobs, is sonar running as VM or native on a physical server? How much other tasks has the machine to do?

At us, we are running the Sonar on a VM together with a dedicated Sonar Jenkins node. That setup worked quite well with Sonar 4.5x but having 5.x we get read time outs.

Same as with you. Reindexing the DB helps between half a day and half a week...
Reply | Threaded
Open this post in threaded view
|

Re: Read time out error .

The_Gentleman
This post has NOT been accepted by the mailing list yet.
Found a workaround if triggered from Jenkins.

At http://stackoverflow.com/questions/20871111/sonar-analysis-fails-with-sockettimeoutexception?rq=1 there was a similar issue for an older Sonar version. They triggered regularly the URL to keep the query in DB cache. This works for this issue also.

In Jenkins I installed the http Request plugin and before issuing the sonar analysis I trigger a http get on http://mySonarQube:9000/batch/project?key=myProjectKey&preview=false . This works around the real problem (DB request duration and timeout interval don't fit), but it may keep us operable.
12