#1 2015-05-29 11:07:57

cjohnson
Member
Registered: 2015-05-29

Slow login? Failed login if lang specified

Sorry this is going to be a bit of a long one :
I've downloaded RS2.2.2-5639 / 2015-03-08-21-51-58 and installed it.

Having got a 404 error when installing tomcat/reportserver on Ubuntu from scratch and not managed to get round that I've installed Bitnami Tomcat (downloaded yesterday) (which is on Ubuntu too) and then installed reportserver as per the configuration guide.  I'm installing on a virtual machine using Virtual box.  All my other machines seem to work OK without performance issues.

I can load the tomcat manager and the bitnami home page and these load quickly. Using "top" from the console shows
-----------------------

top - 10:13:15 up  1:11,  3 users,  load average: 0.16, 0.07, 0.06
Tasks: 110 total,   2 running, 108 sleeping,   0 stopped,   0 zombie
%Cpu(s):  0.4 us,  0.3 sy,  0.0 ni, 99.3 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   2049700 total,  1479256 used,   570444 free,     8904 buffers
KiB Swap:   999420 total,     2436 used,   996984 free.   413208 cached Mem

  PID USER      PR  NI    VIRT    RES    SHR S  %CPU %MEM     TIME+ COMMAND
 1180 tomcat    20   0 4600280 880628  12912 S   5.3 43.0   9:06.98 jsvc
    1 root      20   0   33244   2592   1468 S   0.0  0.1   0:00.67 init
    2 root      20   0       0      0      0 S   0.0  0.0   0:00.00 kthreadd
    3 root      20   0       0      0      0 S   0.0  0.0   0:00.60 ksoftirqd/0

--------------------------
with jsvc never going above 20%.  The vm has 2GB ram and 4 processors

HOWEVER

when clicking through the tomcat manager to /reportserver
or going direct to the http://<hostname>/reportserver address
or going direct to the http://<hostname>/reportserver/ReportServer.html address
I get the compass points image and a "report server" spinning wheel in a box (pretty much instantly) in the middle of the screen which I've seen on your demo login but mine lasts >10 mins.

Sometimes I get a login box in german and I can log in and it all performs well
Sometimes I get a login box in english (when appending ?lang=en to the URL or adding the meta data to the ReportServer.html file) and once logged in it performs well

BUT most of the time I just get a spinning wheel.
It appears to work more often in german than english

I've "tail"ed the tomcat log file and noticed a couple of things

firstly the .css files give a 404, the path appears to be missing an extra "/reportserver" before the gwt folder where the file does exist :

192.168.55.94 - - [29/May/2015:10:29:41 +0000] "GET /reportserver/ HTTP/1.1" 200 768
192.168.55.94 - - [29/May/2015:10:29:41 +0000] "GET /reportserver/reportserver/fileServerAccess?path=/resources/public/index.html HTTP/1.1" 200 -
192.168.55.94 - - [29/May/2015:10:29:41 +0000] "GET /reportserver/ReportServer.html HTTP/1.1" 200 4255
192.168.55.94 - - [29/May/2015:10:29:41 +0000] "GET /reportserver/reportserver/version?style=banner HTTP/1.1" 200 36
192.168.55.94 - - [29/May/2015:10:29:41 +0000] "GET /reportserver/gwt/clean/clean.css HTTP/1.1" 404 1015
192.168.55.94 - - [29/May/2015:10:29:41 +0000] "GET /reportserver/gwt/standard/standard.css HTTP/1.1" 404 1027
192.168.55.94 - - [29/May/2015:10:29:41 +0000] "GET /reportserver/A7C43DC41321969773DA92CD3DD52AAE.cache.html HTTP/1.1" 404 1063
192.168.55.94 - - [29/May/2015:10:29:41 +0000] "GET /reportserver/reportserver/fileServerAccess?path=/resources/public/hostpage-extra.js&_=1432895381778 HTTP/1.1" 200 -

