Red5.sh start and stop...

139 views
Skip to first unread message

markham89

unread,
Oct 15, 2018, 2:48:20 PM10/15/18
to BigBlueButton-Setup
Hi

Red5.sh start and stop...

I have this error message fill my logfile :

red5.sh[1185]: RegisterAgent: onTransFailureResponse start:

red5.sh[1185]: RegisterAgent: onTransFailureResponse 401 or 407:


error see with bbb-conf --debug : 

Oct 15 20:23:10 SIEP-BBB-3 red5-shutdown.sh[18198]: Exception connecting to 127.0.0.1
Oct 15 20:23:10 SIEP-BBB-3 red5-shutdown.sh[18198]: java.lang.ArrayIndexOutOfBoundsException: 0
Oct 15 20:27:45 SIEP-BBB-3 red5-shutdown.sh[20782]: Exception connecting to 127.0.0.1
Oct 15 20:27:45 SIEP-BBB-3 red5-shutdown.sh[20782]: java.lang.ArrayIndexOutOfBoundsException: 0
Oct 15 20:29:35 SIEP-BBB-3 red5-shutdown.sh[22743]: Exception connecting to 127.0.0.1
Oct 15 20:29:35 SIEP-BBB-3 red5-shutdown.sh[22743]: java.lang.ArrayIndexOutOfBoundsException: 0 

with manual Red5 launch :

Running on  Linux
Starting Red5
Red5 root: /usr/share/red5
Configuation root: /usr/share/red5/conf
Red5 server jar was found
URL list: [file:/usr/share/red5/red5-server.jar]
Selected libraries: (59 items)
file:/usr/share/red5/red5-server.jar
file:/usr/share/red5/lib/spring-core-4.3.12.RELEASE.jar
file:/usr/share/red5/lib/commons-collections-3.2.2.jar
file:/usr/share/red5/lib/spring-aop-4.3.12.RELEASE.jar
file:/usr/share/red5/lib/logback-core-1.2.3.jar
file:/usr/share/red5/lib/mina-integration-beans-2.0.17.jar
file:/usr/share/red5/lib/rome-1.5.1.jar
file:/usr/share/red5/lib/apache-mime4j-dom-0.8.1.jar
file:/usr/share/red5/lib/httpcore-4.4.6.jar
file:/usr/share/red5/lib/spring-context-support-4.3.12.RELEASE.jar
file:/usr/share/red5/lib/httpclient-4.5.3.jar
file:/usr/share/red5/lib/quartz-2.3.0.jar
file:/usr/share/red5/lib/commons-compress-1.14.jar
file:/usr/share/red5/lib/rome-utils-1.5.1.jar
file:/usr/share/red5/lib/commons-lang3-3.7.jar
file:/usr/share/red5/lib/juniversalchardet-1.0.3.jar
file:/usr/share/red5/lib/xmpcore-5.1.2.jar
file:/usr/share/red5/lib/ehcache-core-2.6.11.jar
file:/usr/share/red5/lib/commons-fileupload-1.2.2.jar
file:/usr/share/red5/lib/spring-context-4.3.12.RELEASE.jar
file:/usr/share/red5/lib/slf4j-api-1.7.25.jar
file:/usr/share/red5/lib/boilerpipe-1.1.0.jar
file:/usr/share/red5/lib/isoparser-1.9.31.jar
file:/usr/share/red5/lib/red5-io-1.0.10-M5.jar
file:/usr/share/red5/lib/commons-codec-1.11.jar
file:/usr/share/red5/lib/spring-web-4.3.12.RELEASE.jar
file:/usr/share/red5/lib/tagsoup-1.2.1.jar
file:/usr/share/red5/lib/bcprov-jdk15on-1.58.jar
file:/usr/share/red5/lib/tika-core-1.16.jar
file:/usr/share/red5/lib/spring-webmvc-4.3.12.RELEASE.jar
file:/usr/share/red5/lib/java-property-utils-1.9.1.jar
file:/usr/share/red5/lib/metadata-extractor-2.9.1.jar
file:/usr/share/red5/lib/pdfbox-tools-2.0.6.jar
file:/usr/share/red5/lib/spring-beans-4.3.12.RELEASE.jar
file:/usr/share/red5/lib/spring-expression-4.3.12.RELEASE.jar
file:/usr/share/red5/lib/pdfbox-debugger-2.0.6.jar
file:/usr/share/red5/lib/apache-mime4j-core-0.8.1.jar
file:/usr/share/red5/lib/tika-parsers-1.16.jar
file:/usr/share/red5/lib/commons-beanutils-1.9.3.jar
file:/usr/share/red5/lib/jcl-over-slf4j-1.7.25.jar
file:/usr/share/red5/lib/mina-core-2.0.17.jar
file:/usr/share/red5/lib/cors-filter-2.6.jar
file:/usr/share/red5/lib/jempbox-1.8.13.jar
file:/usr/share/red5/lib/log4j-over-slf4j-1.7.25.jar
file:/usr/share/red5/lib/asm-5.0.4.jar
file:/usr/share/red5/lib/mchange-commons-java-0.2.11.jar
file:/usr/share/red5/lib/red5-server-common-1.0.10-M5.jar
file:/usr/share/red5/lib/commons-io-2.5.jar
file:/usr/share/red5/lib/jul-to-slf4j-1.7.25.jar
file:/usr/share/red5/lib/logback-classic-1.2.3.jar
file:/usr/share/red5/conf/
file:/usr/share/red5/plugins/
file:/usr/share/red5/plugins/tomcat-embed-el-8.5.23.jar
file:/usr/share/red5/plugins/tomcat-embed-jasper-8.5.23.jar
file:/usr/share/red5/plugins/ecj-3.12.3.jar
file:/usr/share/red5/plugins/tomcatplugin-1.18.jar
file:/usr/share/red5/plugins/websocket-1.12.jar
file:/usr/share/red5/plugins/tomcat-embed-core-8.5.23.jar
file:/usr/share/red5/plugins/tomcat-annotations-api-8.5.23.jar

