Problem connecting to Websocket

Issues developing apps
1

Hello,

I´m totally new to openvidu. Currently I try to run the tutorial openvidu-js-node in Firefox. Login works. But when I join a video session it fails.

In the browsers dev tools console I get the following message:

  • Firefox can’t establish a connection to the server at wss://192.168.99.100:4443/openvidu. webSocketWithReconnection.js:44:13
  • Could not connect to wss://192.168.99.100:4443/openvidu (invoking onerror if defined)
    error
    bubbles: false
    cancelBubble: false
    cancelable: false
    composed: false
    currentTarget: null
    defaultPrevented: false
    eventPhase: 0
    explicitOriginalTarget: WebSocket { url: “wss://192.168.99.100:4443/openvidu”, readyState: 3, bufferedAmount: 0, … }
    isTrusted: true
    originalTarget: WebSocket { url: “wss://192.168.99.100:4443/openvidu”, readyState: 3, bufferedAmount: 0, … }
    returnValue: true
    srcElement: WebSocket { url: “wss://192.168.99.100:4443/openvidu”, readyState: 3, bufferedAmount: 0, … }
    target: WebSocket { url: “wss://192.168.99.100:4443/openvidu”, readyState: 3, bufferedAmount: 0, … }
    timeStamp: 26462
    type: “error”
    <get isTrusted()>: function isTrusted()
    : EventPrototype { composedPath: composedPath(), stopPropagation: stopPropagation(), stopImmediatePropagation: stopImmediatePropagation(), … }
    webSocketWithReconnection.js:54:15

In the console of my IDE I have these messages from docker (Onyl the relevant wss part):

2020-04-24 06:41:01,713 DEBG ‘kms’ stdout output:
0:16:03.228193213 8 0x7f8dec003350 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:460:processMessage: Message: {“id”:5,“method”:“ping”,“jsonrpc”:“2.0”}
0:16:03.228246013 8 0x7f8dec003350 INFO KurentoServerMethods ServerMethods.cpp:811:ping: WebSocket Ping/Pong
0:16:03.228338912 8 0x7f8dec003350 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:462:processMessage: Response: {“id”:5,“jsonrpc”:“2.0”,“result”:{“value”:“pong”}}

2020-04-24 06:44:58,784 DEBG ‘kms’ stdout output:
0:20:00.299597823 8 0x7f8dec001b30 DEBUG KurentoMediaSet MediaSet.cpp:123:doGarbageCollection: Running garbage collector

2020-04-24 06:45:01,715 DEBG ‘kms’ stdout output:
0:20:03.229745394 8 0x7f8dec003350 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:460:processMessage: Message: {“id”:6,“method”:“ping”,“jsonrpc”:“2.0”}
0:20:03.229891994 8 0x7f8dec003350 INFO KurentoServerMethods ServerMethods.cpp:811:ping: WebSocket Ping/Pong
0:20:03.230080594 8 0x7f8dec003350 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:462:processMessage: Response: {“id”:6,“jsonrpc”:“2.0”,“result”:{“value”:“pong”}}

2020-04-24 06:48:58,784 DEBG ‘kms’ stdout output:
0:24:00.300009334 8 0x7f8dec001b30 DEBUG KurentoMediaSet MediaSet.cpp:123:doGarbageCollection: Running garbage collector

2020-04-24 06:49:01,712 DEBG ‘kms’ stdout output:
0:24:03.228093273 8 0x7f8dec003350 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:460:processMessage: Message: {“id”:7,“method”:“ping”,“jsonrpc”:“2.0”}
0:24:03.228158173 8 0x7f8dec003350 INFO KurentoServerMethods ServerMethods.cpp:811:ping: WebSocket Ping/Pong

2020-04-24 06:49:01,712 DEBG ‘kms’ stdout output:
0:24:03.228196773 8 0x7f8dec003350 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:462:processMessage: Response: {“id”:7,“jsonrpc”:“2.0”,“result”:{“value”:“pong”}}

Can you please help me with this?

Thx in advance,

Martin

2

Are you on windows in a development environment? I guess so becasue I can see IP 192.168.99.100.

If that’s correct, make sure you accept the OpenVIdu Server certificate in https://192.168.99.100:4443/ before trying to connect.

3

Yes I´m on Windows. You mean I should execute “https://192.168.99.100:4443/” in the browser? I tried, but the request timed out.

4

Please can you stop openvidu development docker container and start it again and send us the logs from the very beginning? It can contain important information about the issue.

It seems a networking problem with docker or openvidu. Are you able to start any other docker container exposing a web page and check if it works? Just to be sure docker installation is working fine.

Regards

5

Thx for returning to me!
Container stopped.
Now started with: $ docker run -p 4443:4443 --rm -e OPENVIDU_SECRET=MY_SECRET -e OPENVIDU_PUBLICURL=https://192.168.99.100:4443/ openvidu/openvidu-server-kms:2.13.0

Here is the complete log after start procedure has finished:

/usr/lib/python2.7/dist-packages/supervisor/options.py:297: UserWarning: Supervisord is running as root and it is searching for its configuration file in default locations (including its current working directory); you probably want to specify a “-c” argument specifying an absolute path to a configuration file for improved security.
'Supervisord is running as root and it is searching ’
2020-04-27 08:28:02,774 CRIT Supervisor running as root (no user in config file)
2020-04-27 08:28:02,774 WARN Included extra file “/etc/supervisor/conf.d/supervisord.conf” during parsing
2020-04-27 08:28:02,788 INFO RPC interface ‘supervisor’ initialized
2020-04-27 08:28:02,788 CRIT Server ‘unix_http_server’ running without any HTTP authentication checking
2020-04-27 08:28:02,788 INFO supervisord started with pid 1
2020-04-27 08:28:03,792 INFO spawned: ‘kms’ with pid 8
2020-04-27 08:28:03,796 INFO spawned: ‘openvidu-server’ with pid 9
2020-04-27 08:28:04,797 INFO success: kms entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
2020-04-27 08:28:04,797 INFO success: openvidu-server entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
2020-04-27 08:28:04,870 DEBG ‘kms’ stdout output:
0:00:00.983807275 8 0x55a76126b4a0 DEBUG KurentoModuleManager ModuleManager.cpp:158:loadModules: Looking for modules, path: /usr/lib/x86_64-linux-gnu/kurento/modules

2020-04-27 08:28:04,871 DEBG ‘kms’ stdout output:
0:00:00.985236675 8 0x55a76126b4a0 DEBUG KurentoModuleManager ModuleManager.cpp:174:loadModules: Found file: /usr/lib/x86_64-linux-gnu/kurento/modules/libkmselementsmodule.so

2020-04-27 08:28:04,888 DEBG ‘kms’ stdout output:
0:00:01.002688278 8 0x55a76126b4a0 INFO KurentoModuleManager ModuleManager.cpp:90:loadModule: Load file: /usr/lib/x86_64-linux-gnu/kurento/modules/libkmselementsmodule.so, module name: /usr/lib/x86_64-linux-gnu/kurento/modules/libkmselementsmodule.so

2020-04-27 08:28:04,889 DEBG ‘kms’ stdout output:
0:00:01.003032278 8 0x55a76126b4a0 INFO KurentoModuleManager ModuleManager.cpp:137:loadModule: Loaded module: elements, version: 6.13.1~7.g61435ac, date: Apr 16 2020 14:20:05

2020-04-27 08:28:04,889 DEBG ‘kms’ stdout output:
0:00:01.003269378 8 0x55a76126b4a0 DEBUG KurentoModuleManager ModuleManager.cpp:174:loadModules: Found file: /usr/lib/x86_64-linux-gnu/kurento/modules/libkmsfiltersmodule.so

2020-04-27 08:28:04,893 DEBG ‘kms’ stdout output:
0:00:01.007130579 8 0x55a76126b4a0 INFO KurentoModuleManager ModuleManager.cpp:90:loadModule: Load file: /usr/lib/x86_64-linux-gnu/kurento/modules/libkmsfiltersmodule.so, module name: /usr/lib/x86_64-linux-gnu/kurento/modules/libkmsfiltersmodule.so

2020-04-27 08:28:04,893 DEBG ‘kms’ stdout output:
0:00:01.007410579 8 0x55a76126b4a0 INFO KurentoModuleManager ModuleManager.cpp:137:loadModule: Loaded module: filters, version: 6.13.1~1.gbee162d, date: Apr 16 2020 14:29:32

2020-04-27 08:28:04,893 DEBG ‘kms’ stdout output:
0:00:01.007677379 8 0x55a76126b4a0 DEBUG KurentoModuleManager ModuleManager.cpp:174:loadModules: Found file: /usr/lib/x86_64-linux-gnu/kurento/modules/libkmscoremodule.so

2020-04-27 08:28:04,898 DEBG ‘kms’ stdout output:
0:00:01.012085380 8 0x55a76126b4a0 INFO KurentoModuleManager ModuleManager.cpp:90:loadModule: Load file: /usr/lib/x86_64-linux-gnu/kurento/modules/libkmscoremodule.so, module name: /usr/lib/x86_64-linux-gnu/kurento/modules/libkmscoremodule.so
0:00:01.012134380 8 0x55a76126b4a0 INFO KurentoModuleManager ModuleManager.cpp:137:loadModule: Loaded module: core, version: 6.13.1~7.ga704765, date: Apr 16 2020 14:10:44

2020-04-27 08:28:04,898 DEBG ‘kms’ stdout output:
0:00:01.012172280 8 0x55a76126b4a0 INFO KurentoMediaServer main.cpp:240:main: Kurento Media Server version: 6.13.1~7.g93fee4a
0:00:01.012176680 8 0x55a76126b4a0 INFO KurentoLoadConfig loadConfig.cpp:249:loadConfig: Reading configuration from: /etc/kurento/kurento.conf.json

2020-04-27 08:28:04,901 DEBG ‘kms’ stdout output:
0:00:01.015104680 8 0x55a76126b4a0 INFO KurentoLoadConfig loadConfig.cpp:183:loadModulesConfigFromDir: Looking for config files in /etc/kurento/modules

2020-04-27 08:28:04,901 DEBG ‘kms’ stdout output:
0:00:01.015174880 8 0x55a76126b4a0 INFO KurentoLoadConfig loadConfig.cpp:183:loadModulesConfigFromDir: Looking for config files in /etc/kurento/modules/kurento

2020-04-27 08:28:04,902 DEBG ‘kms’ stdout output:
0:00:01.015804280 8 0x55a76126b4a0 INFO KurentoLoadConfig loadConfig.cpp:209:loadModulesConfigFromDir: Loaded module config: /etc/kurento/modules/kurento/MediaElement.conf.ini

2020-04-27 08:28:04,902 DEBG ‘kms’ stdout output:
0:00:01.016286880 8 0x55a76126b4a0 INFO KurentoLoadConfig loadConfig.cpp:209:loadModulesConfigFromDir: Loaded module config: /etc/kurento/modules/kurento/HttpEndpoint.conf.ini

2020-04-27 08:28:04,903 DEBG ‘kms’ stdout output:
0:00:01.016865781 8 0x55a76126b4a0 INFO KurentoLoadConfig loadConfig.cpp:209:loadModulesConfigFromDir: Loaded module config: /etc/kurento/modules/kurento/BaseRtpEndpoint.conf.ini

2020-04-27 08:28:04,903 DEBG ‘kms’ stdout output:
0:00:01.017485781 8 0x55a76126b4a0 INFO KurentoLoadConfig loadConfig.cpp:209:loadModulesConfigFromDir: Loaded module config: /etc/kurento/modules/kurento/PlayerEndpoint.conf.ini

2020-04-27 08:28:04,904 DEBG ‘kms’ stdout output:
0:00:01.017957181 8 0x55a76126b4a0 INFO KurentoLoadConfig loadConfig.cpp:209:loadModulesConfigFromDir: Loaded module config: /etc/kurento/modules/kurento/UriEndpoint.conf.ini

2020-04-27 08:28:04,904 DEBG ‘kms’ stdout output:
0:00:01.018060381 8 0x55a76126b4a0 INFO KurentoLoadConfig loadConfig.cpp:209:loadModulesConfigFromDir: Loaded module config: /etc/kurento/modules/kurento/WebRtcEndpoint.conf.ini

2020-04-27 08:28:04,905 DEBG ‘kms’ stdout output:
0:00:01.018760081 8 0x55a76126b4a0 INFO KurentoLoadConfig loadConfig.cpp:209:loadModulesConfigFromDir: Loaded module config: /etc/kurento/modules/kurento/SdpEndpoint.conf.json

2020-04-27 08:28:04,905 DEBG ‘kms’ stdout output:
0:00:01.018811581 8 0x55a76126b4a0 INFO KurentoLoadConfig loadConfig.cpp:265:loadConfig: Configuration loaded successfully
0:00:01.018920881 8 0x55a76126b4a0 INFO KurentoLoadConfig loadConfig.cpp:271:loadConfig: Loaded config in effect:
{
“mediaServer”: {
“resources”: {
“//exceptionLimit”: “0.8”,
“//killLimit”: “0.7”,
“garbageCollectorPeriod”: “240”,
“//”: “Whether to disable the RPC request cache, for memory constrained environments”,
“disableRequestCache”: “false”
},
“net”: {
“websocket”: {
“//ipv6”: “false”,
“port”: “8888”,
“//secure”: {
“//port”: “8433”,
“//certificate”: “defaultCertificate.pem”,
“//password”: “”
},
“//registrar”: {
“//address”: “ws://localhost:9090”,
“//localAddress”: “localhost”
},
“//”: “Default SOMAXCONN (128)”,
“//connqueue”: “128”,
“path”: “kurento”,
“threads”: “10”
}
}
},
“configPath”: “/etc/kurento”,
“modules”: {
“kurento”: {
“MediaElement”: {
“configPath”: “/etc/kurento/modules/kurento”
},
“HttpEndpoint”: {
“serverAddress”: “localhost”,
“port”: “9091”,
“configPath”: “/etc/kurento/modules/kurento”
},
“BaseRtpEndpoint”: {
“configPath”: “/etc/kurento/modules/kurento”
},
“PlayerEndpoint”: {
“configPath”: “/etc/kurento/modules/kurento”
},
“UriEndpoint”: {
“defaultPath”: “file:///var/lib/kurento/”,
“configPath”: “/etc/kurento/modules/kurento”
},
“WebRtcEndpoint”: {
“stunServerAddress”: “stun.l.google.com”,
“stunServerPort”: “19302”,
“configPath”: “/etc/kurento/modules/kurento”
},
“SdpEndpoint”: {
“numAudioMedias”: “1”,
“numVideoMedias”: “1”,
“audioCodecs”: [
{
“name”: “opus/48000/2”,
“//”: “Next is an example about how a codec can be configured.”,
“//”: “WARNING: Usage of properties is not yet supported”,
“//properties”: {
“//maxcodedaudiobandwidth”: “16000”,
“//maxaveragebitrate”: “20000”,
“//stereo”: “1”,
“//useinbandfec”: “1”,
“//usedtx”: “0”
}
},
{
“name”: “PCMU/8000”
},
{
“name”: “AMR/8000”
}
],
“videoCodecs”: [
{
“name”: “VP8/90000”
},
{
“name”: “H264/90000”
}
],
“configPath”: “/etc/kurento/modules/kurento”
}
}
}
}

0:00:01.018997181 8 0x55a76126b4a0 INFO KurentoServerMethods ServerMethods.cpp:90:ServerMethods: Using above 80.00% of system limits will throw NOT_ENOUGH_RESOURCES exception
0:00:01.019015281 8 0x55a76126b4a0 INFO KurentoServerMethods ServerMethods.cpp:109:ServerMethods: System limits: unlimited threads, 1048576 files

2020-04-27 08:28:04,906 DEBG ‘kms’ stdout output:
0:00:01.020078981 8 0x55a76126b4a0 INFO KurentoServerMethods ServerMethods.cpp:144:ServerMethods: RPC Request Cache is ENABLED

2020-04-27 08:28:04,906 DEBG ‘kms’ stdout output:
0:00:01.020733281 8 0x55a76126b900 DEBUG KurentoWorkerPool WorkerPool.cpp:40:workerThreadLoop: Working thread starting

2020-04-27 08:28:04,907 DEBG ‘kms’ stdout output:
0:00:01.020795381 8 0x7f42c4001890 DEBUG KurentoWorkerPool WorkerPool.cpp:40:workerThreadLoop: Working thread starting
0:00:01.020823381 8 0x7f42bc001830 DEBUG KurentoWorkerPool WorkerPool.cpp:40:workerThreadLoop: Working thread starting
0:00:01.020843681 8 0x7f42c0001790 DEBUG KurentoWorkerPool WorkerPool.cpp:40:workerThreadLoop: Working thread starting
0:00:01.020867281 8 0x7f42b4001930 DEBUG KurentoWorkerPool WorkerPool.cpp:40:workerThreadLoop: Working thread starting
0:00:01.020891681 8 0x7f42b80018d0 DEBUG KurentoWorkerPool WorkerPool.cpp:40:workerThreadLoop: Working thread starting

2020-04-27 08:28:04,907 DEBG ‘kms’ stdout output:
0:00:01.021417281 8 0x55a76126b4a0 INFO KurentoWebSocketTransport WebSocketTransport.cpp:152:WebSocketTransport: WebSocket server listening on address ‘::’, port 8888

2020-04-27 08:28:04,907 DEBG ‘kms’ stdout output:
0:00:01.021722981 8 0x55a76126b4a0 INFO KurentoWebSocketTransport WebSocketTransport.cpp:249:WebSocketTransport: Secure websocket server not enabled

2020-04-27 08:28:04,908 DEBG ‘kms’ stdout output:
0:00:01.021870081 8 0x7f42a4001210 DEBUG KurentoWorkerPool WorkerPool.cpp:40:workerThreadLoop: Working thread starting

2020-04-27 08:28:04,908 DEBG ‘kms’ stdout output:
0:00:01.022008081 8 0x7f42a4001950 DEBUG KurentoWorkerPool WorkerPool.cpp:40:workerThreadLoop: Working thread starting
0:00:01.022250382 8 0x7f42a80018f0 DEBUG KurentoWorkerPool WorkerPool.cpp:40:workerThreadLoop: Working thread starting
0:00:01.022256082 8 0x7f429c001890 DEBUG KurentoWorkerPool WorkerPool.cpp:40:workerThreadLoop: Working thread starting

2020-04-27 08:28:04,909 DEBG ‘kms’ stdout output:
0:00:01.021838581 8 0x7f42ac001870 DEBUG KurentoWorkerPool WorkerPool.cpp:40:workerThreadLoop: Working thread starting

2020-04-27 08:28:04,910 DEBG ‘kms’ stdout output:
0:00:01.024134482 8 0x55a76126b4a0 INFO KurentoMediaServer main.cpp:259:main: Kurento Media Server started

2020-04-27 08:28:09,139 DEBG ‘openvidu-server’ stdout output:

/ __ \ \ \ / () | |
| | | | __ ___ _ \ \ / / _ | | _
| | | | ’ \ / _ \ ’ \ / / | |/ ` | | | |
| |__| | |) | / | | \ / | | (| | || |
_/| ./ __|| ||/ ||_,|_,|
| |
|| version 2.13.0

2020-04-27 08:28:09,139 DEBG ‘openvidu-server’ stdout output:

2020-04-27 08:28:10,923 DEBG ‘openvidu-server’ stdout output:
[WARN] 2020-04-27 08:28:10,914 [main] org.springframework.boot.StartupInfoLogger - InetAddress.getLocalHost().getHostName() took 1666 milliseconds to respond. Please verify your network configuration.

2020-04-27 08:28:12,595 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:12,595 [main] io.openvidu.server.OpenViduServer - Starting OpenViduServer with PID 9 (/openvidu-server.jar started by root in /)

2020-04-27 08:28:12,598 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:12,597 [main] io.openvidu.server.OpenViduServer - The following profiles are active: docker

2020-04-27 08:28:12,737 DEBG ‘openvidu-server’ stdout output:
[ERROR] 2020-04-27 08:28:12,736 [main] io.openvidu.server.config.OpenviduConfig - .env file not found at /./.env

2020-04-27 08:28:12,859 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:12,859 [main] io.openvidu.server.OpenViduServer - Started OpenViduServer in 7.908 seconds (JVM running for 9.014)

2020-04-27 08:28:12,868 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:12,867 [main] io.openvidu.server.OpenViduServer -

Configuration properties

  • CERTIFICATE_TYPE=selfsigned
  • OPENVIDU_CDR=false
  • OPENVIDU_CDR_PATH=/opt/openvidu/cdr
  • OPENVIDU_DOMAIN_OR_PUBLIC_IP=
  • OPENVIDU_PUBLICURL=https://192.168.99.100:4443/
  • OPENVIDU_RECORDING=false
  • OPENVIDU_RECORDING_AUTOSTOP_TIMEOUT=120
  • OPENVIDU_RECORDING_COMPOSED_URL=
  • OPENVIDU_RECORDING_CUSTOM_LAYOUT=/opt/openvidu/custom-layout
  • OPENVIDU_RECORDING_NOTIFICATION=publisher_moderator
  • OPENVIDU_RECORDING_PATH=/opt/openvidu/recordings
  • OPENVIDU_RECORDING_PUBLIC_ACCESS=false
  • OPENVIDU_RECORDING_VERSION=2.9.0
  • OPENVIDU_SECRET=MY_SECRET
  • OPENVIDU_SESSIONS_GARBAGE_INTERVAL=900
  • OPENVIDU_SESSIONS_GARBAGE_THRESHOLD=3600
  • OPENVIDU_STREAMS_VIDEO_MAX_RECV_BANDWIDTH=1000
  • OPENVIDU_STREAMS_VIDEO_MAX_SEND_BANDWIDTH=1000
  • OPENVIDU_STREAMS_VIDEO_MIN_RECV_BANDWIDTH=300
  • OPENVIDU_STREAMS_VIDEO_MIN_SEND_BANDWIDTH=300
  • OPENVIDU_WEBHOOK=false
  • OPENVIDU_WEBHOOK_ENDPOINT=
  • OPENVIDU_WEBHOOK_EVENTS=[“sessionCreated”,“sessionDestroyed”,“participantJoined”,“participantLeft”,“webrtcConnectionCreated”,“webrtcConnectionDestroyed”,“recordingStatusChanged”,“filterEventDispatched”,“mediaNodeStatusChanged”]
  • OPENVIDU_WEBHOOK_HEADERS=[]

2020-04-27 08:28:12,868 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:12,868 [main] io.openvidu.server.OpenViduServer - Using /dev/urandom for secure random generation

2020-04-27 08:28:16,307 DEBG ‘openvidu-server’ stdout output:
[WARN] 2020-04-27 08:28:16,306 [main] org.springframework.boot.StartupInfoLogger - InetAddress.getLocalHost().getHostName() took 1677 milliseconds to respond. Please verify your network configuration.

2020-04-27 08:28:17,985 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:17,985 [main] io.openvidu.server.OpenViduServer - Starting OpenViduServer with PID 9 (/openvidu-server.jar started by root in /)

2020-04-27 08:28:17,985 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:17,985 [main] io.openvidu.server.OpenViduServer - The following profiles are active: docker

2020-04-27 08:28:19,078 DEBG ‘openvidu-server’ stdout output:
[ERROR] 2020-04-27 08:28:19,078 [main] io.openvidu.server.config.OpenviduConfig - .env file not found at /./.env

2020-04-27 08:28:19,389 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:19,389 [main] org.springframework.boot.web.embedded.tomcat.TomcatWebServer - Tomcat initialized with port(s): 4443 (https)

2020-04-27 08:28:19,403 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:19,403 [main] org.apache.coyote.http11.Http11NioProtocol - Initializing ProtocolHandler [“https-jsse-nio-0.0.0.0-4443”]

2020-04-27 08:28:19,404 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:19,404 [main] org.apache.catalina.core.StandardService - Starting service [Tomcat]

2020-04-27 08:28:19,404 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:19,404 [main] org.apache.catalina.core.StandardEngine - Starting Servlet engine: [Apache Tomcat/9.0.30]

2020-04-27 08:28:19,546 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:19,546 [main] org.apache.catalina.core.ContainerBase.[Tomcat].[localhost].[/] - Initializing Spring embedded WebApplicationContext

2020-04-27 08:28:19,547 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:19,546 [main] org.springframework.web.context.ContextLoader - Root WebApplicationContext: initialization completed in 1536 ms

2020-04-27 08:28:19,722 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:19,721 [main] io.openvidu.server.OpenViduServer - OpenVidu CDR service is disabled (may be enable with ‘OPENVIDU_CDR=true’)

2020-04-27 08:28:19,722 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:19,722 [main] io.openvidu.server.OpenViduServer - OpenVidu Webhook service is disabled (may be enabled with ‘OPENVIDU_WEBHOOK=true’)

2020-04-27 08:28:19,730 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:19,730 [main] io.openvidu.server.OpenViduServer - OpenVidu Server using one KMS: ws://localhost:8888/kurento

2020-04-27 08:28:19,753 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:19,751 [rbeatExec-e1-t0] org.kurento.jsonrpc.client.JsonRpcClientNettyWebSocket - [KurentoClient] Connecting native client

2020-04-27 08:28:19,754 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:19,754 [rbeatExec-e1-t0] org.kurento.jsonrpc.client.JsonRpcClientNettyWebSocket - [KurentoClient] Creating new NioEventLoopGroup

2020-04-27 08:28:21,608 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:21,608 [ntLoopGroup-2-1] org.kurento.jsonrpc.client.JsonRpcClientNettyWebSocket - [KurentoClient] Initiating new Netty channel. Will create new handler too!

2020-04-27 08:28:21,697 DEBG ‘kms’ stdout output:
0:00:17.810884951 8 0x7f4294001830 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:482:openHandler: Client connected from http://localhost:8888

2020-04-27 08:28:21,713 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:21,713 [EventExec-e2-t0] io.openvidu.server.kurento.kms.KmsManager - Kurento Client “connected” event for KMS ws://localhost:8888/kurento [org.kurento.client.KurentoClient@5e92bdf0]

2020-04-27 08:28:21,724 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:21,721 [main] io.openvidu.server.recording.service.RecordingManager - OpenVidu recording service is disabled

2020-04-27 08:28:21,736 DEBG ‘openvidu-server’ stdout output:
[WARN] 2020-04-27 08:28:21,736 [main] io.openvidu.server.coturn.CoturnCredentialsService - No COTURN server is installed in the host machine. Response: /bin/sh: 1: turnadmin: not found
[ERROR] 2020-04-27 08:28:21,736 [main] io.openvidu.server.coturn.CoturnCredentialsService - No COTURN server will be automatically configured for clients

2020-04-27 08:28:21,745 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:21,743 [main] io.openvidu.server.core.SessionManager - Garbage collector for non active sessions initialized. Running every 900 seconds and cleaning up non active Sessions more than 3600 seconds old

2020-04-27 08:28:21,750 DEBG ‘kms’ stdout output:
0:00:17.864424352 8 0x7f428c002390 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:460:processMessage: Message: {“id”:1,“method”:“ping”,“params”:{“interval”:240000},“jsonrpc”:“2.0”}

2020-04-27 08:28:21,751 DEBG ‘kms’ stdout output:
0:00:17.864555252 8 0x7f428c002390 INFO KurentoServerMethods ServerMethods.cpp:811:ping: WebSocket Ping/Pong
0:00:17.864614252 8 0x7f428c002390 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:462:processMessage: Response: {“id”:1,“jsonrpc”:“2.0”,“result”:{“value”:“pong”}}

2020-04-27 08:28:21,786 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:21,785 [main] org.springframework.scheduling.concurrent.ThreadPoolTaskScheduler - Initializing ExecutorService ‘jsonrpcTaskScheduler’

2020-04-27 08:28:21,939 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:21,939 [main] org.springframework.scheduling.concurrent.ThreadPoolTaskExecutor - Initializing ExecutorService ‘applicationTaskExecutor’

2020-04-27 08:28:22,056 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:22,056 [main] org.springframework.boot.autoconfigure.web.servlet.WelcomePageHandlerMapping - Adding welcome page: class path resource [static/index.html]

2020-04-27 08:28:22,254 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:22,253 [main] org.springframework.security.web.DefaultSecurityFilterChain - Creating filter chain: any request, [org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter@4d910fd6, org.springframework.security.web.context.SecurityContextPersistenceFilter@524d6d96, org.springframework.security.web.header.HeaderWriterFilter@7e9131d5, org.springframework.web.filter.CorsFilter@8dbdac1, org.springframework.security.web.authentication.logout.LogoutFilter@5136d012, org.springframework.security.web.authentication.www.BasicAuthenticationFilter@1f97cf0d, org.springframework.security.web.savedrequest.RequestCacheAwareFilter@44a7bfbc, org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@37858383, org.springframework.security.web.authentication.AnonymousAuthenticationFilter@26275bef, org.springframework.security.web.session.SessionManagementFilter@61d6015a, org.springframework.security.web.access.ExceptionTranslationFilter@f381794, org.springframework.security.web.access.intercept.FilterSecurityInterceptor@4cc8eb05]

2020-04-27 08:28:22,310 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:22,309 [main] org.apache.coyote.http11.Http11NioProtocol - Starting ProtocolHandler [“https-jsse-nio-0.0.0.0-4443”]

2020-04-27 08:28:22,575 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:22,575 [main] org.springframework.boot.web.embedded.tomcat.TomcatWebServer - Tomcat started on port(s): 4443 (https) with context path ‘’

2020-04-27 08:28:22,576 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:22,576 [main] io.openvidu.server.OpenViduServer - Started OpenViduServer in 9.691 seconds (JVM running for 18.731)

2020-04-27 08:28:22,578 DEBG ‘openvidu-server’ stdout output:
[INFO] 2020-04-27 08:28:22,577 [main] io.openvidu.server.OpenViduServer -

OpenVidu is ready!

  • OpenVidu Server: LINK

  • OpenVidu Dashboard: LINK/dashboard/

6

I´m just as new to Docker, as I am new to OpenVidu. But I´m sure it won´t hurt to learn Docker a little bit. Give me a little time to manage what you are asking for.

7

Perfect.

This is to be sure that your docker installation is working good and the problem is with OpenVidu software.

How do know that 192.168.99.100 is the IP of the docker virtual machine?

Regards.

8

At the end of the log, where it says “OpenVidu is ready / OpenVidu Server: LINK”, I had to replace the IP address https://192.168.99.100, because I am a new user and therefore can only post two links. So I replaced https://192.168.99.100 with the word LINK. So this IP is, what I think, the one of the VM.

9

How have you installed OpenVidu on your machine? With Docker Toolbox (based on Virtual Box) or with Docker for Windows?

This detail is important to know how you can access to OpenVidu from your apps.

10

It is Docker for Windows.

11

Ok, in docker for windows the URL of OpenVidu is:

https://localhost:4443/

You can access to the dashboard in:

https://localhost:4443/dashboard/

Regards

12

Thnx a lot, Micael! The localhost hint was the key.

So to round things up:
I was able to launch the openvidu server on a Win10pro by running this command:
$ docker run -p 4443:4443 --rm -e OPENVIDU_SECRET=MY_SECRET openvidu/openvidu-server-kms:2.13.0
There was no need to read the FAQ and follow the instruction for Windows users. (The FAQ was pointing to the private IP 192.168.99.100)
To get the “openvidu-js-node” Tutorial working, I had to change the port from 5000 to 9000 in server.js. No idea why the 5000 port did not do the job.

Hope this info is useful for other Windows users.

1 Like