SonarQube 5.6 + Oracle-DB
249 views
Skip to first unread message
Christian Fischer
Jun 29, 2016, 3:02:37 AM6/29/16
to SonarQube
Hello SonarQube community,
right now i am trying to upgrade my SQ installation from 5.4 to 5.6 LTS release. I followed the usual upgrade process (http://docs.sonarqube.org/display/SONAR/Upgrading) but with the newest version there seems to be a problem reagrding my database. The server starts up correctly if i use the embedded database for testing purpose, but if i want to enable the oracle connection the startup fails although this worked perfectly with 5.4. Here are the logs:
#######################################
sonar.log:
#######################################
2016.06.29 08:54:57 INFO es[o.s.p.ProcessEntryPoint] Starting es
2016.06.29 08:54:57 INFO es[o.s.s.EsSettings] Elasticsearch listening on 127.0.0.1:9001
2016.06.29 08:54:57 INFO es[o.elasticsearch.node] [sonar-1467183296981] version[1.7.5], pid[33020], build[00f95f4/2016-02-02T09:55:30Z]
2016.06.29 08:54:57 INFO es[o.elasticsearch.node] [sonar-1467183296981] initializing ...
2016.06.29 08:54:57 INFO es[o.e.plugins] [sonar-1467183296981] loaded [], sites []
2016.06.29 08:54:57 INFO es[o.elasticsearch.env] [sonar-1467183296981] using [1] data paths, mounts [[Volume (D:)]], net usable_space [186.8gb], net total_space [465.7gb], types [NTFS]
2016.06.29 08:54:58 WARN es[o.e.bootstrap] JNA not found. native methods will be disabled.
2016.06.29 08:54:58 INFO es[o.elasticsearch.node] [sonar-1467183296981] initialized
2016.06.29 08:54:58 INFO es[o.elasticsearch.node] [sonar-1467183296981] starting ...
2016.06.29 08:54:58 INFO es[o.e.transport] [sonar-1467183296981] bound_address {inet[/127.0.0.1:9001]}, publish_address {inet[/127.0.0.1:9001]}
2016.06.29 08:54:58 INFO es[o.e.discovery] [sonar-1467183296981] sonarqube/zkQijiQ-QEqEKp91sQtXpQ
Received a packet PING : ping
Send a packet PING : ok
2016.06.29 08:55:01 INFO es[o.e.cluster.service] [sonar-1467183296981] new_master [sonar-1467183296981][zkQijiQ-QEqEKp91sQtXpQ][WMUC601454][inet[/127.0.0.1:9001]]{rack_id=sonar-1467183296981}, reason: zen-disco-join (elected_as_master)
2016.06.29 08:55:01 INFO es[o.elasticsearch.node] [sonar-1467183296981] started
2016.06.29 08:55:01 INFO es[o.e.gateway] [sonar-1467183296981] recovered [0] indices into cluster_state
2016.06.29 08:55:02 INFO app[o.s.p.m.Monitor] Process[es] is up
2016.06.29 08:55:02 INFO app[o.s.p.m.JavaProcessLauncher] Launch process[web]: c:\Program Files (x86)\JavaSoft\jre\1.8.0_72\bin\java -Djava.awt.headless=true -Dfile.encoding=UTF-8 -Djruby.management.enabled=false -Djruby.compile.invokedynamic=false -Xmx512m -Xms128m -XX:+HeapDumpOnOutOfMemoryError -Djava.net.preferIPv4Stack=true -Djava.io.tmpdir=D:\downloads\sonarqube-5.6\temp -javaagent:c:\Program Files (x86)\JavaSoft\jre\1.8.0_72\lib\management-agent.jar -cp ./lib/common/*;./lib/server/*;D:\downloads\sonarqube-5.6\extensions\jdbc-driver\oracle\ojdbc6.jar org.sonar.server.app.WebServer D:\downloads\sonarqube-5.6\temp\sq-process6212819906787408381properties
2016.06.29 08:55:02 INFO web[o.s.p.ProcessEntryPoint] Starting web
2016.06.29 08:55:02 INFO web[o.s.s.a.TomcatContexts] Webapp directory: D:\downloads\sonarqube-5.6\web
2016.06.29 08:55:02 INFO web[o.a.c.h.Http11NioProtocol] Initializing ProtocolHandler ["http-nio-0.0.0.0-19000"]
2016.06.29 08:55:02 INFO web[o.a.t.u.n.NioSelectorPool] Using a shared selector for servlet write/read
2016.06.29 08:55:03 INFO web[o.s.s.p.ServerImpl] SonarQube Server / 5.6 / 074f3d1169f9688d15af4aff67e7e672cbeed782
2016.06.29 08:55:03 INFO web[o.sonar.db.Database] Create JDBC data source for jdbc:oracle:thin:@tldb0134vm.bmwgroup.net:1747:alltutf
2016.06.29 08:55:04 INFO web[o.s.s.p.DefaultServerFileSystem] SonarQube home: D:\downloads\sonarqube-5.6
2016.06.29 08:55:04 INFO web[o.e.plugins] [sonar-1467183296981] loaded [], sites []
2016.06.29 08:55:04 INFO web[o.s.s.p.ServerPluginRepository] Deploy plugin C# / 5.0 / 17ddb09047940791828dcb70c9e225f006ab11aa
2016.06.29 08:55:04 INFO web[o.s.s.p.ServerPluginRepository] Deploy plugin Git / 1.2 / a713dd64daf8719ba4e7f551f9a1966c62690c17
2016.06.29 08:55:04 INFO web[o.s.s.p.ServerPluginRepository] Deploy plugin Java / 3.13.1 / cf0f0c950ba3e83a87c7fe11c6ff7e63f4864bd9
2016.06.29 08:55:04 INFO web[o.s.s.p.ServerPluginRepository] Deploy plugin JavaScript / 2.11 / a9b1afa9ceef7079811779d9efc5f8026acb1400
2016.06.29 08:55:04 INFO web[o.s.s.p.ServerPluginRepository] Deploy plugin SVN / 1.3 / aff503d48bc77b07c2b62abf93249d0a20bd355c
2016.06.29 08:55:04 DEBUG web[o.s.c.p.PluginLoader] API compatibility mode is enabled on plugin C# [csharp] (built with API lower than 5.2)
2016.06.29 08:55:04 DEBUG web[o.s.c.p.PluginLoader] API compatibility mode is enabled on plugin Java [java] (built with API lower than 5.2)
2016.06.29 08:55:04 DEBUG web[o.s.c.p.PluginLoader] API compatibility mode is enabled on plugin Git [scmgit] (built with API lower than 5.2)
Received a packet PING : ping
Send a packet PING : ok
2016.06.29 08:55:05 DEBUG web[o.s.c.p.PluginLoader] API compatibility mode is enabled on plugin SVN [scmsvn] (built with API lower than 5.2)
2016.06.29 08:55:05 DEBUG web[o.s.c.p.PluginLoader] API compatibility mode is enabled on plugin JavaScript [javascript] (built with API lower than 5.2)
2016.06.29 08:55:05 INFO web[o.s.s.p.RailsAppsDeployer] Deploying Ruby on Rails applications
2016.06.29 08:55:05 DEBUG web[o.s.c.i.DefaultI18n] Loaded 2325 properties from l10n bundles
2016.06.29 08:55:05 DEBUG web[o.s.s.s.ServerMetadataPersister] Persisting server metadata
2016.06.29 08:55:05 INFO web[o.s.s.p.UpdateCenterClient] Update center: http://update.sonarsource.org/update-center.properties (no proxy)
2016.06.29 08:55:05 DEBUG web[o.s.a.r.Languages] Available languages:
2016.06.29 08:55:05 DEBUG web[o.s.a.r.Languages] * C# => "cs"
2016.06.29 08:55:05 DEBUG web[o.s.a.r.Languages] * Java => "java"
2016.06.29 08:55:05 DEBUG web[o.s.a.r.Languages] * JavaScript => "js"
2016.06.29 08:55:05 INFO web[o.s.s.e.IndexCreator] Create index tests
2016.06.29 08:55:05 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [tests] creating index, cause [api], templates [], shards [5]/[0], mappings []
2016.06.29 08:55:06 INFO web[o.s.s.e.IndexCreator] Create type tests/test
2016.06.29 08:55:06 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [tests] create_mapping [test]
2016.06.29 08:55:06 INFO web[o.s.s.e.IndexCreator] Create index activities
2016.06.29 08:55:06 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [activities] creating index, cause [api], templates [], shards [5]/[0], mappings []
2016.06.29 08:55:07 INFO web[o.s.s.e.IndexCreator] Create type activities/activity
2016.06.29 08:55:07 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [activities] create_mapping [activity]
2016.06.29 08:55:07 INFO web[o.s.s.e.IndexCreator] Create index rules
2016.06.29 08:55:07 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [rules] creating index, cause [api], templates [], shards [5]/[0], mappings []
2016.06.29 08:55:07 INFO web[o.s.s.e.IndexCreator] Create type rules/activeRule
2016.06.29 08:55:07 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [rules] create_mapping [activeRule]
2016.06.29 08:55:07 INFO web[o.s.s.e.IndexCreator] Create type rules/rule
2016.06.29 08:55:07 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [rules] create_mapping [rule]
2016.06.29 08:55:07 INFO web[o.s.s.e.IndexCreator] Create index issues
2016.06.29 08:55:07 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [issues] creating index, cause [api], templates [], shards [5]/[0], mappings []
2016.06.29 08:55:08 INFO web[o.s.s.e.IndexCreator] Create type issues/authorization
2016.06.29 08:55:08 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [issues] create_mapping [authorization]
2016.06.29 08:55:08 INFO web[o.s.s.e.IndexCreator] Create type issues/issue
2016.06.29 08:55:08 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [issues] create_mapping [issue]
2016.06.29 08:55:08 INFO web[o.s.s.e.IndexCreator] Create index users
2016.06.29 08:55:08 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [users] creating index, cause [api], templates [], shards [5]/[0], mappings []
2016.06.29 08:55:08 INFO web[o.s.s.e.IndexCreator] Create type users/user
2016.06.29 08:55:08 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [users] create_mapping [user]
2016.06.29 08:55:08 INFO web[o.s.s.e.IndexCreator] Create index views
2016.06.29 08:55:08 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [views] creating index, cause [api], templates [], shards [5]/[0], mappings []
Received a packet PING : ping
Send a packet PING : ok
2016.06.29 08:55:09 INFO web[o.s.s.e.IndexCreator] Create type views/view
2016.06.29 08:55:09 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [views] create_mapping [view]
2016.06.29 08:55:09 INFO web[o.s.s.n.NotificationService] Notification service started (delay 60 sec.)
2016.06.29 08:55:09 INFO web[o.s.s.s.RegisterMetrics] Register metrics
2016.06.29 08:55:10 DEBUG web[o.s.s.s.RegisterMetrics] Register metrics (done) | time=356ms
2016.06.29 08:55:10 INFO web[o.s.s.r.RegisterRules] Register rules
Received a packet PING : ping
Send a packet PING : ok
2016.06.29 08:55:14 INFO web[o.s.s.r.RegisterRules] Disable rule other:QAC_Cat1_Violation
2016.06.29 08:55:14 INFO web[o.s.s.r.RegisterRules] Disable rule other:Fas_Compiler_WARNING
2016.06.29 08:55:14 INFO web[o.s.s.r.RegisterRules] Disable rule other:QAC_Cat4_Violation
2016.06.29 08:55:14 INFO web[o.s.s.r.RegisterRules] Disable rule other:Fas_Compiler_ERROR
2016.06.29 08:55:14 INFO web[o.s.s.r.RegisterRules] Disable rule other:CUnit_Error
2016.06.29 08:55:14 INFO web[o.s.s.r.RegisterRules] Disable rule other:QAC_Cat3_Violation
2016.06.29 08:55:14 INFO web[o.s.s.r.RegisterRules] Disable rule other:QAC_Cat2_Violation
2016.06.29 08:55:15 DEBUG web[o.s.s.r.RegisterRules] Register rules (done) | time=5612ms
2016.06.29 08:55:15 INFO web[o.s.s.q.RegisterQualityProfiles] Register quality profiles
Received a packet PING : ping
Send a packet PING : ok
2016.06.29 08:55:19 DEBUG web[o.s.s.q.RegisterQualityProfiles] Register quality profiles (done) | time=4256ms
2016.06.29 08:55:20 INFO web[o.s.s.s.RegisterNewMeasureFilters] Register measure filters
2016.06.29 08:55:20 DEBUG web[o.s.s.s.RegisterNewMeasureFilters] Register measure filters (done) | time=7ms
2016.06.29 08:55:20 INFO web[o.s.s.s.RegisterDashboards] Register dashboards
2016.06.29 08:55:20 DEBUG web[o.s.s.s.RegisterDashboards] Register dashboards (done) | time=13ms
2016.06.29 08:55:20 INFO web[o.s.s.s.RegisterPermissionTemplates] Register permission templates
2016.06.29 08:55:20 DEBUG web[o.s.s.s.RegisterPermissionTemplates] Register permission templates (done) | time=29ms
2016.06.29 08:55:20 INFO web[o.s.s.s.RenameDeprecatedPropertyKeys] Rename deprecated property keys
2016.06.29 08:55:20 DEBUG web[o.s.s.p.ServerLifecycleNotifier] Notify ServerStopHandler handlers...
2016.06.29 08:55:20 INFO web[o.s.s.n.NotificationService] Notification service stopped
2016.06.29 08:55:20 ERROR web[o.a.c.c.C.[.[.[/]] Exception sending context initialized event to listener instance of class org.sonar.server.platform.PlatformServletContextListener
java.lang.NullPointerException: null
at org.sonar.server.startup.FeedUsersLocalStartupTask.updateUsersLocal(FeedUsersLocalStartupTask.java:93) ~[sonar-server-5.6.jar:na]
at org.sonar.server.startup.FeedUsersLocalStartupTask.start(FeedUsersLocalStartupTask.java:76) ~[sonar-server-5.6.jar:na]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_72]
at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0_72]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0_72]
at java.lang.reflect.Method.invoke(Unknown Source) ~[na:1.8.0_72]
at org.picocontainer.lifecycle.ReflectionLifecycleStrategy.invokeMethod(ReflectionLifecycleStrategy.java:110) ~[picocontainer-2.15.jar:na]
at org.picocontainer.lifecycle.ReflectionLifecycleStrategy.start(ReflectionLifecycleStrategy.java:89) ~[picocontainer-2.15.jar:na]
at org.sonar.core.platform.ComponentContainer$1.start(ComponentContainer.java:320) ~[sonar-core-5.6.jar:na]
at org.picocontainer.injectors.AbstractInjectionFactory$LifecycleAdapter.start(AbstractInjectionFactory.java:84) ~[picocontainer-2.15.jar:na]
at org.picocontainer.behaviors.AbstractBehavior.start(AbstractBehavior.java:169) ~[picocontainer-2.15.jar:na]
at org.picocontainer.behaviors.Stored$RealComponentLifecycle.start(Stored.java:132) ~[picocontainer-2.15.jar:na]
at org.picocontainer.behaviors.Stored.start(Stored.java:110) ~[picocontainer-2.15.jar:na]
at org.picocontainer.DefaultPicoContainer.potentiallyStartAdapter(DefaultPicoContainer.java:1016) ~[picocontainer-2.15.jar:na]
at org.picocontainer.DefaultPicoContainer.startAdapters(DefaultPicoContainer.java:1009) ~[picocontainer-2.15.jar:na]
at org.picocontainer.DefaultPicoContainer.start(DefaultPicoContainer.java:767) ~[picocontainer-2.15.jar:na]
at org.sonar.core.platform.ComponentContainer.startComponents(ComponentContainer.java:141) ~[sonar-core-5.6.jar:na]
at org.sonar.server.platform.platformlevel.PlatformLevel.start(PlatformLevel.java:84) ~[sonar-server-5.6.jar:na]
at org.sonar.server.platform.platformlevel.PlatformLevelStartup.access$001(PlatformLevelStartup.java:45) ~[sonar-server-5.6.jar:na]
at org.sonar.server.platform.platformlevel.PlatformLevelStartup$1.doPrivileged(PlatformLevelStartup.java:80) ~[sonar-server-5.6.jar:na]
at org.sonar.server.user.DoPrivileged.execute(DoPrivileged.java:44) ~[sonar-server-5.6.jar:na]
at org.sonar.server.platform.platformlevel.PlatformLevelStartup.start(PlatformLevelStartup.java:77) ~[sonar-server-5.6.jar:na]
at org.sonar.server.platform.Platform.executeStartupTasks(Platform.java:201) ~[sonar-server-5.6.jar:na]
at org.sonar.server.platform.Platform.doStart(Platform.java:114) ~[sonar-server-5.6.jar:na]
at org.sonar.server.platform.Platform.doStart(Platform.java:99) ~[sonar-server-5.6.jar:na]
at org.sonar.server.platform.PlatformServletContextListener.contextInitialized(PlatformServletContextListener.java:44) ~[sonar-server-5.6.jar:na]
at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4812) [tomcat-embed-core-8.0.30.jar:8.0.30]
at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5255) [tomcat-embed-core-8.0.30.jar:8.0.30]
at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) [tomcat-embed-core-8.0.30.jar:8.0.30]
at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1408) [tomcat-embed-core-8.0.30.jar:8.0.30]
at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1398) [tomcat-embed-core-8.0.30.jar:8.0.30]
at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_72]
at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) [na:1.8.0_72]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) [na:1.8.0_72]
at java.lang.Thread.run(Unknown Source) [na:1.8.0_72]
2016.06.29 08:55:20 ERROR web[o.a.c.c.StandardContext] One or more listeners failed to start. Full details will be found in the appropriate container log file
2016.06.29 08:55:20 ERROR web[o.a.c.c.StandardContext] Context [] startup failed due to previous errors
2016.06.29 08:55:20 INFO web[o.a.c.h.Http11NioProtocol] Starting ProtocolHandler ["http-nio-0.0.0.0-19000"]
2016.06.29 08:55:20 INFO web[o.s.s.a.TomcatAccessLog] Web server is started
2016.06.29 08:55:20 INFO web[o.s.s.a.EmbeddedTomcat] HTTP connector enabled on port 19000
2016.06.29 08:55:20 WARN web[o.s.p.ProcessEntryPoint] Fail to start web
java.lang.IllegalStateException: Webapp did not start
at org.sonar.server.app.EmbeddedTomcat.isUp(EmbeddedTomcat.java:84) ~[sonar-server-5.6.jar:na]
at org.sonar.server.app.WebServer.isUp(WebServer.java:47) [sonar-server-5.6.jar:na]
at org.sonar.process.ProcessEntryPoint.launch(ProcessEntryPoint.java:105) ~[sonar-process-5.6.jar:na]
at org.sonar.server.app.WebServer.main(WebServer.java:68) [sonar-server-5.6.jar:na]
2016.06.29 08:55:20 INFO web[o.a.c.h.Http11NioProtocol] Pausing ProtocolHandler ["http-nio-0.0.0.0-19000"]
2016.06.29 08:55:21 INFO web[o.a.c.h.Http11NioProtocol] Stopping ProtocolHandler ["http-nio-0.0.0.0-19000"]
Received a packet PING : ping
Send a packet PING : ok
2016.06.29 08:55:21 INFO web[o.a.c.h.Http11NioProtocol] Destroying ProtocolHandler ["http-nio-0.0.0.0-19000"]
2016.06.29 08:55:21 INFO web[o.s.s.a.TomcatAccessLog] Web server is stopped
2016.06.29 08:55:21 INFO app[o.s.p.m.Monitor] Process[es] is stopping
2016.06.29 08:55:21 INFO es[o.s.p.StopWatcher] Stopping process
2016.06.29 08:55:21 INFO es[o.elasticsearch.node] [sonar-1467183296981] stopping ...
2016.06.29 08:55:23 INFO es[o.elasticsearch.node] [sonar-1467183296981] stopped
2016.06.29 08:55:23 INFO es[o.elasticsearch.node] [sonar-1467183296981] closing ...
2016.06.29 08:55:23 INFO es[o.elasticsearch.node] [sonar-1467183296981] closed
2016.06.29 08:55:23 INFO app[o.s.p.m.Monitor] Process[es] is stopped
Wrapper Manager: ShutdownHook started
WrapperManager.stop(0) called by thread: Wrapper-Shutdown-Hook
Send a packet STOP : 0
Received a packet STOP :
Thread, Wrapper-Shutdown-Hook, handling the shutdown process.
calling listener.stop()
WrapperSimpleApp: stop(0)
returned from listener.stop() -> 0
shutdownJVM(0) Thread:Wrapper-Shutdown-Hook
Send a packet STOPPED : 0
Closing socket.
Server daemon shut down
Wrapper Manager: ShutdownHook complete
<-- Wrapper Stopped
#######################################
terminal
#######################################
D:\downloads\sonarqube-5.6\bin\windows-x86-64>StartSonar.bat
wrapper | --> Wrapper Started as Console
wrapper | Using tick timer.
wrapperp | server listening on port 32000.
wrapper | Launching a JVM...
wrapper | command: "C:\ProgramData\Oracle\Java\javapath\java.exe" -Djava.awt.headless=true -Xms3m -Xmx3m -Djava.library.path="./lib" -classpath "../../lib/jsw/wrapper-3.2.3.jar;../../lib/sonar-application-5.6.
jar" -Dwrapper.key="2Vi4BdQXZfExb8xm" -Dwrapper.port=32000 -Dwrapper.jvm.port.min=31000 -Dwrapper.jvm.port.max=31999 -Dwrapper.debug="TRUE" -Dwrapper.pid=10076 -Dwrapper.version="3.2.3" -Dwrapper.native_library
="wrapper" -Dwrapper.cpu.timeout="10" -Dwrapper.jvmid=1 org.tanukisoftware.wrapper.WrapperSimpleApp org.sonar.application.App
wrapper | JVM started (PID=18044)
jvm 1 | WrapperManager class initialized by thread: main Using classloader: sun.misc.Launcher$AppClassLoader@647e05
jvm 1 | Wrapper (Version 3.2.3) http://wrapper.tanukisoftware.org
jvm 1 | Copyright 1999-2006 Tanuki Software, Inc. All Rights Reserved.
jvm 1 |
jvm 1 | Wrapper Manager: JVM #1
jvm 1 | Running a 32-bit JVM.
jvm 1 | Wrapper Manager: Registering shutdown hook
jvm 1 | Wrapper Manager: Using wrapper
jvm 1 | Load native library. One or more attempts may fail if platform specific libraries do not exist.
jvm 1 | Loading native library failed: wrapper-windows-x86-32.dll Cause: java.lang.UnsatisfiedLinkError: no wrapper-windows-x86-32 in java.library.path
jvm 1 | Loading native library failed: wrapper.dll Cause: java.lang.UnsatisfiedLinkError: D:\downloads\sonarqube-5.6\bin\windows-x86-64\lib\wrapper.dll: Can't load AMD 64-bit .dll on a IA 32-bit platform
jvm 1 |
jvm 1 | WARNING - Unable to load the Wrapper's native library 'wrapper.dll'.
jvm 1 | The file is located on the path at the following location but
jvm 1 | could not be loaded:
jvm 1 | D:\downloads\sonarqube-5.6\bin\windows-x86-64\.\lib\wrapper.dll
jvm 1 | Please verify that the file is readable by the current user
jvm 1 | and that the file has not been corrupted in any way.
jvm 1 | One common cause of this problem is running a 32-bit version
jvm 1 | of the Wrapper with a 64-bit version of Java, or vica versa.
jvm 1 | This is a 32-bit JVM.
jvm 1 | Reported cause:
jvm 1 | D:\downloads\sonarqube-5.6\bin\windows-x86-64\lib\wrapper.dll: Can't load AMD 64-bit .dll on a IA 32-bit platform
jvm 1 | System signals will not be handled correctly.
jvm 1 |
jvm 1 | Java Version : 1.8.0_72-b15 Java HotSpot(TM) Client VM
jvm 1 | Java VM Vendor : Oracle Corporation
jvm 1 |
jvm 1 | Control event monitor thread started.
jvm 1 | Startup runner thread started.
jvm 1 | WrapperManager.start(org.tanukisoftware.wrapper.WrapperSimpleApp@af3868, args[]) called by thread: main
jvm 1 | Communications runner thread started.
jvm 1 | Open socket to wrapper...Wrapper-Connection
jvm 1 | Opened Socket from 31000 to 32000
jvm 1 | Send a packet KEY : 2Vi4BdQXZfExb8xm
jvm 1 | handleSocket(Socket[addr=/127.0.0.1,port=32000,localport=31000])
wrapperp | accepted a socket from 127.0.0.1 on port 31000
wrapperp | read a packet KEY : 2Vi4BdQXZfExb8xm
wrapper | Got key from JVM: 2Vi4BdQXZfExb8xm
wrapperp | send a packet LOW_LOG_LEVEL : 1
wrapperp | send a packet PING_TIMEOUT : 0
wrapperp | send a packet PROPERTIES : (Property Values)
wrapper | Start Application.
wrapperp | send a packet START : start
jvm 1 | Received a packet LOW_LOG_LEVEL : 1
jvm 1 | Wrapper Manager: LowLogLevel from Wrapper is 1
jvm 1 | Received a packet PING_TIMEOUT : 0
jvm 1 | PingTimeout from Wrapper is 0
jvm 1 | Received a packet PROPERTIES : (Property Values)
jvm 1 | Received a packet START : start
jvm 1 | calling WrapperListener.start()
jvm 1 | Waiting for WrapperListener.start runner thread to complete.
jvm 1 | WrapperListener.start runner thread started.
jvm 1 | WrapperSimpleApp: start(args) Will wait up to 2 seconds for the main method to complete.
jvm 1 | WrapperSimpleApp: invoking main method
jvm 1 | 2016.06.29 08:54:57 INFO app[o.s.a.AppFileSystem] Cleaning or creating temp directory D:\downloads\sonarqube-5.6\temp
jvm 1 | 2016.06.29 08:54:57 INFO app[o.s.p.m.JavaProcessLauncher] Launch process[es]: c:\Program Files (x86)\JavaSoft\jre\1.8.0_72\bin\java -Djava.awt.headless=true -Xmx1G -Xms256m -Xss256k -Djava.net.prefe
rIPv4Stack=true -XX:+UseParNewGC -XX:+UseConcMarkSweepGC -XX:CMSInitiatingOccupancyFraction=75 -XX:+UseCMSInitiatingOccupancyOnly -XX:+HeapDumpOnOutOfMemoryError -Djava.io.tmpdir=D:\downloads\sonarqube-5.6\temp
-javaagent:c:\Program Files (x86)\JavaSoft\jre\1.8.0_72\lib\management-agent.jar -cp ./lib/common/*;./lib/search/* org.sonar.search.SearchServer D:\downloads\sonarqube-5.6\temp\sq-process1951482937126755055pro
perties
jvm 1 | Send a packet START_PENDING : 5000
wrapperp | read a packet START_PENDING : 5000
wrapper | JVM signalled a start pending with waitHint of 5000 millis.
jvm 1 | Send a packet START_PENDING : 5000
jvm 1 | WrapperSimpleApp: start(args) end. Main Completed=false, exitCode=null
jvm 1 | WrapperListener.start runner thread stopped.
jvm 1 | returned from WrapperListener.start()
jvm 1 | Send a packet STARTED :
jvm 1 | Startup runner thread stopped.
wrapperp | read a packet START_PENDING : 5000
wrapper | JVM signalled a start pending with waitHint of 5000 millis.
wrapperp | read a packet STARTED :
wrapper | JVM signalled that it was started.
wrapperp | send a packet PING : ping
jvm 1 | Received a packet PING : ping
jvm 1 | Send a packet PING : ok
wrapperp | read a packet PING : ok
wrapper | Got ping response from JVM
jvm 1 | 2016.06.29 08:55:02 INFO app[o.s.p.m.Monitor] Process[es] is up
jvm 1 | 2016.06.29 08:55:02 INFO app[o.s.p.m.JavaProcessLauncher] Launch process[web]: c:\Program Files (x86)\JavaSoft\jre\1.8.0_72\bin\java -Djava.awt.headless=true -Dfile.encoding=UTF-8 -Djruby.management
.enabled=false -Djruby.compile.invokedynamic=false -Xmx512m -Xms128m -XX:+HeapDumpOnOutOfMemoryError -Djava.net.preferIPv4Stack=true -Djava.io.tmpdir=D:\downloads\sonarqube-5.6\temp -javaagent:c:\Program Files
(x86)\JavaSoft\jre\1.8.0_72\lib\management-agent.jar -cp ./lib/common/*;./lib/server/*;D:\downloads\sonarqube-5.6\extensions\jdbc-driver\oracle\ojdbc6.jar org.sonar.server.app.WebServer D:\downloads\sonarqube-5
.6\temp\sq-process6212819906787408381properties
wrapperp | send a packet PING : ping
jvm 1 | Received a packet PING : ping
jvm 1 | Send a packet PING : ok
wrapperp | read a packet PING : ok
wrapper | Got ping response from JVM
wrapperp | send a packet PING : ping
jvm 1 | Received a packet PING : ping
jvm 1 | Send a packet PING : ok
wrapperp | read a packet PING : ok
wrapper | Got ping response from JVM
wrapperp | send a packet PING : ping
jvm 1 | Received a packet PING : ping
jvm 1 | Send a packet PING : ok
wrapperp | read a packet PING : ok
wrapper | Got ping response from JVM
wrapperp | send a packet PING : ping
jvm 1 | Received a packet PING : ping
jvm 1 | Send a packet PING : ok
wrapperp | read a packet PING : ok
wrapper | Got ping response from JVM
wrapperp | send a packet PING : ping
jvm 1 | Received a packet PING : ping
jvm 1 | Send a packet PING : ok
wrapperp | read a packet PING : ok
wrapper | Got ping response from JVM
jvm 1 | 2016.06.29 08:55:21 INFO app[o.s.p.m.Monitor] Process[es] is stopping
jvm 1 | 2016.06.29 08:55:23 INFO app[o.s.p.m.Monitor] Process[es] is stopped
jvm 1 | Wrapper Manager: ShutdownHook started
jvm 1 | WrapperManager.stop(0) called by thread: Wrapper-Shutdown-Hook
jvm 1 | Send a packet STOP : 0
wrapperp | read a packet STOP : 0
wrapper | JVM requested a shutdown. (0)
wrapper | wrapperStopProcess(0) called.
wrapper | Sending stop signal to JVM
wrapperp | send a packet STOP : NULL
jvm 1 | Received a packet STOP :
jvm 1 | Thread, Wrapper-Shutdown-Hook, handling the shutdown process.
jvm 1 | calling listener.stop()
jvm 1 | WrapperSimpleApp: stop(0)
jvm 1 | returned from listener.stop() -> 0
jvm 1 | shutdownJVM(0) Thread:Wrapper-Shutdown-Hook
jvm 1 | Send a packet STOPPED : 0
wrapperp | read a packet STOPPED : 0
wrapper | JVM signalled that it was stopped.
jvm 1 | Closing socket.
wrapperp | socket read no code (closed?).
wrapperp | server listening on port 32001.
jvm 1 | Server daemon shut down
jvm 1 | Wrapper Manager: ShutdownHook complete
wrapper | JVM process exited with a code of 0, leaving the wrapper exit code set to 0.
wrapper | JVM exited normally.
wrapper | <-- Wrapper Stopped
#########################################################
Both loglevels are set to debug. I already tried using the oracle jdbc driver in both 11.x and 12x since the 12.x ones seem to hav some bugs at the moment.
Any ideas about the root cause of this problem? I am running out of ideas...
Regards,
Christian
right now i am trying to upgrade my SQ installation from 5.4 to 5.6 LTS release. I followed the usual upgrade process (http://docs.sonarqube.org/display/SONAR/Upgrading) but with the newest version there seems to be a problem reagrding my database. The server starts up correctly if i use the embedded database for testing purpose, but if i want to enable the oracle connection the startup fails although this worked perfectly with 5.4. Here are the logs:
#######################################
sonar.log:
#######################################
2016.06.29 08:54:57 INFO es[o.s.p.ProcessEntryPoint] Starting es
2016.06.29 08:54:57 INFO es[o.s.s.EsSettings] Elasticsearch listening on 127.0.0.1:9001
2016.06.29 08:54:57 INFO es[o.elasticsearch.node] [sonar-1467183296981] version[1.7.5], pid[33020], build[00f95f4/2016-02-02T09:55:30Z]
2016.06.29 08:54:57 INFO es[o.elasticsearch.node] [sonar-1467183296981] initializing ...
2016.06.29 08:54:57 INFO es[o.e.plugins] [sonar-1467183296981] loaded [], sites []
2016.06.29 08:54:57 INFO es[o.elasticsearch.env] [sonar-1467183296981] using [1] data paths, mounts [[Volume (D:)]], net usable_space [186.8gb], net total_space [465.7gb], types [NTFS]
2016.06.29 08:54:58 WARN es[o.e.bootstrap] JNA not found. native methods will be disabled.
2016.06.29 08:54:58 INFO es[o.elasticsearch.node] [sonar-1467183296981] initialized
2016.06.29 08:54:58 INFO es[o.elasticsearch.node] [sonar-1467183296981] starting ...
2016.06.29 08:54:58 INFO es[o.e.transport] [sonar-1467183296981] bound_address {inet[/127.0.0.1:9001]}, publish_address {inet[/127.0.0.1:9001]}
2016.06.29 08:54:58 INFO es[o.e.discovery] [sonar-1467183296981] sonarqube/zkQijiQ-QEqEKp91sQtXpQ
Received a packet PING : ping
Send a packet PING : ok
2016.06.29 08:55:01 INFO es[o.e.cluster.service] [sonar-1467183296981] new_master [sonar-1467183296981][zkQijiQ-QEqEKp91sQtXpQ][WMUC601454][inet[/127.0.0.1:9001]]{rack_id=sonar-1467183296981}, reason: zen-disco-join (elected_as_master)
2016.06.29 08:55:01 INFO es[o.elasticsearch.node] [sonar-1467183296981] started
2016.06.29 08:55:01 INFO es[o.e.gateway] [sonar-1467183296981] recovered [0] indices into cluster_state
2016.06.29 08:55:02 INFO app[o.s.p.m.Monitor] Process[es] is up
2016.06.29 08:55:02 INFO app[o.s.p.m.JavaProcessLauncher] Launch process[web]: c:\Program Files (x86)\JavaSoft\jre\1.8.0_72\bin\java -Djava.awt.headless=true -Dfile.encoding=UTF-8 -Djruby.management.enabled=false -Djruby.compile.invokedynamic=false -Xmx512m -Xms128m -XX:+HeapDumpOnOutOfMemoryError -Djava.net.preferIPv4Stack=true -Djava.io.tmpdir=D:\downloads\sonarqube-5.6\temp -javaagent:c:\Program Files (x86)\JavaSoft\jre\1.8.0_72\lib\management-agent.jar -cp ./lib/common/*;./lib/server/*;D:\downloads\sonarqube-5.6\extensions\jdbc-driver\oracle\ojdbc6.jar org.sonar.server.app.WebServer D:\downloads\sonarqube-5.6\temp\sq-process6212819906787408381properties
2016.06.29 08:55:02 INFO web[o.s.p.ProcessEntryPoint] Starting web
2016.06.29 08:55:02 INFO web[o.s.s.a.TomcatContexts] Webapp directory: D:\downloads\sonarqube-5.6\web
2016.06.29 08:55:02 INFO web[o.a.c.h.Http11NioProtocol] Initializing ProtocolHandler ["http-nio-0.0.0.0-19000"]
2016.06.29 08:55:02 INFO web[o.a.t.u.n.NioSelectorPool] Using a shared selector for servlet write/read
2016.06.29 08:55:03 INFO web[o.s.s.p.ServerImpl] SonarQube Server / 5.6 / 074f3d1169f9688d15af4aff67e7e672cbeed782
2016.06.29 08:55:03 INFO web[o.sonar.db.Database] Create JDBC data source for jdbc:oracle:thin:@tldb0134vm.bmwgroup.net:1747:alltutf
2016.06.29 08:55:04 INFO web[o.s.s.p.DefaultServerFileSystem] SonarQube home: D:\downloads\sonarqube-5.6
2016.06.29 08:55:04 INFO web[o.e.plugins] [sonar-1467183296981] loaded [], sites []
2016.06.29 08:55:04 INFO web[o.s.s.p.ServerPluginRepository] Deploy plugin C# / 5.0 / 17ddb09047940791828dcb70c9e225f006ab11aa
2016.06.29 08:55:04 INFO web[o.s.s.p.ServerPluginRepository] Deploy plugin Git / 1.2 / a713dd64daf8719ba4e7f551f9a1966c62690c17
2016.06.29 08:55:04 INFO web[o.s.s.p.ServerPluginRepository] Deploy plugin Java / 3.13.1 / cf0f0c950ba3e83a87c7fe11c6ff7e63f4864bd9
2016.06.29 08:55:04 INFO web[o.s.s.p.ServerPluginRepository] Deploy plugin JavaScript / 2.11 / a9b1afa9ceef7079811779d9efc5f8026acb1400
2016.06.29 08:55:04 INFO web[o.s.s.p.ServerPluginRepository] Deploy plugin SVN / 1.3 / aff503d48bc77b07c2b62abf93249d0a20bd355c
2016.06.29 08:55:04 DEBUG web[o.s.c.p.PluginLoader] API compatibility mode is enabled on plugin C# [csharp] (built with API lower than 5.2)
2016.06.29 08:55:04 DEBUG web[o.s.c.p.PluginLoader] API compatibility mode is enabled on plugin Java [java] (built with API lower than 5.2)
2016.06.29 08:55:04 DEBUG web[o.s.c.p.PluginLoader] API compatibility mode is enabled on plugin Git [scmgit] (built with API lower than 5.2)
Received a packet PING : ping
Send a packet PING : ok
2016.06.29 08:55:05 DEBUG web[o.s.c.p.PluginLoader] API compatibility mode is enabled on plugin SVN [scmsvn] (built with API lower than 5.2)
2016.06.29 08:55:05 DEBUG web[o.s.c.p.PluginLoader] API compatibility mode is enabled on plugin JavaScript [javascript] (built with API lower than 5.2)
2016.06.29 08:55:05 INFO web[o.s.s.p.RailsAppsDeployer] Deploying Ruby on Rails applications
2016.06.29 08:55:05 DEBUG web[o.s.c.i.DefaultI18n] Loaded 2325 properties from l10n bundles
2016.06.29 08:55:05 DEBUG web[o.s.s.s.ServerMetadataPersister] Persisting server metadata
2016.06.29 08:55:05 INFO web[o.s.s.p.UpdateCenterClient] Update center: http://update.sonarsource.org/update-center.properties (no proxy)
2016.06.29 08:55:05 DEBUG web[o.s.a.r.Languages] Available languages:
2016.06.29 08:55:05 DEBUG web[o.s.a.r.Languages] * C# => "cs"
2016.06.29 08:55:05 DEBUG web[o.s.a.r.Languages] * Java => "java"
2016.06.29 08:55:05 DEBUG web[o.s.a.r.Languages] * JavaScript => "js"
2016.06.29 08:55:05 INFO web[o.s.s.e.IndexCreator] Create index tests
2016.06.29 08:55:05 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [tests] creating index, cause [api], templates [], shards [5]/[0], mappings []
2016.06.29 08:55:06 INFO web[o.s.s.e.IndexCreator] Create type tests/test
2016.06.29 08:55:06 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [tests] create_mapping [test]
2016.06.29 08:55:06 INFO web[o.s.s.e.IndexCreator] Create index activities
2016.06.29 08:55:06 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [activities] creating index, cause [api], templates [], shards [5]/[0], mappings []
2016.06.29 08:55:07 INFO web[o.s.s.e.IndexCreator] Create type activities/activity
2016.06.29 08:55:07 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [activities] create_mapping [activity]
2016.06.29 08:55:07 INFO web[o.s.s.e.IndexCreator] Create index rules
2016.06.29 08:55:07 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [rules] creating index, cause [api], templates [], shards [5]/[0], mappings []
2016.06.29 08:55:07 INFO web[o.s.s.e.IndexCreator] Create type rules/activeRule
2016.06.29 08:55:07 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [rules] create_mapping [activeRule]
2016.06.29 08:55:07 INFO web[o.s.s.e.IndexCreator] Create type rules/rule
2016.06.29 08:55:07 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [rules] create_mapping [rule]
2016.06.29 08:55:07 INFO web[o.s.s.e.IndexCreator] Create index issues
2016.06.29 08:55:07 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [issues] creating index, cause [api], templates [], shards [5]/[0], mappings []
2016.06.29 08:55:08 INFO web[o.s.s.e.IndexCreator] Create type issues/authorization
2016.06.29 08:55:08 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [issues] create_mapping [authorization]
2016.06.29 08:55:08 INFO web[o.s.s.e.IndexCreator] Create type issues/issue
2016.06.29 08:55:08 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [issues] create_mapping [issue]
2016.06.29 08:55:08 INFO web[o.s.s.e.IndexCreator] Create index users
2016.06.29 08:55:08 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [users] creating index, cause [api], templates [], shards [5]/[0], mappings []
2016.06.29 08:55:08 INFO web[o.s.s.e.IndexCreator] Create type users/user
2016.06.29 08:55:08 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [users] create_mapping [user]
2016.06.29 08:55:08 INFO web[o.s.s.e.IndexCreator] Create index views
2016.06.29 08:55:08 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [views] creating index, cause [api], templates [], shards [5]/[0], mappings []
Received a packet PING : ping
Send a packet PING : ok
2016.06.29 08:55:09 INFO web[o.s.s.e.IndexCreator] Create type views/view
2016.06.29 08:55:09 INFO es[o.e.cluster.metadata] [sonar-1467183296981] [views] create_mapping [view]
2016.06.29 08:55:09 INFO web[o.s.s.n.NotificationService] Notification service started (delay 60 sec.)
2016.06.29 08:55:09 INFO web[o.s.s.s.RegisterMetrics] Register metrics
2016.06.29 08:55:10 DEBUG web[o.s.s.s.RegisterMetrics] Register metrics (done) | time=356ms
2016.06.29 08:55:10 INFO web[o.s.s.r.RegisterRules] Register rules
Received a packet PING : ping
Send a packet PING : ok
2016.06.29 08:55:14 INFO web[o.s.s.r.RegisterRules] Disable rule other:QAC_Cat1_Violation
2016.06.29 08:55:14 INFO web[o.s.s.r.RegisterRules] Disable rule other:Fas_Compiler_WARNING
2016.06.29 08:55:14 INFO web[o.s.s.r.RegisterRules] Disable rule other:QAC_Cat4_Violation
2016.06.29 08:55:14 INFO web[o.s.s.r.RegisterRules] Disable rule other:Fas_Compiler_ERROR
2016.06.29 08:55:14 INFO web[o.s.s.r.RegisterRules] Disable rule other:CUnit_Error
2016.06.29 08:55:14 INFO web[o.s.s.r.RegisterRules] Disable rule other:QAC_Cat3_Violation
2016.06.29 08:55:14 INFO web[o.s.s.r.RegisterRules] Disable rule other:QAC_Cat2_Violation
2016.06.29 08:55:15 DEBUG web[o.s.s.r.RegisterRules] Register rules (done) | time=5612ms
2016.06.29 08:55:15 INFO web[o.s.s.q.RegisterQualityProfiles] Register quality profiles
Received a packet PING : ping
Send a packet PING : ok
2016.06.29 08:55:19 DEBUG web[o.s.s.q.RegisterQualityProfiles] Register quality profiles (done) | time=4256ms
2016.06.29 08:55:20 INFO web[o.s.s.s.RegisterNewMeasureFilters] Register measure filters
2016.06.29 08:55:20 DEBUG web[o.s.s.s.RegisterNewMeasureFilters] Register measure filters (done) | time=7ms
2016.06.29 08:55:20 INFO web[o.s.s.s.RegisterDashboards] Register dashboards
2016.06.29 08:55:20 DEBUG web[o.s.s.s.RegisterDashboards] Register dashboards (done) | time=13ms
2016.06.29 08:55:20 INFO web[o.s.s.s.RegisterPermissionTemplates] Register permission templates
2016.06.29 08:55:20 DEBUG web[o.s.s.s.RegisterPermissionTemplates] Register permission templates (done) | time=29ms
2016.06.29 08:55:20 INFO web[o.s.s.s.RenameDeprecatedPropertyKeys] Rename deprecated property keys
2016.06.29 08:55:20 DEBUG web[o.s.s.p.ServerLifecycleNotifier] Notify ServerStopHandler handlers...
2016.06.29 08:55:20 INFO web[o.s.s.n.NotificationService] Notification service stopped
2016.06.29 08:55:20 ERROR web[o.a.c.c.C.[.[.[/]] Exception sending context initialized event to listener instance of class org.sonar.server.platform.PlatformServletContextListener
java.lang.NullPointerException: null
at org.sonar.server.startup.FeedUsersLocalStartupTask.updateUsersLocal(FeedUsersLocalStartupTask.java:93) ~[sonar-server-5.6.jar:na]
at org.sonar.server.startup.FeedUsersLocalStartupTask.start(FeedUsersLocalStartupTask.java:76) ~[sonar-server-5.6.jar:na]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_72]
at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0_72]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0_72]
at java.lang.reflect.Method.invoke(Unknown Source) ~[na:1.8.0_72]
at org.picocontainer.lifecycle.ReflectionLifecycleStrategy.invokeMethod(ReflectionLifecycleStrategy.java:110) ~[picocontainer-2.15.jar:na]
at org.picocontainer.lifecycle.ReflectionLifecycleStrategy.start(ReflectionLifecycleStrategy.java:89) ~[picocontainer-2.15.jar:na]
at org.sonar.core.platform.ComponentContainer$1.start(ComponentContainer.java:320) ~[sonar-core-5.6.jar:na]
at org.picocontainer.injectors.AbstractInjectionFactory$LifecycleAdapter.start(AbstractInjectionFactory.java:84) ~[picocontainer-2.15.jar:na]
at org.picocontainer.behaviors.AbstractBehavior.start(AbstractBehavior.java:169) ~[picocontainer-2.15.jar:na]
at org.picocontainer.behaviors.Stored$RealComponentLifecycle.start(Stored.java:132) ~[picocontainer-2.15.jar:na]
at org.picocontainer.behaviors.Stored.start(Stored.java:110) ~[picocontainer-2.15.jar:na]
at org.picocontainer.DefaultPicoContainer.potentiallyStartAdapter(DefaultPicoContainer.java:1016) ~[picocontainer-2.15.jar:na]
at org.picocontainer.DefaultPicoContainer.startAdapters(DefaultPicoContainer.java:1009) ~[picocontainer-2.15.jar:na]
at org.picocontainer.DefaultPicoContainer.start(DefaultPicoContainer.java:767) ~[picocontainer-2.15.jar:na]
at org.sonar.core.platform.ComponentContainer.startComponents(ComponentContainer.java:141) ~[sonar-core-5.6.jar:na]
at org.sonar.server.platform.platformlevel.PlatformLevel.start(PlatformLevel.java:84) ~[sonar-server-5.6.jar:na]
at org.sonar.server.platform.platformlevel.PlatformLevelStartup.access$001(PlatformLevelStartup.java:45) ~[sonar-server-5.6.jar:na]
at org.sonar.server.platform.platformlevel.PlatformLevelStartup$1.doPrivileged(PlatformLevelStartup.java:80) ~[sonar-server-5.6.jar:na]
at org.sonar.server.user.DoPrivileged.execute(DoPrivileged.java:44) ~[sonar-server-5.6.jar:na]
at org.sonar.server.platform.platformlevel.PlatformLevelStartup.start(PlatformLevelStartup.java:77) ~[sonar-server-5.6.jar:na]
at org.sonar.server.platform.Platform.executeStartupTasks(Platform.java:201) ~[sonar-server-5.6.jar:na]
at org.sonar.server.platform.Platform.doStart(Platform.java:114) ~[sonar-server-5.6.jar:na]
at org.sonar.server.platform.Platform.doStart(Platform.java:99) ~[sonar-server-5.6.jar:na]
at org.sonar.server.platform.PlatformServletContextListener.contextInitialized(PlatformServletContextListener.java:44) ~[sonar-server-5.6.jar:na]
at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4812) [tomcat-embed-core-8.0.30.jar:8.0.30]
at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5255) [tomcat-embed-core-8.0.30.jar:8.0.30]
at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) [tomcat-embed-core-8.0.30.jar:8.0.30]
at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1408) [tomcat-embed-core-8.0.30.jar:8.0.30]
at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1398) [tomcat-embed-core-8.0.30.jar:8.0.30]
at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_72]
at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) [na:1.8.0_72]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) [na:1.8.0_72]
at java.lang.Thread.run(Unknown Source) [na:1.8.0_72]
2016.06.29 08:55:20 ERROR web[o.a.c.c.StandardContext] One or more listeners failed to start. Full details will be found in the appropriate container log file
2016.06.29 08:55:20 ERROR web[o.a.c.c.StandardContext] Context [] startup failed due to previous errors
2016.06.29 08:55:20 INFO web[o.a.c.h.Http11NioProtocol] Starting ProtocolHandler ["http-nio-0.0.0.0-19000"]
2016.06.29 08:55:20 INFO web[o.s.s.a.TomcatAccessLog] Web server is started
2016.06.29 08:55:20 INFO web[o.s.s.a.EmbeddedTomcat] HTTP connector enabled on port 19000
2016.06.29 08:55:20 WARN web[o.s.p.ProcessEntryPoint] Fail to start web
java.lang.IllegalStateException: Webapp did not start
at org.sonar.server.app.EmbeddedTomcat.isUp(EmbeddedTomcat.java:84) ~[sonar-server-5.6.jar:na]
at org.sonar.server.app.WebServer.isUp(WebServer.java:47) [sonar-server-5.6.jar:na]
at org.sonar.process.ProcessEntryPoint.launch(ProcessEntryPoint.java:105) ~[sonar-process-5.6.jar:na]
at org.sonar.server.app.WebServer.main(WebServer.java:68) [sonar-server-5.6.jar:na]
2016.06.29 08:55:20 INFO web[o.a.c.h.Http11NioProtocol] Pausing ProtocolHandler ["http-nio-0.0.0.0-19000"]
2016.06.29 08:55:21 INFO web[o.a.c.h.Http11NioProtocol] Stopping ProtocolHandler ["http-nio-0.0.0.0-19000"]
Received a packet PING : ping
Send a packet PING : ok
2016.06.29 08:55:21 INFO web[o.a.c.h.Http11NioProtocol] Destroying ProtocolHandler ["http-nio-0.0.0.0-19000"]
2016.06.29 08:55:21 INFO web[o.s.s.a.TomcatAccessLog] Web server is stopped
2016.06.29 08:55:21 INFO app[o.s.p.m.Monitor] Process[es] is stopping
2016.06.29 08:55:21 INFO es[o.s.p.StopWatcher] Stopping process
2016.06.29 08:55:21 INFO es[o.elasticsearch.node] [sonar-1467183296981] stopping ...
2016.06.29 08:55:23 INFO es[o.elasticsearch.node] [sonar-1467183296981] stopped
2016.06.29 08:55:23 INFO es[o.elasticsearch.node] [sonar-1467183296981] closing ...
2016.06.29 08:55:23 INFO es[o.elasticsearch.node] [sonar-1467183296981] closed
2016.06.29 08:55:23 INFO app[o.s.p.m.Monitor] Process[es] is stopped
Wrapper Manager: ShutdownHook started
WrapperManager.stop(0) called by thread: Wrapper-Shutdown-Hook
Send a packet STOP : 0
Received a packet STOP :
Thread, Wrapper-Shutdown-Hook, handling the shutdown process.
calling listener.stop()
WrapperSimpleApp: stop(0)
returned from listener.stop() -> 0
shutdownJVM(0) Thread:Wrapper-Shutdown-Hook
Send a packet STOPPED : 0
Closing socket.
Server daemon shut down
Wrapper Manager: ShutdownHook complete
<-- Wrapper Stopped
#######################################
terminal
#######################################
D:\downloads\sonarqube-5.6\bin\windows-x86-64>StartSonar.bat
wrapper | --> Wrapper Started as Console
wrapper | Using tick timer.
wrapperp | server listening on port 32000.
wrapper | Launching a JVM...
wrapper | command: "C:\ProgramData\Oracle\Java\javapath\java.exe" -Djava.awt.headless=true -Xms3m -Xmx3m -Djava.library.path="./lib" -classpath "../../lib/jsw/wrapper-3.2.3.jar;../../lib/sonar-application-5.6.
jar" -Dwrapper.key="2Vi4BdQXZfExb8xm" -Dwrapper.port=32000 -Dwrapper.jvm.port.min=31000 -Dwrapper.jvm.port.max=31999 -Dwrapper.debug="TRUE" -Dwrapper.pid=10076 -Dwrapper.version="3.2.3" -Dwrapper.native_library
="wrapper" -Dwrapper.cpu.timeout="10" -Dwrapper.jvmid=1 org.tanukisoftware.wrapper.WrapperSimpleApp org.sonar.application.App
wrapper | JVM started (PID=18044)
jvm 1 | WrapperManager class initialized by thread: main Using classloader: sun.misc.Launcher$AppClassLoader@647e05
jvm 1 | Wrapper (Version 3.2.3) http://wrapper.tanukisoftware.org
jvm 1 | Copyright 1999-2006 Tanuki Software, Inc. All Rights Reserved.
jvm 1 |
jvm 1 | Wrapper Manager: JVM #1
jvm 1 | Running a 32-bit JVM.
jvm 1 | Wrapper Manager: Registering shutdown hook
jvm 1 | Wrapper Manager: Using wrapper
jvm 1 | Load native library. One or more attempts may fail if platform specific libraries do not exist.
jvm 1 | Loading native library failed: wrapper-windows-x86-32.dll Cause: java.lang.UnsatisfiedLinkError: no wrapper-windows-x86-32 in java.library.path
jvm 1 | Loading native library failed: wrapper.dll Cause: java.lang.UnsatisfiedLinkError: D:\downloads\sonarqube-5.6\bin\windows-x86-64\lib\wrapper.dll: Can't load AMD 64-bit .dll on a IA 32-bit platform
jvm 1 |
jvm 1 | WARNING - Unable to load the Wrapper's native library 'wrapper.dll'.
jvm 1 | The file is located on the path at the following location but
jvm 1 | could not be loaded:
jvm 1 | D:\downloads\sonarqube-5.6\bin\windows-x86-64\.\lib\wrapper.dll
jvm 1 | Please verify that the file is readable by the current user
jvm 1 | and that the file has not been corrupted in any way.
jvm 1 | One common cause of this problem is running a 32-bit version
jvm 1 | of the Wrapper with a 64-bit version of Java, or vica versa.
jvm 1 | This is a 32-bit JVM.
jvm 1 | Reported cause:
jvm 1 | D:\downloads\sonarqube-5.6\bin\windows-x86-64\lib\wrapper.dll: Can't load AMD 64-bit .dll on a IA 32-bit platform
jvm 1 | System signals will not be handled correctly.
jvm 1 |
jvm 1 | Java Version : 1.8.0_72-b15 Java HotSpot(TM) Client VM
jvm 1 | Java VM Vendor : Oracle Corporation
jvm 1 |
jvm 1 | Control event monitor thread started.
jvm 1 | Startup runner thread started.
jvm 1 | WrapperManager.start(org.tanukisoftware.wrapper.WrapperSimpleApp@af3868, args[]) called by thread: main
jvm 1 | Communications runner thread started.
jvm 1 | Open socket to wrapper...Wrapper-Connection
jvm 1 | Opened Socket from 31000 to 32000
jvm 1 | Send a packet KEY : 2Vi4BdQXZfExb8xm
jvm 1 | handleSocket(Socket[addr=/127.0.0.1,port=32000,localport=31000])
wrapperp | accepted a socket from 127.0.0.1 on port 31000
wrapperp | read a packet KEY : 2Vi4BdQXZfExb8xm
wrapper | Got key from JVM: 2Vi4BdQXZfExb8xm
wrapperp | send a packet LOW_LOG_LEVEL : 1
wrapperp | send a packet PING_TIMEOUT : 0
wrapperp | send a packet PROPERTIES : (Property Values)
wrapper | Start Application.
wrapperp | send a packet START : start
jvm 1 | Received a packet LOW_LOG_LEVEL : 1
jvm 1 | Wrapper Manager: LowLogLevel from Wrapper is 1
jvm 1 | Received a packet PING_TIMEOUT : 0
jvm 1 | PingTimeout from Wrapper is 0
jvm 1 | Received a packet PROPERTIES : (Property Values)
jvm 1 | Received a packet START : start
jvm 1 | calling WrapperListener.start()
jvm 1 | Waiting for WrapperListener.start runner thread to complete.
jvm 1 | WrapperListener.start runner thread started.
jvm 1 | WrapperSimpleApp: start(args) Will wait up to 2 seconds for the main method to complete.
jvm 1 | WrapperSimpleApp: invoking main method
jvm 1 | 2016.06.29 08:54:57 INFO app[o.s.a.AppFileSystem] Cleaning or creating temp directory D:\downloads\sonarqube-5.6\temp
jvm 1 | 2016.06.29 08:54:57 INFO app[o.s.p.m.JavaProcessLauncher] Launch process[es]: c:\Program Files (x86)\JavaSoft\jre\1.8.0_72\bin\java -Djava.awt.headless=true -Xmx1G -Xms256m -Xss256k -Djava.net.prefe
rIPv4Stack=true -XX:+UseParNewGC -XX:+UseConcMarkSweepGC -XX:CMSInitiatingOccupancyFraction=75 -XX:+UseCMSInitiatingOccupancyOnly -XX:+HeapDumpOnOutOfMemoryError -Djava.io.tmpdir=D:\downloads\sonarqube-5.6\temp
-javaagent:c:\Program Files (x86)\JavaSoft\jre\1.8.0_72\lib\management-agent.jar -cp ./lib/common/*;./lib/search/* org.sonar.search.SearchServer D:\downloads\sonarqube-5.6\temp\sq-process1951482937126755055pro
perties
jvm 1 | Send a packet START_PENDING : 5000
wrapperp | read a packet START_PENDING : 5000
wrapper | JVM signalled a start pending with waitHint of 5000 millis.
jvm 1 | Send a packet START_PENDING : 5000
jvm 1 | WrapperSimpleApp: start(args) end. Main Completed=false, exitCode=null
jvm 1 | WrapperListener.start runner thread stopped.
jvm 1 | returned from WrapperListener.start()
jvm 1 | Send a packet STARTED :
jvm 1 | Startup runner thread stopped.
wrapperp | read a packet START_PENDING : 5000
wrapper | JVM signalled a start pending with waitHint of 5000 millis.
wrapperp | read a packet STARTED :
wrapper | JVM signalled that it was started.
wrapperp | send a packet PING : ping
jvm 1 | Received a packet PING : ping
jvm 1 | Send a packet PING : ok
wrapperp | read a packet PING : ok
wrapper | Got ping response from JVM
jvm 1 | 2016.06.29 08:55:02 INFO app[o.s.p.m.Monitor] Process[es] is up
jvm 1 | 2016.06.29 08:55:02 INFO app[o.s.p.m.JavaProcessLauncher] Launch process[web]: c:\Program Files (x86)\JavaSoft\jre\1.8.0_72\bin\java -Djava.awt.headless=true -Dfile.encoding=UTF-8 -Djruby.management
.enabled=false -Djruby.compile.invokedynamic=false -Xmx512m -Xms128m -XX:+HeapDumpOnOutOfMemoryError -Djava.net.preferIPv4Stack=true -Djava.io.tmpdir=D:\downloads\sonarqube-5.6\temp -javaagent:c:\Program Files
(x86)\JavaSoft\jre\1.8.0_72\lib\management-agent.jar -cp ./lib/common/*;./lib/server/*;D:\downloads\sonarqube-5.6\extensions\jdbc-driver\oracle\ojdbc6.jar org.sonar.server.app.WebServer D:\downloads\sonarqube-5
.6\temp\sq-process6212819906787408381properties
wrapperp | send a packet PING : ping
jvm 1 | Received a packet PING : ping
jvm 1 | Send a packet PING : ok
wrapperp | read a packet PING : ok
wrapper | Got ping response from JVM
wrapperp | send a packet PING : ping
jvm 1 | Received a packet PING : ping
jvm 1 | Send a packet PING : ok
wrapperp | read a packet PING : ok
wrapper | Got ping response from JVM
wrapperp | send a packet PING : ping
jvm 1 | Received a packet PING : ping
jvm 1 | Send a packet PING : ok
wrapperp | read a packet PING : ok
wrapper | Got ping response from JVM
wrapperp | send a packet PING : ping
jvm 1 | Received a packet PING : ping
jvm 1 | Send a packet PING : ok
wrapperp | read a packet PING : ok
wrapper | Got ping response from JVM
wrapperp | send a packet PING : ping
jvm 1 | Received a packet PING : ping
jvm 1 | Send a packet PING : ok
wrapperp | read a packet PING : ok
wrapper | Got ping response from JVM
jvm 1 | 2016.06.29 08:55:21 INFO app[o.s.p.m.Monitor] Process[es] is stopping
jvm 1 | 2016.06.29 08:55:23 INFO app[o.s.p.m.Monitor] Process[es] is stopped
jvm 1 | Wrapper Manager: ShutdownHook started
jvm 1 | WrapperManager.stop(0) called by thread: Wrapper-Shutdown-Hook
jvm 1 | Send a packet STOP : 0
wrapperp | read a packet STOP : 0
wrapper | JVM requested a shutdown. (0)
wrapper | wrapperStopProcess(0) called.
wrapper | Sending stop signal to JVM
wrapperp | send a packet STOP : NULL
jvm 1 | Received a packet STOP :
jvm 1 | Thread, Wrapper-Shutdown-Hook, handling the shutdown process.
jvm 1 | calling listener.stop()
jvm 1 | WrapperSimpleApp: stop(0)
jvm 1 | returned from listener.stop() -> 0
jvm 1 | shutdownJVM(0) Thread:Wrapper-Shutdown-Hook
jvm 1 | Send a packet STOPPED : 0
wrapperp | read a packet STOPPED : 0
wrapper | JVM signalled that it was stopped.
jvm 1 | Closing socket.
wrapperp | socket read no code (closed?).
wrapperp | server listening on port 32001.
jvm 1 | Server daemon shut down
jvm 1 | Wrapper Manager: ShutdownHook complete
wrapper | JVM process exited with a code of 0, leaving the wrapper exit code set to 0.
wrapper | JVM exited normally.
wrapper | <-- Wrapper Stopped
#########################################################
Both loglevels are set to debug. I already tried using the oracle jdbc driver in both 11.x and 12x since the 12.x ones seem to hav some bugs at the moment.
Any ideas about the root cause of this problem? I am running out of ideas...
Regards,
Christian
nicolas...@sonarsource.com
Jun 30, 2016, 2:47:37 AM6/30/16
to SonarQube
Hi Christian,
You're hitting a known migration issue, tracked in SONAR-7686 . The problem is actually unrelated to Oracle DB itself, it's simply that when you use your Oracle DB (previously used with SQ 5.4) there is an actual dataset migration occurring (contrary to when you start with embedded DB).
Workaround (SQL query) is mentioned in the aforementioned ticket.
Best regards,
Nicolas
Reply all
Reply to author
Forward
0 new messages