so I've made a symbolic link to that folder so that those files are now visible via the console :

bitnami@tomcat:/opt/bitnami/apache-tomcat/webapps/reportserver$ ls -al gwt/clean/
total 76
drwxr-xr-x 3 root root  4096 May 28 15:51 .
drwxr-xr-x 4 root root  4096 May 28 15:51 ..
-rw-r--r-- 1 root root 29325 May 28 15:51 clean.css
-rw-r--r-- 1 root root 29354 May 28 15:51 clean_rtl.css
drwxr-xr-x 2 root root  4096 May 28 15:51 images

BUT putting the direct url into a browser still gives a 404 (even after restarting the tomcat server)

    HTTP Status 404 - /reportserver/gwt/clean/clean.css
    type Status report
    message /reportserver/gwt/clean/clean.css
    description The requested resource is not available.
    Apache Tomcat/7.0.62

loading the page in German gives a log file entry of :

192.168.55.94 - - [29/May/2015:10:56:20 +0000] "GET /reportserver/reportserver/A7C43DC41321969773DA92CD3DD52AAE.cache.html HTTP/1.1" 200 4719922

changing the options to english gives log file entries of :

192.168.55.94 - - [29/May/2015:10:57:08 +0000] "GET /reportserver/resources/icons/flags/zh-tw.png HTTP/1.1" 404 1039
192.168.55.94 - - [29/May/2015:10:57:08 +0000] "GET /reportserver/resources/icons/flags/zh-cn.png HTTP/1.1" 404 1039
192.168.55.94 - - [29/May/2015:10:57:10 +0000] "GET /reportserver/ReportServer.html?locale=en HTTP/1.1" 200 4255
192.168.55.94 - - [29/May/2015:10:57:10 +0000] "GET /reportserver/reportserver/version?style=banner HTTP/1.1" 200 36
192.168.55.94 - - [29/May/2015:10:57:10 +0000] "GET /reportserver/A7C43DC41321969773DA92CD3DD52AAE.cache.html HTTP/1.1" 404 1063
192.168.55.94 - - [29/May/2015:10:57:10 +0000] "GET /reportserver/gwt/standard/standard.css HTTP/1.1" 404 1027
192.168.55.94 - - [29/May/2015:10:57:10 +0000] "GET /reportserver/reportserver/fileServerAccess?path=/resources/public/hostpage-extra.js&_=1432897030710 HTTP/1.1" 200 -
192.168.55.94 - - [29/May/2015:10:57:10 +0000] "GET /reportserver/gwt/clean/clean.css HTTP/1.1" 404 1015

showing a number of requests with /reportserver missing from the paths

changing back to no local again in the URL DOES NOT appear to get rid of the 404 errors unless I clear my cache first.


Any help getting this working would be appreciated.

Thanks


Chris

Offline

#2 2015-05-29 11:53:20

cjohnson
Member
Registered: 2015-05-29

Re: Slow login? Failed login if lang specified

quick update
Its not just english, the same happens for french and german (unless its just the default) as well so it appears to be tied to specifying a non standard locale rather than an issue just with english.

As tomcat apparently doesn't follow symlinks http://stackoverflow.com/questions/4822 … er-symlink I've used this

sudo mount --bind olddir newdir

to get the css to load but I'm now stuck on a cache file throwing a 404 error every time I load a page :

192.168.55.94 - - [29/May/2015:11:48:30 +0000] "GET /reportserver/resources/icons/flags/zh-cn.png HTTP/1.1" 404 1039
192.168.55.94 - - [29/May/2015:11:48:30 +0000] "GET /reportserver/resources/icons/flags/zh-tw.png HTTP/1.1" 404 1039
192.168.55.94 - - [29/May/2015:11:48:31 +0000] "GET /reportserver/A7C43DC41321969773DA92CD3DD52AAE.cache.html HTTP/1.1" 404 1063
192.168.55.94 - - [29/May/2015:11:49:10 +0000] "GET /reportserver/A7C43DC41321969773DA92CD3DD52AAE.cache.html HTTP/1.1" 404 1063
192.168.55.94 - - [29/May/2015:11:49:22 +0000] "GET /reportserver/A7C43DC41321969773DA92CD3DD52AAE.cache.html HTTP/1.1" 404 1063