Root: /usr/share/red5
Deploy type: bootstrap
20:41:50,655 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
20:41:50,656 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
20:41:50,656 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/etc/red5/logback.xml]
20:41:50,656 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs multiple times on the classpath.
20:41:50,656 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs at [file:/etc/red5/logback.xml]
20:41:50,656 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs at [file:/usr/share/red5/conf/logback.xml]
20:41:50,714 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
20:41:50,722 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Will scan for changes in [file:/etc/red5/logback.xml]
20:41:50,722 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Setting ReconfigureOnChangeTask scanning period to 1 minutes
20:41:50,724 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
20:41:50,727 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [CONSOLE]
20:41:50,733 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
20:41:50,775 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
20:41:50,778 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE]
20:41:50,784 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1740000325 - No compression will be used
20:41:50,786 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1740000325 - Will use the pattern log/red5.%d{yyyy-MM-dd}.log for the active file
20:41:50,790 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - The date pattern is 'yyyy-MM-dd' from file name pattern 'log/red5.%d{yyyy-MM-dd}.log'.
20:41:50,790 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Roll-over at midnight.
20:41:50,793 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Setting initial period to Mon Oct 15 20:41:17 CEST 2018
20:41:50,796 |-WARN in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - This appender no longer admits a layout as a sub-component, set an encoder instead.
20:41:50,796 |-WARN in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - To ensure compatibility, wrapping your layout in LayoutWrappingEncoder.
20:41:50,796 |-WARN in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - See also http://logback.qos.ch/codes.html#layoutInsteadOfEncoder for details
20:41:50,797 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: log/red5.log
20:41:50,797 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [log/red5.log]
20:41:50,798 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
20:41:50,798 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [ERRORFILE]
20:41:50,799 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1142020464 - No compression will be used
20:41:50,799 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1142020464 - Will use the pattern log/error.%d{yyyy-MM-dd}.log for the active file
20:41:50,800 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - The date pattern is 'yyyy-MM-dd' from file name pattern 'log/error.%d{yyyy-MM-dd}.log'.
20:41:50,800 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Roll-over at midnight.
20:41:50,800 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Setting initial period to Mon Oct 15 20:40:48 CEST 2018
20:41:50,801 |-WARN in ch.qos.logback.core.rolling.RollingFileAppender[ERRORFILE] - This appender no longer admits a layout as a sub-component, set an encoder instead.
20:41:50,801 |-WARN in ch.qos.logback.core.rolling.RollingFileAppender[ERRORFILE] - To ensure compatibility, wrapping your layout in LayoutWrappingEncoder.
20:41:50,801 |-WARN in ch.qos.logback.core.rolling.RollingFileAppender[ERRORFILE] - See also http://logback.qos.ch/codes.html#layoutInsteadOfEncoder for details
20:41:50,802 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
20:41:50,803 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[ERRORFILE] - Active log file name: log/error.log
20:41:50,803 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[ERRORFILE] - File property is set to [log/error.log]
20:41:50,803 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - ROOT level set to INFO
20:41:50,803 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [CONSOLE] to Logger[ROOT]
20:41:50,804 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [FILE] to Logger[ROOT]
20:41:50,804 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [ERRORFILE] to Logger[ROOT]
20:41:50,804 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - org.red5.server.stream.PlayEngine level set to INFO
20:41:50,804 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - org.red5.server.net.rtmp.codec.RTMPProtocolEncoder level set to INFO
20:41:50,804 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - org.red5.server.net.rtmp.RTMPConnection level set to INFO
20:41:50,805 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.scheduling] to WARN
20:41:50,805 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - org.springframework level set to INFO
20:41:50,805 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - org.apache level set to INFO
20:41:50,805 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - ch.qos level set to WARN
20:41:50,805 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.jasper.servlet] to WARN
20:41:50,805 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
20:41:50,806 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6442b0a6 - Registering current configuration as safe fallback point

