____________________________________________
____ __ ___ _
/ __ \ \ \ / (_) | |
| | | |_ __ ___ _ _\ \ / / _ __| |_ _
| | | | '_ \ / _ \ '_ \ \/ / | |/ _` | | | |
| |__| | |_) | __/ | | \ / | | (_| | |_| |
\____/| .__/ \___|_| |_|\/ |_|\__,_|\__,_|
| |
|_| version 1.7.0
______________________________________________
2018-02-21 18:35:25,954 DEBG 'openvidu-server' stdout output:
2018-02-21 18:35:26,420 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:26,419 [main] io.openvidu.server.OpenViduServer (logStarting) - Starting OpenViduServer with PID 8 (/openvidu-server.jar started by root in /)
2018-02-21 18:35:26,421 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:26,420 [main] io.openvidu.server.OpenViduServer (logStartupProfileInfo) - No active profile set, falling back to default profiles: default
2018-02-21 18:35:26,941 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:26,938 [ound-preinit] org.hibernate.validator.internal.util.Version (<clinit>) - HV000001: Hibernate Validator 5.2.4.Final
2018-02-21 18:35:27,480 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:27,479 [main] org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext (prepareRefresh) - Refreshing org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@50134894: startup date [Wed Feb 21 18:35:27 UTC 2018]; root of context hierarchy
2018-02-21 18:35:29,890 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:29,890 [main] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor (<init>) - JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
2018-02-21 18:35:31,231 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:31,230 [main] org.springframework.boot.context.embedded.tomcat.TomcatEmbeddedServletContainer (initialize) - Tomcat initialized with port(s): 8443 (https)
2018-02-21 18:35:31,276 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:31,275 [main] org.apache.catalina.core.StandardService (log) - Starting service Tomcat
2018-02-21 18:35:31,281 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:31,281 [main] org.apache.catalina.core.StandardEngine (log) - Starting Servlet Engine: Apache Tomcat/8.5.6
2018-02-21 18:35:31,465 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:31,464 [-startStop-1] org.apache.catalina.core.ContainerBase.[Tomcat].[localhost].[/] (log) - Initializing Spring embedded WebApplicationContext
2018-02-21 18:35:31,469 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:31,469 [-startStop-1] org.springframework.web.context.ContextLoader (prepareEmbeddedWebApplicationContext) - Root WebApplicationContext: initialization completed in 3998 ms
2018-02-21 18:35:31,935 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:31,934 [-startStop-1] org.springframework.boot.web.servlet.FilterRegistrationBean (configure) - Mapping filter: 'characterEncodingFilter' to: [/*]
2018-02-21 18:35:31,940 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:31,939 [-startStop-1] org.springframework.boot.web.servlet.FilterRegistrationBean (configure) - Mapping filter: 'hiddenHttpMethodFilter' to: [/*]
2018-02-21 18:35:31,940 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:31,940 [-startStop-1] org.springframework.boot.web.servlet.FilterRegistrationBean (configure) - Mapping filter: 'httpPutFormContentFilter' to: [/*]
2018-02-21 18:35:31,940 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:31,940 [-startStop-1] org.springframework.boot.web.servlet.FilterRegistrationBean (configure) - Mapping filter: 'requestContextFilter' to: [/*]
2018-02-21 18:35:31,944 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:31,943 [-startStop-1] org.springframework.boot.web.servlet.DelegatingFilterProxyRegistrationBean (configure) - Mapping filter: 'springSecurityFilterChain' to: [/*]
2018-02-21 18:35:31,945 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:31,945 [-startStop-1] org.springframework.boot.web.servlet.ServletRegistrationBean (onStartup) - Mapping servlet: 'dispatcherServlet' to [/]
2018-02-21 18:35:32,100 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:32,099 [main] io.openvidu.server.OpenViduServer (kmsManager) - Configuring Kurento Room Server to use first of the following kmss: [ws://localhost:8888/kurento]
2018-02-21 18:35:32,149 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:32,148 [atExec-e1-t0] org.kurento.jsonrpc.client.JsonRpcClientNettyWebSocket (connectNativeClient) - [KurentoClient] Connecting native client
2018-02-21 18:35:32,153 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:32,153 [atExec-e1-t0] org.kurento.jsonrpc.client.JsonRpcClientNettyWebSocket (connectNativeClient) - [KurentoClient] Creating new NioEventLoopGroup
2018-02-21 18:35:32,862 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:32,861 [oopGroup-2-1] org.kurento.jsonrpc.client.JsonRpcClientNettyWebSocket (initChannel) - [KurentoClient] Inititating new Netty channel. Will create new handler too!
2018-02-21 18:35:33,074 DEBG 'kms' stdout output:
0:00:11.680271309 7 0x7f7214001840 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:444:openHandler: Client connected from
2018-02-21 18:35:33,222 DEBG 'kms' stdout output:
0:00:11.827924431 7 0x7f7214001840 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:422:processMessage: Message: >{"id":1,"method":"ping","params":{"interval":240000},"jsonrpc":"2.0"}<
0:00:11.828109833 7 0x7f7214001840 INFO KurentoServerMethods ServerMethods.cpp:794:ping: WebSocket Ping/Pong
0:00:11.828174181 7 0x7f7214001840 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:424:processMessage: Response: >{"id":1,"jsonrpc":"2.0","result":{"value":"pong"}}
<
2018-02-21 18:35:34,324 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:34,323 [main] org.springframework.scheduling.concurrent.ThreadPoolTaskScheduler (initialize) - Initializing ExecutorService 'defaultSockJsTaskScheduler'
2018-02-21 18:35:34,370 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:34,367 [main] org.springframework.scheduling.concurrent.ThreadPoolTaskScheduler (initialize) - Initializing ExecutorService 'jsonrpcTaskScheduler'
2018-02-21 18:35:34,502 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:34,502 [main] org.springframework.web.socket.server.support.WebSocketHandlerMapping (registerHandler) - Mapped URL path [/info] onto handler of type [class org.springframework.web.socket.server.support.WebSocketHttpRequestHandler]
2018-02-21 18:35:34,506 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:34,506 [main] org.springframework.web.socket.server.support.WebSocketHandlerMapping (registerHandler) - Mapped URL path [/room] onto handler of type [class org.springframework.web.socket.server.support.WebSocketHttpRequestHandler]
2018-02-21 18:35:34,530 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:34,530 [main] org.springframework.web.servlet.handler.SimpleUrlHandlerMapping (registerHandler) - Mapped URL path [/room] onto handler of type [class org.kurento.jsonrpc.internal.http.JsonRpcHttpRequestHandler]
2018-02-21 18:35:34,739 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:34,739 [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter (initControllerAdviceCache) - Looking for @ControllerAdvice: org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@50134894: startup date [Wed Feb 21 18:35:27 UTC 2018]; root of context hierarchy
2018-02-21 18:35:34,897 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:34,895 [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping (register) - Mapped "{[/api/tokens],methods=[POST]}" onto public org.springframework.http.ResponseEntity<org.json.simple.JSONObject> io.openvidu.server.rest.SessionRestController.newToken(java.util.Map<?, ?>)
2018-02-21 18:35:34,917 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:34,910 [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping (register) - Mapped "{[/api/sessions],methods=[POST]}" onto public org.springframework.http.ResponseEntity<org.json.simple.JSONObject> io.openvidu.server.rest.SessionRestController.getSessionId(java.util.Map<?, ?>)
2018-02-21 18:35:34,921 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:34,921 [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping (register) - Mapped "{[/api/getThresholdSpeaker]}" onto public java.lang.Integer io.openvidu.server.rest.SessionRestController.getThresholdSpeaker()
2018-02-21 18:35:34,925 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:34,925 [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping (register) - Mapped "{[/api/getUpdateSpeakerInterval]}" onto public java.lang.Integer
io.openvidu.server.rest.SessionRestController.getUpdateSpeakerInterval()
2018-02-21 18:35:34,926 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:34,925 [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping (register) - Mapped "{[/api/sessions],methods=[GET]}" onto public java.util.Set<java.lang.String> io.openvidu.server.rest.SessionRestController.getAllSessions()
2018-02-21 18:35:34,929 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:34,929 [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping (register) - Mapped "{[/accept-certificate],methods=[GET]}" onto public java.lang.String io.openvidu.server.rest.CertificateRestController.acceptCert() throws java.lang.Exception
2018-02-21 18:35:34,940 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:34,940 [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping (register) - Mapped "{[/error],produces=[text/html]}" onto public org.springframework.web.servlet.ModelAndView org.springframework.boot.autoconfigure.web.BasicErrorController.errorHtml(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)
2018-02-21 18:35:34,940 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:34,940 [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping (register) - Mapped "{[/error]}" onto public org.springframework.http.ResponseEntity<java.util.Map<java.lang.String, java.lang.Object>> org.springframework.boot.autoconfigure.web.BasicErrorController.error(javax.servlet.http.HttpServletRequest)
2018-02-21 18:35:35,004 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:35,003 [main] org.springframework.web.servlet.handler.SimpleUrlHandlerMapping (registerHandler) - Mapped URL path [/recordings/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2018-02-21 18:35:35,009 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:35,009 [main] org.springframework.web.servlet.handler.SimpleUrlHandlerMapping (registerHandler) - Mapped URL path [/webjars/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2018-02-21 18:35:35,012 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:35,012 [main] org.springframework.web.servlet.handler.SimpleUrlHandlerMapping (registerHandler) - Mapped URL path [/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2018-02-21 18:35:35,134 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:35,133 [main] org.springframework.web.servlet.handler.SimpleUrlHandlerMapping (registerHandler) - Mapped URL path [/**/favicon.ico] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2018-02-21 18:35:35,253 DEBG 'openvidu-server' stdout output:
[WARN] 2018-02-21 18:35:35,252 [main] org.thymeleaf.templatemode.TemplateMode (parse) - [THYMELEAF][main] Template Mode 'HTML5' is deprecated. Using Template Mode 'HTML' instead.
2018-02-21 18:35:35,961 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:35,961 [main] org.springframework.boot.autoconfigure.web.WebMvcAutoConfiguration$WelcomePageHandlerMapping (<init>) - Adding welcome page: class path resource [static/index.html]
2018-02-21 18:35:36,418 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:36,418 [main] org.springframework.security.web.DefaultSecurityFilterChain (<init>) - Creating filter chain: Ant [pattern='/css/**'], []
2018-02-21 18:35:36,423 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:36,422 [main] org.springframework.security.web.DefaultSecurityFilterChain (<init>) - Creating filter chain: Ant [pattern='/js/**'], []
2018-02-21 18:35:36,425 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:36,425 [main] org.springframework.security.web.DefaultSecurityFilterChain (<init>) - Creating filter chain: Ant [pattern='/images/**'], []
2018-02-21 18:35:36,425 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:36,425 [main] org.springframework.security.web.DefaultSecurityFilterChain (<init>) - Creating filter chain: Ant [pattern='/webjars/**'], []
2018-02-21 18:35:36,426 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:36,425 [main] org.springframework.security.web.DefaultSecurityFilterChain (<init>) - Creating filter chain: Ant [pattern='/**/favicon.ico'], []
2018-02-21 18:35:36,426 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:36,426 [main] org.springframework.security.web.DefaultSecurityFilterChain (<init>) - Creating filter chain: Ant [pattern='/error'], []
2018-02-21 18:35:36,646 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:36,639 [main] org.springframework.security.web.DefaultSecurityFilterChain (<init>) - Creating filter chain: org.springframework.security.web.util.matcher.AnyRequestMatcher@1, [org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter@67f639d3, org.springframework.security.web.context.SecurityContextPersistenceFilter@5b03b9fe, org.springframework.security.web.header.HeaderWriterFilter@54bff557, org.springframework.security.web.authentication.logout.LogoutFilter@3a44431a, org.springframework.security.web.authentication.www.BasicAuthenticationFilter@3cebbb30, org.springframework.security.web.savedrequest.RequestCacheAwareFilter@434a63ab, org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@2805d709, org.springframework.security.web.authentication.AnonymousAuthenticationFilter@6253c26, org.springframework.security.web.session.SessionManagementFilter@5a56cdac, org.springframework.security.web.access.ExceptionTranslationFilter@c88a337, org.springframework.security.web.access.intercept.FilterSecurityInterceptor@550dbc7a]
2018-02-21 18:35:36,668 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:36,664 [main] org.springframework.security.web.DefaultSecurityFilterChain (<init>) - Creating filter chain: OrRequestMatcher [requestMatchers=[Ant [pattern='/**']]], [org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter@55634720, org.springframework.security.web.context.SecurityContextPersistenceFilter@54a7079e, org.springframework.security.web.header.HeaderWriterFilter@61001b64, org.springframework.security.web.authentication.logout.LogoutFilter@4b8ee4de, org.springframework.security.web.authentication.www.BasicAuthenticationFilter@27f981c6, org.springframework.security.web.savedrequest.RequestCacheAwareFilter@26e356f0, org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@47d9a273, org.springframework.security.web.authentication.AnonymousAuthenticationFilter@4b0d79fc, org.springframework.security.web.session.SessionManagementFilter@4310d43, org.springframework.security.web.access.ExceptionTranslationFilter@201a4587, org.springframework.security.web.access.intercept.FilterSecurityInterceptor@4659191b]
2018-02-21 18:35:36,921 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:36,915 [main] org.springframework.jmx.export.annotation.AnnotationMBeanExporter (afterSingletonsInstantiated) - Registering beans for JMX exposure on startup
2018-02-21 18:35:36,940 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:36,937 [main] org.springframework.context.support.DefaultLifecycleProcessor (start) - Starting beans in phase
2147483647
2018-02-21 18:35:36,972 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:36,972 [main] org.apache.coyote.http11.Http11NioProtocol (log) - Initializing ProtocolHandler ["https-jsse-nio-0.0.0.0-8443"]
2018-02-21 18:35:37,000 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:37,000 [main] org.apache.coyote.http11.Http11NioProtocol (log) - Starting ProtocolHandler [https-jsse-nio-0.0.0.0-8443]
2018-02-21 18:35:37,372 DEBG 'openvidu-server' stdout output:
[ERROR] 2018-02-21 18:35:37,371 [main] org.apache.coyote.http11.Http11NioProtocol (log) - Failed to start end point associated with ProtocolHandler [https-jsse-nio-0.0.0.0-8443]
java.lang.IllegalArgumentException: java.io.IOException: Keystore was tampered with, or password was incorrect
at org.apache.tomcat.util.net.AbstractJsseEndpoint.createSSLContext(AbstractJsseEndpoint.java:103)
at org.apache.tomcat.util.net.AbstractJsseEndpoint.initialiseSsl(AbstractJsseEndpoint.java:81)
at org.apache.tomcat.util.net.NioEndpoint.bind(NioEndpoint.java:244)
at org.apache.tomcat.util.net.AbstractEndpoint.start(AbstractEndpoint.java:874)
at org.apache.coyote.AbstractProtocol.start(AbstractProtocol.java:590)
at org.apache.catalina.connector.Connector.startInternal(Connector.java:969)
at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150)
at org.apache.catalina.core.StandardService.addConnector(StandardService.java:225)
at org.springframework.boot.context.embedded.tomcat.TomcatEmbeddedServletContainer.addPreviouslyRemovedConnectors(TomcatEmbeddedServletContainer.java:233)
at org.springframework.boot.context.embedded.tomcat.TomcatEmbeddedServletContainer.start(TomcatEmbeddedServletContainer.java:178)
at org.springframework.boot.context.embedded.EmbeddedWebApplicationContext.startEmbeddedServletContainer(EmbeddedWebApplicationContext.java:297)
at org.springframework.boot.context.embedded.EmbeddedWebApplicationContext.finishRefresh(EmbeddedWebApplicationContext.java:145)
at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:544)
at org.springframework.boot.context.embedded.EmbeddedWebApplicationContext.refresh(EmbeddedWebApplicationContext.java:122)
at org.springframework.boot.SpringApplication.refresh(SpringApplication.java:761)
at org.springframework.boot.SpringApplication.refreshContext(SpringApplication.java:371)
at org.springframework.boot.SpringApplication.run(SpringApplication.java:315)
at org.springframework.boot.SpringApplication.run(SpringApplication.java:1186)
at org.springframework.boot.SpringApplication.run(SpringApplication.java:1175)
at io.openvidu.server.OpenViduServer.start(OpenViduServer.java:247)
at io.openvidu.server.OpenViduServer.main(OpenViduServer.java:135)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.springframework.boot.loader.MainMethodRunner.run(MainMethodRunner.java:48)
at org.springframework.boot.loader.Launcher.launch(Launcher.java:87)
at org.springframework.boot.loader.Launcher.launch(Launcher.java:50)
at org.springframework.boot.loader.PropertiesLauncher.main(PropertiesLauncher.java:521)
Caused by: java.io.IOException: Keystore was tampered with, or password was incorrect
at sun.security.provider.JavaKeyStore.engineLoad(JavaKeyStore.java:780)
at sun.security.provider.JavaKeyStore$JKS.engineLoad(JavaKeyStore.java:56)
at sun.security.provider.KeyStoreDelegator.engineLoad(KeyStoreDelegator.java:224)
at sun.security.provider.JavaKeyStore$DualFormatJKS.engineLoad(JavaKeyStore.java:70)
at java.security.KeyStore.load(KeyStore.java:1445)
at org.apache.tomcat.util.net.SSLUtilBase.getStore(SSLUtilBase.java:136)
at org.apache.tomcat.util.net.SSLHostConfigCertificate.getCertificateKeystore(SSLHostConfigCertificate.java:187)
at org.apache.tomcat.util.net.jsse.JSSEUtil.getKeyManagers(JSSEUtil.java:185)
at org.apache.tomcat.util.net.AbstractJsseEndpoint.createSSLContext(AbstractJsseEndpoint.java:101)
... 28 common frames omitted
Caused by: java.security.UnrecoverableKeyException: Password verification failed
at sun.security.provider.JavaKeyStore.engineLoad(JavaKeyStore.java:778)
... 36 common frames omitted
2018-02-21 18:35:37,389 DEBG 'openvidu-server' stdout output:
[ERROR] 2018-02-21 18:35:37,381 [main] org.apache.catalina.core.StandardService (log) - Failed to start connector [Connector[HTTP/1.1-8443]]
org.apache.catalina.LifecycleException: Failed to start component [Connector[HTTP/1.1-8443]]
at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:167)
at org.apache.catalina.core.StandardService.addConnector(StandardService.java:225)
at org.springframework.boot.context.embedded.tomcat.TomcatEmbeddedServletContainer.addPreviouslyRemovedConnectors(TomcatEmbeddedServletContainer.java:233)
at org.springframework.boot.context.embedded.tomcat.TomcatEmbeddedServletContainer.start(TomcatEmbeddedServletContainer.java:178)
at org.springframework.boot.context.embedded.EmbeddedWebApplicationContext.startEmbeddedServletContainer(EmbeddedWebApplicationContext.java:297)
at org.springframework.boot.context.embedded.EmbeddedWebApplicationContext.finishRefresh(EmbeddedWebApplicationContext.java:145)
at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:544)
at org.springframework.boot.context.embedded.EmbeddedWebApplicationContext.refresh(EmbeddedWebApplicationContext.java:122)
at org.springframework.boot.SpringApplication.refresh(SpringApplication.java:761)
at org.springframework.boot.SpringApplication.refreshContext(SpringApplication.java:371)
at org.springframework.boot.SpringApplication.run(SpringApplication.java:315)
at org.springframework.boot.SpringApplication.run(SpringApplication.java:1186)
at org.springframework.boot.SpringApplication.run(SpringApplication.java:1175)
at io.openvidu.server.OpenViduServer.start(OpenViduServer.java:247)
at io.openvidu.server.OpenViduServer.main(OpenViduServer.java:135)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.springframework.boot.loader.MainMethodRunner.run(MainMethodRunner.java:48)
at org.springframework.boot.loader.Launcher.launch(Launcher.java:87)
at org.springframework.boot.loader.Launcher.launch(Launcher.java:50)
at org.springframework.boot.loader.PropertiesLauncher.main(PropertiesLauncher.java:521)
Caused by: org.apache.catalina.LifecycleException: service.getName(): "Tomcat"; Protocol handler start failed
at org.apache.catalina.connector.Connector.startInternal(Connector.java:976)
at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150)
... 22 common frames omitted
Caused by: java.lang.IllegalArgumentException: java.io.IOException: Keystore was tampered with, or password was incorrect
at org.apache.tomcat.util.net.AbstractJsseEndpoint.createSSLContext(AbstractJsseEndpoint.java:103)
at org.apache.tomcat.util.net.AbstractJsseEndpoint.initialiseSsl(AbstractJsseEndpoint.java:81)
at org.apache.tomcat.util.net.NioEndpoint.bind(NioEndpoint.java:244)
at org.apache.tomcat.util.net.AbstractEndpoint.start(AbstractEndpoint.java:874)
at org.apache.coyote.AbstractProtocol.start(AbstractProtocol.java:590)
at org.apache.catalina.connector.Connector.startInternal(Connector.java:969)
... 23 common frames omitted
Caused by: java.io.IOException: Keystore was tampered with, or password was incorrect
at sun.security.provider.JavaKeyStore.engineLoad(JavaKeyStore.java:780)
at sun.security.provider.JavaKeyStore$JKS.engineLoad(JavaKeyStore.java:56)
at sun.security.provider.KeyStoreDelegator.engineLoad(KeyStoreDelegator.java:224)
at sun.security.provider.JavaKeyStore$DualFormatJKS.engineLoad(JavaKeyStore.java:70)
at java.security.KeyStore.load(KeyStore.java:1445)
at org.apache.tomcat.util.net.SSLUtilBase.getStore(SSLUtilBase.java:136)
at org.apache.tomcat.util.net.SSLHostConfigCertificate.getCertificateKeystore(SSLHostConfigCertificate.java:187)
at org.apache.tomcat.util.net.jsse.JSSEUtil.getKeyManagers(JSSEUtil.java:185)
at org.apache.tomcat.util.net.AbstractJsseEndpoint.createSSLContext(AbstractJsseEndpoint.java:101)
... 28 common frames omitted
Caused by: java.security.UnrecoverableKeyException: Password verification failed
at sun.security.provider.JavaKeyStore.engineLoad(JavaKeyStore.java:778)
... 36 common frames omitted
2018-02-21 18:35:37,431 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:37,428 [main] org.apache.coyote.http11.Http11NioProtocol (log) - Pausing ProtocolHandler ["https-jsse-nio-0.0.0.0-8443"]
2018-02-21 18:35:37,434 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:37,433 [main] org.apache.catalina.core.StandardService (log) - Stopping service Tomcat
2018-02-21 18:35:37,471 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:37,471 [main] org.apache.catalina.util.LifecycleBase (log) - The stop() method was called on component [StandardServer[-1]] after stop() had already been called. The second call will
be ignored.
2018-02-21 18:35:37,476 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:37,476 [main] org.apache.coyote.http11.Http11NioProtocol (log) - Stopping ProtocolHandler ["https-jsse-nio-0.0.0.0-8443"]
2018-02-21 18:35:37,480 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:37,480 [main] org.apache.coyote.http11.Http11NioProtocol (log) - Destroying ProtocolHandler ["https-jsse-nio-0.0.0.0-8443"]
2018-02-21 18:35:37,497 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:37,497 [main] org.springframework.boot.autoconfigure.logging.AutoConfigurationReportLoggingInitializer (logAutoConfigurationReport) -
Error starting ApplicationContext. To display the auto-configuration report re-run your application with 'debug' enabled.
2018-02-21 18:35:37,512 DEBG 'openvidu-server' stdout output:
[ERROR] 2018-02-21 18:35:37,511 [main] org.springframework.boot.diagnostics.LoggingFailureAnalysisReporter (report) -
***************************
APPLICATION FAILED TO START
***************************
Description:
The Tomcat connector configured to listen on port 8443 failed to start. The port may already be in use or the connector may be misconfigured.
Action:
Verify the connector's configuration, identify and stop any process that's listening on port 8443, or configure this application to listen on another port.
2018-02-21 18:35:37,519 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:37,518 [main] org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext (doClose) - Closing org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@50134894: startup date [Wed Feb 21 18:35:27 UTC 2018]; root of context hierarchy
2018-02-21 18:35:37,527 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:37,526 [main] org.springframework.context.support.DefaultLifecycleProcessor (stop) - Stopping beans in phase
2147483647
2018-02-21 18:35:37,542 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:37,541 [main] org.springframework.jmx.export.annotation.AnnotationMBeanExporter (destroy) - Unregistering JMX-exposed beans on shutdown
2018-02-21 18:35:37,544 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:37,544 [main] org.springframework.scheduling.concurrent.ThreadPoolTaskScheduler (shutdown) - Shutting down ExecutorService 'jsonrpcTaskScheduler'
2018-02-21 18:35:37,544 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:37,544 [main] org.springframework.scheduling.concurrent.ThreadPoolTaskScheduler (shutdown) - Shutting down ExecutorService 'jsonrpcTaskScheduler'
2018-02-21 18:35:37,549 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:37,548 [main] org.springframework.scheduling.concurrent.ThreadPoolTaskScheduler (shutdown) - Shutting down ExecutorService 'defaultSockJsTaskScheduler'
2018-02-21 18:35:37,549 DEBG 'openvidu-server' stdout output:
[INFO] 2018-02-21 18:35:37,549 [main] io.openvidu.server.core.SessionManager (close) - Closing all sessions
2018-02-21 18:39:23,455 DEBG 'kms' stdout output:
0:04:02.060435873 7 0x7f720c007d80 DEBUG KurentoMediaSet MediaSet.cpp:122:doGarbageCollection: Running garbage collector
2018-02-21 18:39:32,148 DEBG 'kms' stdout output:
0:04:10.749696486 7 0x7f7204001920 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:422:processMessage: Message: >{"id":2,"method":"ping","jsonrpc":"2.0"}<
0:04:10.749827835 7 0x7f7204001920 INFO KurentoServerMethods ServerMethods.cpp:794:ping: WebSocket Ping/Pong
0:04:10.749867406 7 0x7f7204001920 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:424:processMessage: Response: >{"id":2,"jsonrpc":"2.0","result":{"value":"pong"}}
<
2018-02-21 18:43:23,455 DEBG 'kms' stdout output:
0:08:02.060737360 7 0x7f720c007d80 DEBUG KurentoMediaSet MediaSet.cpp:122:doGarbageCollection: Running garbage collector
2018-02-21 18:43:32,142 DEBG 'kms' stdout output:
0:08:10.747803647 7 0x7f7204001920 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:422:processMessage: Message: >{"id":3,"method":"ping","jsonrpc":"2.0"}<
0:08:10.747876855 7 0x7f7204001920 INFO KurentoServerMethods ServerMethods.cpp:794:ping: WebSocket Ping/Pong
0:08:10.747914460 7 0x7f7204001920 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:424:processMessage: Response: >{"id":3,"jsonrpc":"2.0","result":{"value":"pong"}}
<
2018-02-21 18:47:23,455 DEBG 'kms' stdout output:
0:12:02.061041755 7 0x7f720c007d80 DEBUG KurentoMediaSet MediaSet.cpp:122:doGarbageCollection: Running garbage collector
2018-02-21 18:47:32,142 DEBG 'kms' stdout output:
0:12:10.747194486 7 0x7f7204001920 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:422:processMessage: Message: >{"id":4,"method":"ping","jsonrpc":"2.0"}<
0:12:10.747270194 7 0x7f7204001920 INFO KurentoServerMethods ServerMethods.cpp:794:ping: WebSocket Ping/Pong
0:12:10.747310663 7 0x7f7204001920 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:424:processMessage: Response: >{"id":4,"jsonrpc":"2.0","result":{"value":"pong"}}
<
2018-02-21 18:51:23,455 DEBG 'kms' stdout output:
0:16:02.061260706 7 0x7f720c007d80 DEBUG KurentoMediaSet MediaSet.cpp:122:doGarbageCollection: Running garbage collector
2018-02-21 18:51:32,141 DEBG 'kms' stdout output:
0:16:10.746788044 7 0x7f7204001920 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:422:processMessage: Message: >{"id":5,"method":"ping","jsonrpc":"2.0"}<
0:16:10.746913558 7 0x7f7204001920 INFO KurentoServerMethods ServerMethods.cpp:794:ping: WebSocket Ping/Pong
0:16:10.746952129 7 0x7f7204001920 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:424:processMessage: Response: >{"id":5,"jsonrpc":"2.0","result":{"value":"pong"}}
<
2018-02-21 18:55:23,455 DEBG 'kms' stdout output:
0:20:02.061408790 7 0x7f720c007d80 DEBUG KurentoMediaSet MediaSet.cpp:122:doGarbageCollection: Running garbage collector
2018-02-21 18:55:32,142 DEBG 'kms' stdout output:
0:20:10.747594180 7 0x7f7208001ac0 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:422:processMessage: Message: >{"id":6,"method":"ping","jsonrpc":"2.0"}<
0:20:10.747692535 7 0x7f7208001ac0 INFO KurentoServerMethods ServerMethods.cpp:794:ping: WebSocket Ping/Pong
0:20:10.747731594 7 0x7f7208001ac0 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:424:processMessage: Response: >{"id":6,"jsonrpc":"2.0","result":{"value":"pong"}}
<
2018-02-21 18:59:23,456 DEBG 'kms' stdout output:
0:24:02.061561634 7 0x7f720c007d80 DEBUG KurentoMediaSet MediaSet.cpp:122:doGarbageCollection: Running garbage collector
2018-02-21 18:59:32,141 DEBG 'kms' stdout output:
0:24:10.746938959 7 0x7f7208001ac0 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:422:processMessage: Message: >{"id":7,"method":"ping","jsonrpc":"2.0"}<
0:24:10.747005562 7 0x7f7208001ac0 INFO KurentoServerMethods ServerMethods.cpp:794:ping: WebSocket Ping/Pong
0:24:10.747043819 7 0x7f7208001ac0 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:424:processMessage: Response: >{"id":7,"jsonrpc":"2.0","result":{"value":"pong"}}
<
2018-02-21 19:03:23,456 DEBG 'kms' stdout output:
0:28:02.061733632 7 0x7f720c007d80 DEBUG KurentoMediaSet MediaSet.cpp:122:doGarbageCollection: Running garbage collector
2018-02-21 19:03:32,141 DEBG 'kms' stdout output:
0:28:10.746955437 7 0x7f7208001ac0 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:422:processMessage: Message: >{"id":8,"method":"ping","jsonrpc":"2.0"}<
0:28:10.747088059 7 0x7f7208001ac0 INFO KurentoServerMethods ServerMethods.cpp:794:ping: WebSocket Ping/Pong
0:28:10.747128954 7 0x7f7208001ac0 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:424:processMessage: Response: >{"id":8,"jsonrpc":"2.0","result":{"value":"pong"}}
<
2018-02-21 19:07:23,456 DEBG 'kms' stdout output:
0:32:02.061963528 7 0x7f720c007d80 DEBUG KurentoMediaSet MediaSet.cpp:122:doGarbageCollection: Running garbage collector
2018-02-21 19:07:32,143 DEBG 'kms' stdout output:
0:32:10.747960020 7 0x7f7208001ac0 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:422:processMessage: Message: >{"id":9,"method":"ping","jsonrpc":"2.0"}<
0:32:10.748036703 7 0x7f7208001ac0 INFO KurentoServerMethods ServerMethods.cpp:794:ping: WebSocket Ping/Pong
0:32:10.748075472 7 0x7f7208001ac0 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:424:processMessage: Response: >{"id":9,"jsonrpc":"2.0","result":{"value":"pong"}}
<
2018-02-21 19:11:23,456 DEBG 'kms' stdout output:
0:36:02.062248305 7 0x7f720c007d80 DEBUG KurentoMediaSet MediaSet.cpp:122:doGarbageCollection: Running garbage collector
2018-02-21 19:11:32,141 DEBG 'kms' stdout output:
0:36:10.746767281 7 0x7f7208001ac0 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:422:processMessage: Message: >{"id":10,"method":"ping","jsonrpc":"2.0"}<
0:36:10.746855368 7 0x7f7208001ac0 INFO KurentoServerMethods ServerMethods.cpp:794:ping: WebSocket Ping/Pong
0:36:10.746909024 7 0x7f7208001ac0 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:424:processMessage: Response: >{"id":10,"jsonrpc":"2.0","result":{"value":"pong"}}
<
2018-02-21 19:15:23,457 DEBG 'kms' stdout output:
0:40:02.062482891 7 0x7f720c007d80 DEBUG KurentoMediaSet MediaSet.cpp:122:doGarbageCollection: Running garbage collector
2018-02-21 19:15:32,142 DEBG 'kms' stdout output:
0:40:10.747881230 7 0x7f7214001840 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:422:processMessage: Message: >{"id":11,"method":"ping","jsonrpc":"2.0"}<
0:40:10.747979029 7 0x7f7214001840 INFO KurentoServerMethods ServerMethods.cpp:794:ping: WebSocket Ping/Pong
0:40:10.748026001 7 0x7f7214001840 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:424:processMessage: Response: >{"id":11,"jsonrpc":"2.0","result":{"value":"pong"}}
<
2018-02-21 19:19:23,458 DEBG 'kms' stdout output:
0:44:02.062639681 7 0x7f720c007d80 DEBUG KurentoMediaSet MediaSet.cpp:122:doGarbageCollection: Running garbage collector
2018-02-21 19:19:32,141 DEBG 'kms' stdout output:
0:44:10.746700254 7 0x7f7214001840 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:422:processMessage: Message: >{"id":12,"method":"ping","jsonrpc":"2.0"}<
0:44:10.746866544 7 0x7f7214001840 INFO KurentoServerMethods ServerMethods.cpp:794:ping: WebSocket Ping/Pong
0:44:10.746910217 7 0x7f7214001840 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:424:processMessage: Response: >{"id":12,"jsonrpc":"2.0","result":{"value":"pong"}}
<