Offline

#3 2015-05-29 12:25:13

Thorsten J. Krause
datenwerke
Registered: 2012-02-15
Website

Re: Slow login? Failed login if lang specified

Hi Chris,

don't pay too much attention to the 404 errors for now. These are very likely not the root cause of the problem.

Let's start by taking a look at the logfiles. What you posted seems all to come, from the access logs. You won't find anything helpful there.
Look for the files catalina.out or localhost.xxx.log. These should contain the actual error messages.

Cheers,
Thorsten

Offline

#4 2015-05-29 15:35:04

cjohnson
Member
Registered: 2015-05-29

Re: Slow login? Failed login if lang specified

Thanks for the pointer.  I've "tail -f"ed the report files that have today's date on them:

tail -f /opt/bitnami/apache-tomcat/logs/catalina.2015-05-29.log
tail -f /opt/bitnami/apache-tomcat/logs/localhost.2015-05-29.log
tail -f /opt/bitnami/apache-tomcat/logs/localhost_access_log.2015-05-29.txt
sudo tail -f /opt/bitnami/apache-tomcat/logs/catalina-daemon.out

cleared my browser cache and then loaded the following pages

and this time it loaded perfectly (<10secs) in english (set via the default in the filesystem) with no additional lines reported in any of the above files except localhost_access_log.2015-05-29.txt

on trying to log back in again I get the spinning wheel and these lines in /opt/bitnami/apache-tomcat/logs/localhost_access_log.2015-05-29.txt

192.168.55.94 - - [29/May/2015:14:31:14 +0000] "GET /reportserver/ HTTP/1.1" 200 768
192.168.55.94 - - [29/May/2015:14:31:14 +0000] "GET /reportserver/reportserver/fileServerAccess?path=/resources/public/index.html HTTP/1.1" 200 -
192.168.55.94 - - [29/May/2015:14:31:14 +0000] "GET /reportserver/ReportServer.html HTTP/1.1" 200 4255
192.168.55.94 - - [29/May/2015:14:31:14 +0000] "GET /reportserver/reportserver/version?style=banner HTTP/1.1" 200 36
192.168.55.94 - - [29/May/2015:14:31:14 +0000] "GET /reportserver/F69163F71E0EF61EA108B1E5F22D6353.cache.html HTTP/1.1" 404 1063
192.168.55.94 - - [29/May/2015:14:31:14 +0000] "GET /reportserver/reportserver/fileServerAccess?path=/resources/public/hostpage-extra.js&_=1432909875730 HTTP/1.1" 200 -

followed by a very long wait (>3 minutes)
Clearing the cache and reloading the page gives me the login box in <3secsonds
and these lines in localhost_access_log.2015-05-29.txt