2018-10-15T20:41:50.818+02:00 [main] org.red5.server.Launcher - Red5 Server 1.0.10 (https://github.com/Red5)
2018-10-15T20:41:50.853+02:00 [main] o.s.c.s.FileSystemXmlApplicationContext - Refreshing org.springframework.context.support.FileSystemXmlApplicationContext@28d25987: startup date [Mon Oct 15 20:41:50 CEST 2018]; root of context hierarchy
2018-10-15T20:41:50.894+02:00 [main] o.s.b.f.xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [red5.xml]
2018-10-15T20:41:50.982+02:00 [main] o.s.b.f.xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [jee-container.xml]
2018-10-15T20:41:51.116+02:00 [main] o.r.spring.Red5ApplicationContext - setApplicationContext: org.springframework.context.support.FileSystemXmlApplicationContext@28d25987: startup date [Mon Oct 15 20:41:50 CEST 2018]; root of context hierarchy
2018-10-15T20:41:51.116+02:00 [main] o.r.spring.Red5ApplicationContext - Red5ApplicationContext init
2018-10-15T20:41:51.117+02:00 [main] o.r.spring.Red5ApplicationContext - Refreshing ApplicationContext 'red5.common': startup date [Mon Oct 15 20:41:51 CEST 2018]; parent: org.springframework.context.support.FileSystemXmlApplicationContext@28d25987
2018-10-15T20:41:51.117+02:00 [main] o.s.b.f.xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [red5-common.xml]
2018-10-15T20:41:51.216+02:00 [main] org.quartz.impl.StdSchedulerFactory - Using default implementation for ThreadExecutor
2018-10-15T20:41:51.231+02:00 [main] o.quartz.core.SchedulerSignalerImpl - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
2018-10-15T20:41:51.231+02:00 [main] org.quartz.core.QuartzScheduler - Quartz Scheduler v.2.3.0 created.
2018-10-15T20:41:51.232+02:00 [main] org.quartz.simpl.RAMJobStore - RAMJobStore initialized.
2018-10-15T20:41:51.233+02:00 [main] org.quartz.core.QuartzScheduler - Scheduler meta-data: Quartz Scheduler (v2.3.0) 'Red5_Scheduler' with instanceId 'NON_CLUSTERED'
  Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
  NOT STARTED.
  Currently in standby mode.
  Number of jobs executed: 0
  Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 32 threads.
  Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered.

2018-10-15T20:41:51.233+02:00 [main] org.quartz.impl.StdSchedulerFactory - Quartz scheduler 'Red5_Scheduler' initialized from the specified file : '/usr/share/red5/conf/quartz.properties'
2018-10-15T20:41:51.233+02:00 [main] org.quartz.impl.StdSchedulerFactory - Quartz scheduler version: 2.3.0
2018-10-15T20:41:51.234+02:00 [main] org.quartz.core.QuartzScheduler - Scheduler Red5_Scheduler_$_NON_CLUSTERED started.
2018-10-15T20:41:51.517+02:00 [main] o.s.j.e.a.AnnotationMBeanExporter - Registering beans for JMX exposure on startup
2018-10-15T20:41:51.519+02:00 [main] o.s.j.e.a.AnnotationMBeanExporter - Bean with name 'schedulingService' has been autodetected for JMX exposure
2018-10-15T20:41:51.520+02:00 [main] o.s.j.e.a.AnnotationMBeanExporter - Bean with name 'clientBroadcastStream' has been autodetected for JMX exposure
2018-10-15T20:41:51.645+02:00 [main] o.s.j.e.a.AnnotationMBeanExporter - Located MBean 'schedulingService': registering with JMX server as MBean [org.red5.server:name=schedulingService,type=QuartzSchedulingService]
2018-10-15T20:41:51.653+02:00 [main] o.r.spring.Red5ApplicationContext - setApplicationContext: org.springframework.context.support.FileSystemXmlApplicationContext@28d25987: startup date [Mon Oct 15 20:41:50 CEST 2018]; root of context hierarchy
2018-10-15T20:41:51.653+02:00 [main] o.r.spring.Red5ApplicationContext - Red5ApplicationContext init
2018-10-15T20:41:51.654+02:00 [main] o.r.spring.Red5ApplicationContext - Refreshing ApplicationContext 'red5.core': startup date [Mon Oct 15 20:41:51 CEST 2018]; parent: ApplicationContext 'red5.common'
2018-10-15T20:41:51.654+02:00 [main] o.s.b.f.xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [red5-core.xml]
2018-10-15T20:41:51.716+02:00 [main] o.r.s.net.rtmp.RTMPMinaTransport - RTMP will be bound to [0.0.0.0:1935]
2018-10-15T20:41:51.717+02:00 [main] o.r.s.net.rtmp.RTMPMinaTransport - RTMP Mina Transport Settings
Acceptor style: default I/O threads: 16
TCP no-delay: true keep-alive: false
2018-10-15T20:41:51.740+02:00 [main] o.r.s.net.rtmp.RTMPMinaTransport - Traffic class modification is disabled
2018-10-15T20:41:51.740+02:00 [main] o.r.s.net.rtmp.RTMPMinaTransport - Send buffer size: 65536 recv buffer size: 65536 so linger: -1 traffic class: 0
2018-10-15T20:41:51.748+02:00 [main] o.r.s.net.rtmp.RTMPMinaTransport - Exception occurred during resolve / bind
java.io.IOException: Error while binding on /0.0.0.0:1935
original message : Address already in use
    at org.apache.mina.transport.socket.nio.NioSocketAcceptor.open(NioSocketAcceptor.java:257)
    at org.apache.mina.transport.socket.nio.NioSocketAcceptor.open(NioSocketAcceptor.java:50)
    at org.apache.mina.core.polling.AbstractPollingIoAcceptor$Acceptor.registerHandles(AbstractPollingIoAcceptor.java:591)
    at org.apache.mina.core.polling.AbstractPollingIoAcceptor$Acceptor.run(AbstractPollingIoAcceptor.java:460)
    at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
    at java.lang.Thread.run(Thread.java:748)
2018-10-15T20:41:51.765+02:00 [main] org.red5.server.ContextLoader - ContextLoader init
2018-10-15T20:41:51.766+02:00 [main] org.red5.server.ContextLoader - Loading: default.context = ${red5.root}/webapps/red5-default.xml => /usr/share/red5/webapps/red5-default.xml
2018-10-15T20:41:51.767+02:00 [main] o.s.c.s.FileSystemXmlApplicationContext - Refreshing org.springframework.context.support.FileSystemXmlApplicationContext@3c01cfa1: startup date [Mon Oct 15 20:41:51 CEST 2018]; parent: ApplicationContext 'red5.common'
2018-10-15T20:41:51.767+02:00 [main] o.s.b.f.xml.XmlBeanDefinitionReader - Loading XML bean definitions from URL [file:/usr/share/red5/webapps/red5-default.xml]
2018-10-15T20:41:51.824+02:00 [main] org.red5.server.Context - Setting parent bean factory as core
2018-10-15T20:41:51.828+02:00 [main] o.red5.server.plugin.PluginLauncher - Loaded plugin: org.red5.net.websocket.WebSocketPlugin
2018-10-15T20:41:51.829+02:00 [main] org.red5.server.tomcat.WarDeployer - War deployer service created
2018-10-15T20:41:51.831+02:00 [main] org.red5.server.tomcat.WarDeployer - Starting WarDeployer
2018-10-15T20:41:51.833+02:00 [main] org.red5.server.tomcat.WarDeployer - Deploy wars without app start
2018-10-15T20:41:51.915+02:00 [main] org.red5.server.tomcat.TomcatLoader - Loading Tomcat
2018-10-15T20:41:51.915+02:00 [main] org.red5.server.tomcat.TomcatLoader - Server root: /usr/share/red5
2018-10-15T20:41:51.916+02:00 [main] org.red5.server.tomcat.TomcatLoader - Config root: /usr/share/red5/conf
2018-10-15T20:41:51.935+02:00 [main] org.red5.server.tomcat.TomcatLoader - Application root: /usr/share/red5/webapps
2018-10-15T20:41:52.096+02:00 [main] org.red5.server.tomcat.TomcatLoader - Starting Tomcat servlet engine
2018-10-15T20:41:52.186+02:00 [main] o.a.c.core.AprLifecycleListener - The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: [/usr/java/packages/lib/amd64:/usr/lib/x86_64-linux-gnu/jni:/lib/x86_64-linux-gnu:/usr/lib/x86_64-linux-gnu:/usr/lib/jni:/lib:/usr/lib]
2018-10-15T20:41:52.191+02:00 [main] o.a.coyote.http11.Http11NioProtocol - Initializing ProtocolHandler ["http-nio-0.0.0.0-5080"]
2018-10-15T20:41:52.203+02:00 [main] o.a.coyote.http11.Http11NioProtocol - Failed to initialize end point associated with ProtocolHandler ["http-nio-0.0.0.0-5080"]
java.net.BindException: Address already in use
    at sun.nio.ch.Net.bind0(Native Method)
    at sun.nio.ch.Net.bind(Net.java:433)
    at sun.nio.ch.Net.bind(Net.java:425)
    at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:223)
    at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:74)
    at org.apache.tomcat.util.net.NioEndpoint.bind(NioEndpoint.java:210)
    at org.apache.tomcat.util.net.AbstractEndpoint.init(AbstractEndpoint.java:982)
    at org.apache.tomcat.util.net.AbstractJsseEndpoint.init(AbstractJsseEndpoint.java:245)
    at org.apache.coyote.AbstractProtocol.init(AbstractProtocol.java:620)
    at org.apache.coyote.http11.AbstractHttp11Protocol.init(AbstractHttp11Protocol.java:66)
    at org.apache.catalina.connector.Connector.initInternal(Connector.java:997)
    at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:107)
    at org.apache.catalina.core.StandardService.initInternal(StandardService.java:549)
    at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:107)
    at org.apache.catalina.core.StandardServer.initInternal(StandardServer.java:875)
    at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:107)
    at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:140)
    at org.apache.catalina.startup.Tomcat.start(Tomcat.java:367)
    at org.red5.server.tomcat.TomcatLoader.start(TomcatLoader.java:440)
    at org.red5.server.tomcat.TomcatLoader.afterPropertiesSet(TomcatLoader.java:179)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1687)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1624)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:555)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:483)
    at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:306)
    at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230)
    at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:302)
    at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:220)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveNamedBean(DefaultListableBeanFactory.java:1018)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.getBean(DefaultListableBeanFactory.java:345)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.getBean(DefaultListableBeanFactory.java:340)
    at org.springframework.context.support.AbstractApplicationContext.getBean(AbstractApplicationContext.java:1090)
    at org.red5.server.service.ShutdownServer.afterPropertiesSet(ShutdownServer.java:116)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1687)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1624)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:555)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:483)
    at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:306)
    at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230)
    at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:302)
    at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:197)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:761)
    at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:867)
    at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:543)
    at org.red5.server.Launcher.launch(Launcher.java:73)
    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.red5.server.Bootstrap.bootStrap(Bootstrap.java:122)
    at org.red5.server.Bootstrap.main(Bootstrap.java:50)