192.168.55.94 - - [29/May/2015:14:33:47 +0000] "GET /reportserver/ HTTP/1.1" 200 768
192.168.55.102 - - [29/May/2015:14:33:47 +0000] "GET /reportserver/resources/js/jquery-1.8.3.min.js HTTP/1.1" 304 -
192.168.55.94 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/reportserver/fileServerAccess?path=/resources/public/index.html HTTP/1.1" 200 -
192.168.55.94 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/ReportServer.html HTTP/1.1" 200 4255
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/reportserver/reset.css HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/css/modulespecific.css HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/js/codemirror/lib/codemirror.css HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/js/codemirror/theme/elegant.css HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/js/crypto/crypto-sha1-hmac-pbkdf2-ofb-aes/crypto-sha1-hmac-pbkdf2-ofb-aes.js HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/js/crypto/cbc/cbc-min.js HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/js/crypto/sha256/sha256-min.js HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/js/codemirror/mode/plsql/plsql.js HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/js/codemirror/mode/xml/xml.js HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/js/codemirror/mode/javascript/javascript.js HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/js/codemirror/mode/css/css.js HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/js/codemirror/mode/clike/clike.js HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/js/codemirror/mode/groovy/groovy.js HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/js/codemirror/mode/php/php.js HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/js/codemirror/mode/properties/properties.js HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/reportserver/reportserver.nocache.js HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/images/default/shared/large-loading.gif HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/themes/repser/images/compass_bg_s.png HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/ReportServerLogo.png HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/icons/uifi/configuration.png HTTP/1.1" 304 -
192.168.55.102 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/resources/js/codemirror/lib/codemirror.js HTTP/1.1" 304 -
192.168.55.94 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/reportserver/version?style=banner HTTP/1.1" 200 36
192.168.55.94 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/reportserver/gwt/standard/standard.css HTTP/1.1" 200 26972
192.168.55.94 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/reportserver/gwt/clean/clean.css HTTP/1.1" 200 29325
192.168.55.94 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/reportserver/F69163F71E0EF61EA108B1E5F22D6353.cache.html HTTP/1.1" 200 4753517
192.168.55.94 - - [29/May/2015:14:33:48 +0000] "GET /reportserver/reportserver/fileServerAccess?path=/resources/public/hostpage-extra.js&_=1432910029353 HTTP/1.1" 200 -
192.168.55.94 - - [29/May/2015:14:33:49 +0000] "POST /reportserver/reportserver/base_datasource HTTP/1.1" 200 395
192.168.55.94 - - [29/May/2015:14:33:50 +0000] "POST /reportserver/reportserver/homepage HTTP/1.1" 200 14
192.168.55.94 - - [29/May/2015:14:33:50 +0000] "POST /reportserver/reportserver/homepage HTTP/1.1" 200 28
192.168.55.94 - - [29/May/2015:14:33:50 +0000] "POST /reportserver/reportserver/login HTTP/1.1" 200 150
192.168.55.94 - - [29/May/2015:14:33:50 +0000] "POST /reportserver/reportserver/security_challengeresponse HTTP/1.1" 200 95
192.168.55.94 - - [29/May/2015:14:33:50 +0000] "POST /reportserver/reportserver/remotemessage HTTP/1.1" 200 36306
192.168.55.94 - - [29/May/2015:14:33:50 +0000] "POST /reportserver/reportserver/login HTTP/1.1" 200 14
192.168.55.94 - - [29/May/2015:14:33:50 +0000] "POST /reportserver/reportserver/datasourcebundle HTTP/1.1" 200 108
192.168.55.94 - - [29/May/2015:14:33:50 +0000] "POST /reportserver/reportserver/datasourcebundle HTTP/1.1" 200 112
192.168.55.94 - - [29/May/2015:14:33:50 +0000] "POST /reportserver/reportserver/localization HTTP/1.1" 200 474
192.168.55.94 - - [29/May/2015:14:33:50 +0000] "GET /reportserver/reportserver/clear.cache.gif HTTP/1.1" 200 43
192.168.55.94 - - [29/May/2015:14:33:55 +0000] "POST /reportserver/reportserver/login HTTP/1.1" 200 18

again logging out gives a spinning wheel and these lines in localhost_access_log.2015-05-29.txt

192.168.55.94 - - [29/May/2015:14:36:17 +0000] "POST /reportserver/reportserver/login HTTP/1.1" 200 14
192.168.55.94 - - [29/May/2015:14:36:17 +0000] "GET /reportserver/ReportServer.html HTTP/1.1" 200 4255
192.168.55.94 - - [29/May/2015:14:36:17 +0000] "GET /reportserver/reportserver/version?style=banner HTTP/1.1" 200 36
192.168.55.94 - - [29/May/2015:14:36:17 +0000] "GET /reportserver/gwt/clean/clean.css HTTP/1.1" 200 29325
192.168.55.94 - - [29/May/2015:14:36:17 +0000] "GET /reportserver/F69163F71E0EF61EA108B1E5F22D6353.cache.html HTTP/1.1" 404 1063
192.168.55.94 - - [29/May/2015:14:36:17 +0000] "GET /reportserver/gwt/standard/standard.css HTTP/1.1" 200 26972
192.168.55.94 - - [29/May/2015:14:36:17 +0000] "GET /reportserver/reportserver/fileServerAccess?path=/resources/public/hostpage-extra.js&_=1432910178315 HTTP/1.1" 200 -

I tried the same with firefox (above test with Chrome on Windows 7) and with Chrome on a separate ubuntu machine (load, login, <do admin>, logout, try to log in again)

First login after clearing browser cache seems OK and web interface appears to work quickly allowing creation of users, groups etc but when logging out it can't find a cache file (404) because the path is incorrect and it hangs there, seemingly indefinitely and then won't log in again until after the local browser cache is cleared



So I restarted tomcat to see what the logs were saying on start up :
catalina.2015-05-29.log

May 29, 2015 2:56:43 PM net.datenwerke.rs.core.service.internaldb.InternalDbServiceImpl getLocation
WARNING: Cannot write to internal db file: /opt/bitnami/apache-tomcat/logs/dbtmp
May 29, 2015 2:56:43 PM net.datenwerke.rs.core.service.internaldb.InternalDbServiceImpl getLocation
WARNING: Fallback location internaldb: /opt/bitnami/apache-tomcat/temp
May 29, 2015 2:56:43 PM net.datenwerke.rs.core.service.internaldb.InternalDbServiceImpl getLocation
WARNING: Cannot write to internal db file: /opt/bitnami/apache-tomcat/logs/dbtmp
May 29, 2015 2:56:43 PM net.datenwerke.rs.core.service.internaldb.InternalDbServiceImpl getLocation
WARNING: Fallback location internaldb: /opt/bitnami/apache-tomcat/temp
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc
SEVERE: The web application [/reportserver] registered the JDBC driver [com.mysql.jdbc.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc
SEVERE: The web application [/reportserver] registered the JDBC driver [org.h2.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/reportserver] appears to have started a thread named [com.google.inject.internal.util.$Finalizer] but has failed to stop it. This is very likely to create a memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/reportserver] appears to have started a thread named [Abandoned connection cleanup thread] but has failed to stop it. This is very likely to create a memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/reportserver] appears to have started a thread named [dwdaemon-JpaEventLoggerServiceImpl-1] but has failed to stop it. This is very likely to create a memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/reportserver] appears to have started a thread named [dwdaemon-watchdog-JpaEventLoggerServiceImpl] but has failed to stop it. This is very likely to create a memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/reportserver] appears to have started a thread named [scheduler-watchdog] but has failed to stop it. This is very likely to create a memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/reportserver] appears to have started a thread named [NioSocketAcceptor-1] but has failed to stop it. This is very likely to create a memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/reportserver] appears to have started a thread named [dwdaemon-MaintenanceServiceImpl-1] but has failed to stop it. This is very likely to create a memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/reportserver] appears to have started a thread named [dwdaemon-watchdog-MaintenanceServiceImpl] but has failed to stop it. This is very likely to create a memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/reportserver] created a ThreadLocal with key of type [com.google.inject.internal.InjectorImpl$1] (value [com.google.inject.internal.InjectorImpl$1@7b47fbc2]) and a value of type [java.lang.Object[]] (value [[Ljava.lang.Object;@32e1bcc9]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/reportserver] created a ThreadLocal with key of type [com.google.inject.internal.InjectorImpl$1] (value [com.google.inject.internal.InjectorImpl$1@7b47fbc2]) and a value of type [java.lang.Object[]] (value [[Ljava.lang.Object;@2314a38]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/reportserver] created a ThreadLocal with key of type [com.google.inject.internal.InjectorImpl$1] (value [com.google.inject.internal.InjectorImpl$1@7b47fbc2]) and a value of type [java.lang.Object[]] (value [[Ljava.lang.Object;@71ffa3a7]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/reportserver] created a ThreadLocal with key of type [com.google.inject.internal.InjectorImpl$1] (value [com.google.inject.internal.InjectorImpl$1@7b47fbc2]) and a value of type [java.lang.Object[]] (value [[Ljava.lang.Object;@46516369]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/reportserver] created a ThreadLocal with key of type [com.google.inject.internal.InjectorImpl$1] (value [com.google.inject.internal.InjectorImpl$1@7b47fbc2]) and a value of type [java.lang.Object[]] (value [[Ljava.lang.Object;@955a690]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/reportserver] created a ThreadLocal with key of type [com.google.inject.internal.InjectorImpl$1] (value [com.google.inject.internal.InjectorImpl$1@7b47fbc2]) and a value of type [java.lang.Object[]] (value [[Ljava.lang.Object;@562a3cb9]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/reportserver] created a ThreadLocal with key of type [com.google.inject.internal.InjectorImpl$1] (value [com.google.inject.internal.InjectorImpl$1@7b47fbc2]) and a value of type [java.lang.Object[]] (value [[Ljava.lang.Object;@6ef8c1e0]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/reportserver] created a ThreadLocal with key of type [com.google.inject.internal.InjectorImpl$1] (value [com.google.inject.internal.InjectorImpl$1@7b47fbc2]) and a value of type [java.lang.Object[]] (value [[Ljava.lang.Object;@d80920b]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/reportserver] created a ThreadLocal with key of type [com.google.inject.internal.InjectorImpl$1] (value [com.google.inject.internal.InjectorImpl$1@7b47fbc2]) and a value of type [java.lang.Object[]] (value [[Ljava.lang.Object;@73f41719]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/reportserver] created a ThreadLocal with key of type [com.google.inject.internal.InjectorImpl$1] (value [com.google.inject.internal.InjectorImpl$1@7b47fbc2]) and a value of type [java.lang.Object[]] (value [[Ljava.lang.Object;@1e18a5c8]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/reportserver] created a ThreadLocal with key of type [com.google.inject.internal.InjectorImpl$1] (value [com.google.inject.internal.InjectorImpl$1@7b47fbc2]) and a value of type [java.lang.Object[]] (value [[Ljava.lang.Object;@642bfce7]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/reportserver] created a ThreadLocal with key of type [com.google.inject.internal.InjectorImpl$1] (value [com.google.inject.internal.InjectorImpl$1@7b47fbc2]) and a value of type [java.lang.Object[]] (value [[Ljava.lang.Object;@3e8d512]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 29, 2015 2:56:43 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/reportserver] created a ThreadLocal with key of type [com.google.inject.internal.InjectorImpl$1] (value [com.google.inject.internal.InjectorImpl$1@7b47fbc2]) and a value of type [java.lang.Object[]] (value [[Ljava.lang.Object;@2afc92bf]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.

so I created a dbtmp folder in /logs/dbtemp, chowned it to tomcat:tomcat and restarted

this gave a lot less errors in catalina.2015-05-29.log

May 29, 2015 3:13:04 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /opt/bitnami/apache-tomcat/webapps/ROOT has finished in 212 ms
May 29, 2015 3:13:13 PM org.apache.catalina.startup.TldConfig execute
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
May 29, 2015 3:13:28 PM com.google.inject.internal.ProxyFactory <init>
WARNING: Method [public net.datenwerke.gxtdto.client.model.DwModel net.datenwerke.rs.core.server.reportexecutor.ReportExecutorRpcServiceImpl.storePNGInSession(net.datenwerke.rs.core.client.reportmanager.dto.reports.ReportDto) throws net.datenwerke.gxtdto.client.servercommunication.exceptions.ServerCallFailedException] is synthetic and is being intercepted by [net.datenwerke.gf.service.gwtstacktrace.CatchStacktraceInterceptor@3dae3822]. This could indicate a bug.  The method may be intercepted twice, or may not be intercepted at all.
May 29, 2015 3:13:28 PM com.google.inject.internal.ProxyFactory <init>
WARNING: Method [public net.datenwerke.gxtdto.client.model.DwModel net.datenwerke.rs.core.server.reportexecutor.ReportExecutorRpcServiceImpl.storePNGInSession(net.datenwerke.rs.core.client.reportmanager.dto.reports.ReportDto) throws net.datenwerke.gxtdto.client.servercommunication.exceptions.ServerCallFailedException] is synthetic and is being intercepted by [net.datenwerke.security.service.security.aop.SecurityCheckInterceptor@1ff6868]. This could indicate a bug.  The method may be intercepted twice, or may not be intercepted at all.
May 29, 2015 3:13:28 PM net.datenwerke.rs.core.service.jarextension.hookers.ReportServerExtenderHooker initialize
INFO: Start loading ReportServer extensions.
May 29, 2015 3:13:28 PM net.datenwerke.rs.core.service.i18ntools.RemoteMessageServiceImpl <init>
INFO: Loading messages...
May 29, 2015 3:13:39 PM net.datenwerke.rs.core.service.i18ntools.RemoteMessageServiceImpl loadMessageFiles
INFO: remove locale stubs: , zh, ca, tr, pt_BR, no, el_GR, pt_PT, ko, cn, ua, ru, ja
May 29, 2015 3:13:39 PM net.datenwerke.rs.core.service.i18ntools.RemoteMessageServiceImpl <init>
INFO: Available locales: hr, ro, hu, lv, lt, ga, de, fi, fr, bg, sv, zh-TW, sl, sk, da, it, keys, mt, zh-CN, cs, el, pl, pt, en, et, es, nl

(stripped out all the info lines)

and these lines in

15:13:24,250  INFO Version:39 - HSEARCH000034: Hibernate Search 4.2.0.Final
15:13:24,303  WARN ConfigContext:301 - HSEARCH000075: Configuration setting hibernate.search.lucene_version was not specified, using LUCENE_CURRENT.
15:13:27,902 ERROR MetadataContext:443 - HHH015011: Unable to locate static metamodel field : net.datenwerke.security.service.usermanager.entities.AbstractUserManagerNode_#origin
15:13:27,903 ERROR MetadataContext:443 - HHH015011: Unable to locate static metamodel field : net.datenwerke.security.service.usermanager.entities.AbstractUserManagerNode_#guid
15:13:27,921 ERROR MetadataContext:443 - HHH015011: Unable to locate static metamodel field : net.datenwerke.rs.base.service.reportengines.table.entities.TableReport_#allowCubification
15:13:27,922 ERROR MetadataContext:443 - HHH015011: Unable to locate static metamodel field : net.datenwerke.rs.base.service.reportengines.table.entities.TableReport_#cubeXml
15:13:27,922 ERROR MetadataContext:443 - HHH015011: Unable to locate static metamodel field : net.datenwerke.rs.base.service.reportengines.table.entities.TableReport_#cube
15:13:27,929 ERROR MetadataContext:443 - HHH015011: Unable to locate static metamodel field : net.datenwerke.rs.base.service.reportengines.table.entities.Column_#dimension
15:13:27,944 ERROR MetadataContext:443 - HHH015011: Unable to locate static metamodel field : net.datenwerke.rs.dsbundle.service.dsbundle.entities.DatabaseBundle_#keySource
15:13:27,945 ERROR MetadataContext:443 - HHH015011: Unable to locate static metamodel field : net.datenwerke.rs.dsbundle.service.dsbundle.entities.DatabaseBundle_#keySourceParamName
15:13:27,945 ERROR MetadataContext:443 - HHH015011: Unable to locate static metamodel field : net.datenwerke.rs.dsbundle.service.dsbundle.entities.DatabaseBundle_#mappingSource
15:13:28,269  WARN Ejb3Configuration:1141 - HHH000144: hibernate.connection.autocommit = false breaks the EJB3 specification
May 29, 2015 3:13:28 PM com.google.inject.internal.ProxyFactory <init>
WARNING: Method [public net.datenwerke.gxtdto.client.model.DwModel net.datenwerke.rs.core.server.reportexecutor.ReportExecutorRpcServiceImpl.storePNGInSession(net.datenwerke.rs.core.client.reportmanager.dto.reports.ReportDto) throws net.datenwerke.gxtdto.client.servercommunication.exceptions.ServerCallFailedException] is synthetic and is being intercepted by [net.datenwerke.gf.service.gwtstacktrace.CatchStacktraceInterceptor@3dae3822]. This could indicate a bug.  The method may be intercepted twice, or may not be intercepted at all.
May 29, 2015 3:13:28 PM com.google.inject.internal.ProxyFactory <init>
WARNING: Method [public net.datenwerke.gxtdto.client.model.DwModel net.datenwerke.rs.core.server.reportexecutor.ReportExecutorRpcServiceImpl.storePNGInSession(net.datenwerke.rs.core.client.reportmanager.dto.reports.ReportDto) throws net.datenwerke.gxtdto.client.servercommunication.exceptions.ServerCallFailedException] is synthetic and is being intercepted by [net.datenwerke.security.service.security.aop.SecurityCheckInterceptor@1ff6868]. This could indicate a bug.  The method may be intercepted twice, or may not be intercepted at all.
May 29, 2015 3:13:28 PM net.datenwerke.rs.core.service.jarextension.hookers.ReportServerExtenderHooker initialize
INFO: Start loading ReportServer extensions.
May 29, 2015 3:13:28 PM net.datenwerke.rs.core.service.i18ntools.RemoteMessageServiceImpl <init>
INFO: Loading messages...
15:13:38,870  INFO MondrianProperties:267 - Mondrian: properties loaded from 'file:/opt/bitnami/apache-tomcat/webapps/reportserver/WEB-INF/classes/mondrian.properties'
15:13:38,870  INFO MondrianProperties:236 - Mondrian: loaded 0 system properties

(stripped out all the info lines)

After restarting tomcat all I get is spinning wheel even with clearing the local browser cache.  Going to restart the server but might not get to test it until Monday...

Does anything above point to something else I can fix ??

Last edited by cjohnson (2015-05-29 15:38:13)

Offline

#5 2015-05-29 18:57:23

Thorsten J. Krause
datenwerke
Registered: 2012-02-15
Website

Re: Slow login? Failed login if lang specified

Hi Chris,

okay this got me curious enough to try it for myself.
And after a clean install of ReportServer on the bitnami tomcat VM I got exactly the same result as you did. You don't even need to log in. Just refreshing the page breaks it. (Which happens when you switch the language)

The problem is the bitnami stack uses a apache http server as a reverse proxy in front of tomcat. This is quite a common configuration and usually not a problem, but they also choose to include the mod_pagespeed module.
Mod_pagespeed tries to improve your sites performance by rewriting html and javascript content on the fly and adding an additional caching layer. Well with ReportServer this does not seem to work very well...

To disable mod_pagespeed follow these steps:

/opt/bitnami/ctlscript.sh stop apache
# Set ModPageSpeed off in /opt/bitnami/apache2/conf/pagespeed.conf
sed -i 's/ModPagespeed on/ModPagespeed off/g' /opt/bitnami/apache2/conf/pagespeed.conf
/opt/bitnami/ctlscript.sh start apache

After this everything should run smoothly.

Cheers,
Thorsten

Offline

#6 2015-06-01 12:08:12

cjohnson
Member
Registered: 2015-05-29

Re: Slow login? Failed login if lang specified

Thanks
This works great!
Now to have a play with some reports and data sources...

Offline

Board footer

Powered by FluxBB