2018-10-15T20:41:52.205+02:00 [main] o.a.catalina.core.StandardService - Failed to initialize connector [Connector[HTTP/1.1-5080]]
org.apache.catalina.LifecycleException: Failed to initialize component [Connector[HTTP/1.1-5080]]
    at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:113)
    at org.apache.catalina.core.StandardService.initInternal(StandardService.java:549)
    at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:107)
    at org.apache.catalina.core.StandardServer.initInternal(StandardServer.java:875)
    at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:107)
    at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:140)
    at org.apache.catalina.startup.Tomcat.start(Tomcat.java:367)
    at org.red5.server.tomcat.TomcatLoader.start(TomcatLoader.java:440)
    at org.red5.server.tomcat.TomcatLoader.afterPropertiesSet(TomcatLoader.java:179)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1687)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1624)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:555)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:483)
    at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:306)
    at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230)
    at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:302)
    at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:220)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveNamedBean(DefaultListableBeanFactory.java:1018)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.getBean(DefaultListableBeanFactory.java:345)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.getBean(DefaultListableBeanFactory.java:340)
    at org.springframework.context.support.AbstractApplicationContext.getBean(AbstractApplicationContext.java:1090)
    at org.red5.server.service.ShutdownServer.afterPropertiesSet(ShutdownServer.java:116)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1687)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1624)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:555)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:483)
    at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:306)
    at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230)
    at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:302)
    at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:197)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:761)
    at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:867)
    at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:543)
    at org.red5.server.Launcher.launch(Launcher.java:73)
    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.red5.server.Bootstrap.bootStrap(Bootstrap.java:122)
    at org.red5.server.Bootstrap.main(Bootstrap.java:50)
Caused by: org.apache.catalina.LifecycleException: Protocol handler initialization failed
    at org.apache.catalina.connector.Connector.initInternal(Connector.java:1000)
    at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:107)
    ... 39 common frames omitted
Caused by: java.net.BindException: Address already in use
    at sun.nio.ch.Net.bind0(Native Method)
    at sun.nio.ch.Net.bind(Net.java:433)
    at sun.nio.ch.Net.bind(Net.java:425)
    at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:223)
    at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:74)
    at org.apache.tomcat.util.net.NioEndpoint.bind(NioEndpoint.java:210)
    at org.apache.tomcat.util.net.AbstractEndpoint.init(AbstractEndpoint.java:982)
    at org.apache.tomcat.util.net.AbstractJsseEndpoint.init(AbstractJsseEndpoint.java:245)
    at org.apache.coyote.AbstractProtocol.init(AbstractProtocol.java:620)
    at org.apache.coyote.http11.AbstractHttp11Protocol.init(AbstractHttp11Protocol.java:66)
    at org.apache.catalina.connector.Connector.initInternal(Connector.java:997)
    ... 40 common frames omitted
2018-10-15T20:41:52.212+02:00 [main] o.a.catalina.core.StandardService - Starting service [Tomcat]
2018-10-15T20:41:52.212+02:00 [main] o.a.catalina.core.StandardEngine - Starting Servlet Engine: Apache Tomcat/8.5.23
2018-10-15T20:41:52.287+02:00 [0.0.0.0-startStop-1] o.a.catalina.startup.ContextConfig - No global web.xml found
2018-10-15T20:41:52.330+02:00 [0.0.0.0-startStop-1] o.a.c.c.C.[.[0.0.0.0].[/] - No Spring WebApplicationInitializer types detected on classpath
2018-10-15T20:41:54.099+02:00 [0.0.0.0-startStop-1] o.a.c.c.C.[.[.0.0.0.[/bigbluebutton] - No Spring WebApplicationInitializer types detected on classpath
Context init: bigbluebutton
Context logger config found: file:/var/lib/red5/webapps/bigbluebutton/WEB-INF/classes/logback-bigbluebutton.xml
Filter init: bigbluebutton
2018-10-15T20:41:55.086+02:00 [0.0.0.0-startStop-1] o.a.c.c.C.[.[.0.0.0].[/screenshare] - No Spring WebApplicationInitializer types detected on classpath
Context init: screenshare
Context logger config found: file:/var/lib/red5/webapps/screenshare/WEB-INF/classes/logback-screenshare.xml
Filter init: screenshare
2018-10-15T20:41:55.100+02:00 [0.0.0.0-startStop-1] o.a.c.c.C.[.[.0.0.0].[/screenshare] - Initializing Spring FrameworkServlet 'tunnel'
2018-10-15T20:41:55.101+02:00 [0.0.0.0-startStop-1] o.s.web.servlet.DispatcherServlet - FrameworkServlet 'tunnel': initialization started
2018-10-15T20:41:55.111+02:00 [0.0.0.0-startStop-1] o.s.w.c.s.XmlWebApplicationContext - Refreshing WebApplicationContext for namespace 'tunnel-servlet': startup date [Mon Oct 15 20:41:55 CEST 2018]; root of context hierarchy
2018-10-15T20:41:55.111+02:00 [0.0.0.0-startStop-1] o.s.b.f.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/tunnel-servlet.xml]
2018-10-15T20:41:55.175+02:00 [0.0.0.0-startStop-1] o.s.w.s.h.SimpleUrlHandlerMapping - Neither 'urlMap' nor 'mappings' set on SimpleUrlHandlerMapping
2018-10-15T20:41:55.210+02:00 [0.0.0.0-startStop-1] o.s.web.servlet.DispatcherServlet - FrameworkServlet 'tunnel': initialization completed in 109 ms
2018-10-15T20:41:56.194+02:00 [0.0.0.0-startStop-1] o.a.c.c.C.[.[.0.0.0.[/video-broadcast] - No Spring WebApplicationInitializer types detected on classpath
Context init: video-broadcast
2018-10-15T20:41:56.195+02:00 [0.0.0.0-startStop-1] ROOT - Starting up context: video-broadcast
Filter init: video-broadcast
2018-10-15T20:41:57.226+02:00 [0.0.0.0-startStop-1] o.a.c.c.C.[.[0.0.0.0].[/sip] - No Spring WebApplicationInitializer types detected on classpath
Context init: sip
Context logger config found: file:/var/lib/red5/webapps/sip/WEB-INF/classes/logback-sip.xml
Filter init: sip
2018-10-15T20:41:58.230+02:00 [0.0.0.0-startStop-1] o.a.c.c.C.[.[0.0.0.0].[/video] - No Spring WebApplicationInitializer types detected on classpath
Context init: video
Context logger config found: file:/var/lib/red5/webapps/video/WEB-INF/classes/logback-video.xml
Filter init: video
2018-10-15T20:41:58.242+02:00 [main] org.red5.server.tomcat.TomcatLoader - Context initialized:  path: /var/lib/red5/webapps/root/
2018-10-15T20:41:58.243+02:00 [main] org.red5.server.tomcat.TomcatLoader - Context initialized: /bigbluebutton path: /var/lib/red5/webapps/bigbluebutton/
2018-10-15T20:41:58.243+02:00 [main] org.red5.server.tomcat.TomcatLoader - Context initialized: /screenshare path: /var/lib/red5/webapps/screenshare/
2018-10-15T20:41:58.244+02:00 [main] org.red5.server.tomcat.TomcatLoader - Context initialized: /video-broadcast path: /var/lib/red5/webapps/video-broadcast/
2018-10-15T20:41:58.244+02:00 [main] org.red5.server.tomcat.TomcatLoader - Context initialized: /sip path: /var/lib/red5/webapps/sip/
2018-10-15T20:41:58.244+02:00 [main] org.red5.server.tomcat.TomcatLoader - Context initialized: /video path: /var/lib/red5/webapps/video/
2018-10-15T20:41:58.244+02:00 [main] org.red5.server.tomcat.TomcatLoader - Dedicated RTMPT server configuration was not specified
2018-10-15T20:41:58.244+02:00 [Loader:] o.s.w.c.s.XmlWebApplicationContext - Refreshing Root WebApplicationContext: startup date [Mon Oct 15 20:41:58 CEST 2018]; parent: org.springframework.context.support.FileSystemXmlApplicationContext@3c01cfa1
2018-10-15T20:41:58.245+02:00 [main] o.r.server.service.ShutdownServer - JEE server was found: TomcatLoader [serviceEngineName=red5Engine]
2018-10-15T20:41:58.246+02:00 [Loader:] o.s.b.f.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/red5-web.xml]
Bootstrap complete
Bootstrap exit
Token: 5ad7c55b-e853-4767-b315-b3f2363f4ad9
2018-10-15T20:41:58.251+02:00 [pool-6-thread-1] o.r.server.service.ShutdownServer - Cannot bind to port: 9999, ensure no other instances are bound or choose another port
java.net.BindException: Address already in use (Bind failed)
    at java.net.PlainSocketImpl.socketBind(Native Method)
    at java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:387)
    at java.net.ServerSocket.bind(ServerSocket.java:375)
    at java.net.ServerSocket.<init>(ServerSocket.java:237)
    at java.net.ServerSocket.<init>(ServerSocket.java:128)
    at org.red5.server.service.ShutdownServer.start(ShutdownServer.java:161)
    at org.red5.server.service.ShutdownServer$1.run(ShutdownServer.java:129)
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
    at java.util.concurrent.FutureTask.run(FutureTask.java:266)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
    at java.lang.Thread.run(Thread.java:748)
2018-10-15T20:41:58.251+02:00 [pool-6-thread-1] o.red5.server.plugin.PluginRegistry - Destroying and cleaning up 1 plugins
2018-10-15T20:41:58.251+02:00 [pool-6-thread-1] org.red5.server.ContextLoader - Shutting down
2018-10-15T20:41:58.251+02:00 [pool-6-thread-1] org.red5.server.ContextLoader - Unloading context default.context on shutdown
2018-10-15T20:41:58.252+02:00 [pool-6-thread-1] o.s.c.s.FileSystemXmlApplicationContext - Closing org.springframework.context.support.FileSystemXmlApplicationContext@3c01cfa1: startup date [Mon Oct 15 20:41:51 CEST 2018]; parent: ApplicationContext 'red5.common'
2018-10-15T20:41:58.252+02:00 [pool-6-thread-1] org.red5.server.ContextLoader - Shutdown complete
2018-10-15T20:41:58.259+02:00 [Thread-3] o.r.spring.Red5ApplicationContext - Closing ApplicationContext 'red5.core': startup date [Mon Oct 15 20:41:51 CEST 2018]; parent: ApplicationContext 'red5.common'
2018-10-15T20:41:58.260+02:00 [Thread-3] o.r.s.net.rtmp.RTMPMinaTransport - RTMP Mina Transport stop
2018-10-15T20:41:58.276+02:00 [Loader:] org.red5.server.Context - Setting parent bean factory as core
2018-10-15T20:41:58.276+02:00 [Thread-4] o.r.spring.Red5ApplicationContext - Closing ApplicationContext 'red5.common': startup date [Mon Oct 15 20:41:51 CEST 2018]; parent: org.springframework.context.support.FileSystemXmlApplicationContext@28d25987
2018-10-15T20:41:58.277+02:00 [Thread-4] o.s.j.e.a.AnnotationMBeanExporter - Unregistering JMX-exposed beans on shutdown
2018-10-15T20:41:58.277+02:00 [Thread-4] o.s.j.e.a.AnnotationMBeanExporter - Unregistering JMX-exposed beans
2018-10-15T20:41:58.277+02:00 [Thread-4] org.quartz.core.QuartzScheduler - Scheduler Red5_Scheduler_$_NON_CLUSTERED shutting down.
2018-10-15T20:41:58.277+02:00 [Thread-4] org.quartz.core.QuartzScheduler - Scheduler Red5_Scheduler_$_NON_CLUSTERED paused.
2018-10-15T20:41:58.277+02:00 [Thread-4] org.quartz.core.QuartzScheduler - Scheduler Red5_Scheduler_$_NON_CLUSTERED shutdown complete.
2018-10-15T20:41:58.279+02:00 [Loader:] o.s.w.c.s.XmlWebApplicationContext - Exception encountered during context initialization - cancelling refresh attempt: org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'web.context' defined in ServletContext resource [/WEB-INF/red5-web.xml]: Unsatisfied dependency expressed through bean property 'scopeResolver'; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'red5.scopeResolver' defined in URL [file:/usr/share/red5/webapps/red5-default.xml]: Cannot resolve reference to bean 'global.scope' while setting bean property 'globalScope'; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'global.scope' defined in URL [file:/usr/share/red5/webapps/red5-default.xml]: Invocation of init method failed; nested exception is java.lang.RuntimeException: org.quartz.SchedulerException: The Scheduler has been shutdown.
2018-10-15T20:41:58.282+02:00 [Loader:/bigbluebutton] o.s.w.c.s.XmlWebApplicationContext - Refreshing Root WebApplicationContext: startup date [Mon Oct 15 20:41:58 CEST 2018]; parent: org.springframework.context.support.FileSystemXmlApplicationContext@3c01cfa1
2018-10-15T20:41:58.289+02:00 [Loader:/screenshare] o.s.w.c.s.XmlWebApplicationContext - Refreshing Root WebApplicationContext: startup date [Mon Oct 15 20:41:58 CEST 2018]; parent: org.springframework.context.support.FileSystemXmlApplicationContext@3c01cfa1
2018-10-15T20:41:58.303+02:00 [Thread-5] o.s.c.s.FileSystemXmlApplicationContext - Closing org.springframework.context.support.FileSystemXmlApplicationContext@28d25987: startup date [Mon Oct 15 20:41:50 CEST 2018]; root of context hierarchy
2018-10-15T20:41:58.304+02:00 [Thread-5] o.s.c.s.DefaultLifecycleProcessor - Stopping beans in phase 0
2018-10-15T20:41:58.305+02:00 [Thread-5] o.red5.server.plugin.PluginRegistry - Destroying and cleaning up 0 plugins
2018-10-15T20:41:58.323+02:00 [Loader:/video-broadcast] o.s.w.c.s.XmlWebApplicationContext - Refreshing Root WebApplicationContext: startup date [Mon Oct 15 20:41:58 CEST 2018]; parent: org.springframework.context.support.FileSystemXmlApplicationContext@3c01cfa1
2018-10-15T20:41:58.326+02:00 [Loader:/sip] o.s.w.c.s.XmlWebApplicationContext - Refreshing Root WebApplicationContext: startup date [Mon Oct 15 20:41:58 CEST 2018]; parent: org.springframework.context.support.FileSystemXmlApplicationContext@3c01cfa1
2018-10-15T20:41:58.329+02:00 [Loader:/video] o.s.w.c.s.XmlWebApplicationContext - Refreshing Root WebApplicationContext: startup date [Mon Oct 15 20:41:58 CEST 2018]; parent: org.springframework.context.support.FileSystemXmlApplicationContext@3c01cfa1
2018-10-15T20:42:28.303+02:00 [pool-6-thread-1] o.r.server.service.ShutdownServer - One or more contexts didn't close in the allotted time


thx,

Chad Pilkey

unread,
Oct 15, 2018, 3:36:37 PM10/15/18
to BigBlueButton-Setup
Did you stop red5 before trying to run it manually? The errors all point to being unable to bind to ports because they're probably in use. http://docs.bigbluebutton.org/dev/setup.html#developing-bbb-apps
...
Reply all
Reply to author
Forward
0 new messages