[jitsi-dev] Unable to load jnopenssl


#1

I am seeing this WARNING in the log, when running JVB on Windows. Is this a bug or is there anything I miss? I am running from source. Shouldn't all required libraries be packaged?

Everything seems fine, then suddenly, just before video should start, I see "Unknown DTLS handshake" and short after "no jnopenssl in java.library.path", then ICE terminates.

Oct 13, 2016 8:28:59 PM org.ice4j.ice.ConnectivityCheckClient processSuccessResponse
INFO: IsControlling: true USE-CANDIDATE:true
Oct 13, 2016 8:28:59 PM org.ice4j.ice.ConnectivityCheckClient processSuccessResponse
INFO: Nomination confirmed for pair: [2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:10000/udp/host -> [2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:63289/udp/host (stream.RTP)
Oct 13, 2016 8:28:59 PM org.ice4j.ice.CheckList handleNominationConfirmed
INFO: Selected pair for stream stream.RTP: [2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:10000/udp/host -> [2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:63289/udp/host (stream.RTP)
Oct 13, 2016 8:28:59 PM org.ice4j.ice.Agent checkListStatesUpdated
INFO: CheckList of stream stream is COMPLETED
Oct 13, 2016 8:28:59 PM org.ice4j.ice.Agent setState
INFO: ICE state changed from Running to Completed
Oct 13, 2016 8:28:59 PM org.jitsi.util.Logger info
INFO: ICE processing state of IceUdpTransportManager #1b17be59 (for channels 63e8cb78d1af9b27 23eb458898b9f8ec) of conference 3b87bb78418f90f changed from Running to Completed.
Oct 13, 2016 8:28:59 PM org.ice4j.ice.Agent logCandTypes
INFO: Harvester used for selected pair for stream.RTP: host
Oct 13, 2016 8:28:59 PM org.jitsi.util.Logger info
INFO: Transport connected for channel 63e8cb78d1af9b27 of content audio of conference 3b87bb78418f90f
Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger info
INFO: Transport connected for channel 171963c0dc0f231d of content video of conference 3b87bb78418f90f
Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger info
INFO: Transport connected for channel 23eb458898b9f8ec of content video of conference 3b87bb78418f90f
Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger warn
WARNING: Unknown DTLS handshake message type: -38 <----------------------------------------------------------------------------------------------
Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger warn
WARNING: Unknown DTLS handshake message type: -66 <----------------------------------------------------------------------------------------------
Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger warn
WARNING: Unable to load jnopenssl: java.lang.UnsatisfiedLinkError: no jnopenssl in java.library.path <----------------------------------------------------------------------------------------------
Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info
INFO: AES benchmark (of execution times expressed in nanoseconds): BouncyCastle 21571101, SunJCE 8423859
Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info
INFO: Will employ AES implemented by SunJCE.
Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info
INFO: There are no receive streams to build report blocks for.
Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info
INFO: There are no receive streams to build report blocks for.
Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info
INFO: Resetting queue, last seq added: 9223372036854775806, current seq: 1072
Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info
INFO: Resetting queue, last seq added: 9223372036854775806, current seq: 14042
Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info
INFO: Starting RTPSourceStream.
Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info
INFO: Starting RTPSourceStream.
Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info
INFO: Resetting queue, last seq added: 9223372036854775806, current seq: 19793
Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info
INFO: There are no receive streams to build report blocks for.
Oct 13, 2016 8:29:02 PM org.ice4j.ice.Agent setState
INFO: ICE state changed from Completed to Terminated

When I switch to FINEST log, I see this:

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger trace
FINER: Fingerprint E8:3A:85:03:B7:BD:F8:2E:B7:92:39:B8:8E:E8:CC:5D:CB:D4:18:D3:47:9D:27:18:B9:72:FC:00:6B:6C:38:CD matches the sha-256-hashed certificate.
Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger trace
FINER: Fingerprint E8:94:D3:60:01:2E:11:7E:0D:25:1D:87:4A:C8:B3:71:4C:40:42:B0:F5:DF:B7:2E:E7:1F:CC:74:BA:E8:F8:63 matches the sha-256-hashed certificate.
Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run
FINEST: will skip a check beat.
Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run
FINEST: will skip a check beat.
Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run
FINEST: will skip a check beat.
Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run
FINEST: will skip a check beat.
Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run
FINEST: will skip a check beat.
Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run
FINEST: will skip a check beat.
Oct 13, 2016 8:44:46 PM org.ice4j.stack.Connector run
FINEST: received datagram packet - addr: /2001:0:5ef5:79fb:24e1:2427:bb9f:8a46 port: 52856
Oct 13, 2016 8:44:46 PM org.ice4j.stack.MessageProcessor run
FINEST: Dispatching a StunMessageEvent.
Oct 13, 2016 8:44:46 PM org.ice4j.stack.StunStack handleMessageEvent
FINEST: Received a message on [2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:10000/udp of type:1
Oct 13, 2016 8:44:46 PM org.ice4j.stack.StunStack handleMessageEvent
FINEST: parsing request
Oct 13, 2016 8:44:46 PM org.ice4j.stack.StunStack handleMessageEvent
FINEST: existing transaction not found
Oct 13, 2016 8:44:46 PM org.ice4j.stack.StunStack validateMessageIntegrity
FINEST: Successfully verified msg integrity
Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckServer processRequest
FINER: Received request StunMessageEvent:
                Message=BINDING-REQUEST(0x1)[attrib.count=6 len=84 tranID=0x7A4D3532656D547432796F31] remoteAddr=[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:52856/udp localAddr=[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:10000/udp
Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run
FINEST: will skip a check beat.
Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run
FINEST: will skip a check beat.
Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger warn
WARNING: Unknown DTLS handshake message type: -20
Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug
FINE: Caught a non-RTCP/RTP packet.
Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug
FINE: Caught a non-RTCP/RTP packet.
Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug
FINE: Caught a non-RTCP/RTP packet.
Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug
FINE: Caught a non-RTCP/RTP packet.
Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug
FINE: Caught a non-RTCP/RTP packet.
Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug
FINE: Caught a non-RTCP/RTP packet.
Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug
FINE: Caught a non-RTCP/RTP packet.
Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug
FINE: Caught a non-RTCP/RTP packet.
Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger warn
WARNING: Unable to load jnopenssl: java.lang.UnsatisfiedLinkError: no jnopenssl in java.library.path


#2

Hi,

I don't think the jnopenssl is the problem here. This is an
optimization for encryption/decryption available for linux, and it
should use default implementation when this is absent. Maybe DTLS, not
sure about that.

Regards
damencho

···

On Fri, Oct 14, 2016 at 9:13 AM, Oliver Hausler <oliver@closeup.cc> wrote:

I am seeing this WARNING in the log, when running JVB on Windows. Is this a
bug or is there anything I miss? I am running from source. Shouldn't all
required libraries be packaged?

Everything seems fine, then suddenly, just before video should start, I see
"Unknown DTLS handshake" and short after "no jnopenssl in
java.library.path", then ICE terminates.

Oct 13, 2016 8:28:59 PM org.ice4j.ice.ConnectivityCheckClient
processSuccessResponse

INFO: IsControlling: true USE-CANDIDATE:true

Oct 13, 2016 8:28:59 PM org.ice4j.ice.ConnectivityCheckClient
processSuccessResponse

INFO: Nomination confirmed for pair:
[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:10000/udp/host ->
[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:63289/udp/host (stream.RTP)

Oct 13, 2016 8:28:59 PM org.ice4j.ice.CheckList handleNominationConfirmed

INFO: Selected pair for stream stream.RTP:
[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:10000/udp/host ->
[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:63289/udp/host (stream.RTP)

Oct 13, 2016 8:28:59 PM org.ice4j.ice.Agent checkListStatesUpdated

INFO: CheckList of stream stream is COMPLETED

Oct 13, 2016 8:28:59 PM org.ice4j.ice.Agent setState

INFO: ICE state changed from Running to Completed

Oct 13, 2016 8:28:59 PM org.jitsi.util.Logger info

INFO: ICE processing state of IceUdpTransportManager #1b17be59 (for channels
63e8cb78d1af9b27 23eb458898b9f8ec) of conference 3b87bb78418f90f changed
from Running to Completed.

Oct 13, 2016 8:28:59 PM org.ice4j.ice.Agent logCandTypes

INFO: Harvester used for selected pair for stream.RTP: host

Oct 13, 2016 8:28:59 PM org.jitsi.util.Logger info

INFO: Transport connected for channel 63e8cb78d1af9b27 of content audio of
conference 3b87bb78418f90f

Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger info

INFO: Transport connected for channel 171963c0dc0f231d of content video of
conference 3b87bb78418f90f

Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger info

INFO: Transport connected for channel 23eb458898b9f8ec of content video of
conference 3b87bb78418f90f

Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger warn

WARNING: Unknown DTLS handshake message type: -38
<----------------------------------------------------------------------------------------------

Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger warn

WARNING: Unknown DTLS handshake message type: -66
<----------------------------------------------------------------------------------------------

Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger warn

WARNING: Unable to load jnopenssl: java.lang.UnsatisfiedLinkError: no
jnopenssl in java.library.path
<----------------------------------------------------------------------------------------------

Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info

INFO: AES benchmark (of execution times expressed in nanoseconds):
BouncyCastle 21571101, SunJCE 8423859

Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info

INFO: Will employ AES implemented by SunJCE.

Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info

INFO: There are no receive streams to build report blocks for.

Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info

INFO: There are no receive streams to build report blocks for.

Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info

INFO: Resetting queue, last seq added: 9223372036854775806, current seq:
1072

Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info

INFO: Resetting queue, last seq added: 9223372036854775806, current seq:
14042

Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info

INFO: Starting RTPSourceStream.

Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info

INFO: Starting RTPSourceStream.

Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info

INFO: Resetting queue, last seq added: 9223372036854775806, current seq:
19793

Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info

INFO: There are no receive streams to build report blocks for.

Oct 13, 2016 8:29:02 PM org.ice4j.ice.Agent setState

INFO: ICE state changed from Completed to Terminated

When I switch to FINEST log, I see this:

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger trace

FINER: Fingerprint
E8:3A:85:03:B7:BD:F8:2E:B7:92:39:B8:8E:E8:CC:5D:CB:D4:18:D3:47:9D:27:18:B9:72:FC:00:6B:6C:38:CD
matches the sha-256-hashed certificate.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger trace

FINER: Fingerprint
E8:94:D3:60:01:2E:11:7E:0D:25:1D:87:4A:C8:B3:71:4C:40:42:B0:F5:DF:B7:2E:E7:1F:CC:74:BA:E8:F8:63
matches the sha-256-hashed certificate.

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.ice4j.stack.Connector run

FINEST: received datagram packet - addr:
/2001:0:5ef5:79fb:24e1:2427:bb9f:8a46 port: 52856

Oct 13, 2016 8:44:46 PM org.ice4j.stack.MessageProcessor run

FINEST: Dispatching a StunMessageEvent.

Oct 13, 2016 8:44:46 PM org.ice4j.stack.StunStack handleMessageEvent

FINEST: Received a message on
[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:10000/udp of type:1

Oct 13, 2016 8:44:46 PM org.ice4j.stack.StunStack handleMessageEvent

FINEST: parsing request

Oct 13, 2016 8:44:46 PM org.ice4j.stack.StunStack handleMessageEvent

FINEST: existing transaction not found

Oct 13, 2016 8:44:46 PM org.ice4j.stack.StunStack validateMessageIntegrity

FINEST: Successfully verified msg integrity

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckServer processRequest

FINER: Received request StunMessageEvent:

                Message=BINDING-REQUEST(0x1)[attrib.count=6 len=84
tranID=0x7A4D3532656D547432796F31]
remoteAddr=[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:52856/udp
localAddr=[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:10000/udp

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger warn

WARNING: Unknown DTLS handshake message type: -20

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger warn

WARNING: Unable to load jnopenssl: java.lang.UnsatisfiedLinkError: no
jnopenssl in java.library.path

_______________________________________________
dev mailing list
dev@jitsi.org
Unsubscribe instructions and other list options:
http://lists.jitsi.org/mailman/listinfo/dev


#3

Note that the "TERMINATED" state in ICE is normal (successful).

Boris

···

On 14/10/16 17:38, Damian Minkov wrote:

Hi,

I don't think the jnopenssl is the problem here. This is an
optimization for encryption/decryption available for linux, and it
should use default implementation when this is absent. Maybe DTLS, not
sure about that.

Regards
damencho

On Fri, Oct 14, 2016 at 9:13 AM, Oliver Hausler <oliver@closeup.cc> wrote:

I am seeing this WARNING in the log, when running JVB on Windows. Is this a
bug or is there anything I miss? I am running from source. Shouldn't all
required libraries be packaged?

Everything seems fine, then suddenly, just before video should start, I see
"Unknown DTLS handshake" and short after "no jnopenssl in
java.library.path", then ICE terminates.

Oct 13, 2016 8:28:59 PM org.ice4j.ice.ConnectivityCheckClient
processSuccessResponse

INFO: IsControlling: true USE-CANDIDATE:true

Oct 13, 2016 8:28:59 PM org.ice4j.ice.ConnectivityCheckClient
processSuccessResponse

INFO: Nomination confirmed for pair:
[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:10000/udp/host ->
[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:63289/udp/host (stream.RTP)

Oct 13, 2016 8:28:59 PM org.ice4j.ice.CheckList handleNominationConfirmed

INFO: Selected pair for stream stream.RTP:
[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:10000/udp/host ->
[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:63289/udp/host (stream.RTP)

Oct 13, 2016 8:28:59 PM org.ice4j.ice.Agent checkListStatesUpdated

INFO: CheckList of stream stream is COMPLETED

Oct 13, 2016 8:28:59 PM org.ice4j.ice.Agent setState

INFO: ICE state changed from Running to Completed

Oct 13, 2016 8:28:59 PM org.jitsi.util.Logger info

INFO: ICE processing state of IceUdpTransportManager #1b17be59 (for channels
63e8cb78d1af9b27 23eb458898b9f8ec) of conference 3b87bb78418f90f changed
from Running to Completed.

Oct 13, 2016 8:28:59 PM org.ice4j.ice.Agent logCandTypes

INFO: Harvester used for selected pair for stream.RTP: host

Oct 13, 2016 8:28:59 PM org.jitsi.util.Logger info

INFO: Transport connected for channel 63e8cb78d1af9b27 of content audio of
conference 3b87bb78418f90f

Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger info

INFO: Transport connected for channel 171963c0dc0f231d of content video of
conference 3b87bb78418f90f

Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger info

INFO: Transport connected for channel 23eb458898b9f8ec of content video of
conference 3b87bb78418f90f

Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger warn

WARNING: Unknown DTLS handshake message type: -38
<----------------------------------------------------------------------------------------------

Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger warn

WARNING: Unknown DTLS handshake message type: -66
<----------------------------------------------------------------------------------------------

Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger warn

WARNING: Unable to load jnopenssl: java.lang.UnsatisfiedLinkError: no
jnopenssl in java.library.path
<----------------------------------------------------------------------------------------------

Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info

INFO: AES benchmark (of execution times expressed in nanoseconds):
BouncyCastle 21571101, SunJCE 8423859

Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info

INFO: Will employ AES implemented by SunJCE.

Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info

INFO: There are no receive streams to build report blocks for.

Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info

INFO: There are no receive streams to build report blocks for.

Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info

INFO: Resetting queue, last seq added: 9223372036854775806, current seq:
1072

Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info

INFO: Resetting queue, last seq added: 9223372036854775806, current seq:
14042

Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info

INFO: Starting RTPSourceStream.

Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info

INFO: Starting RTPSourceStream.

Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info

INFO: Resetting queue, last seq added: 9223372036854775806, current seq:
19793

Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info

INFO: There are no receive streams to build report blocks for.

Oct 13, 2016 8:29:02 PM org.ice4j.ice.Agent setState

INFO: ICE state changed from Completed to Terminated

When I switch to FINEST log, I see this:

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger trace

FINER: Fingerprint
E8:3A:85:03:B7:BD:F8:2E:B7:92:39:B8:8E:E8:CC:5D:CB:D4:18:D3:47:9D:27:18:B9:72:FC:00:6B:6C:38:CD
matches the sha-256-hashed certificate.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger trace

FINER: Fingerprint
E8:94:D3:60:01:2E:11:7E:0D:25:1D:87:4A:C8:B3:71:4C:40:42:B0:F5:DF:B7:2E:E7:1F:CC:74:BA:E8:F8:63
matches the sha-256-hashed certificate.

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.ice4j.stack.Connector run

FINEST: received datagram packet - addr:
/2001:0:5ef5:79fb:24e1:2427:bb9f:8a46 port: 52856

Oct 13, 2016 8:44:46 PM org.ice4j.stack.MessageProcessor run

FINEST: Dispatching a StunMessageEvent.

Oct 13, 2016 8:44:46 PM org.ice4j.stack.StunStack handleMessageEvent

FINEST: Received a message on
[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:10000/udp of type:1

Oct 13, 2016 8:44:46 PM org.ice4j.stack.StunStack handleMessageEvent

FINEST: parsing request

Oct 13, 2016 8:44:46 PM org.ice4j.stack.StunStack handleMessageEvent

FINEST: existing transaction not found

Oct 13, 2016 8:44:46 PM org.ice4j.stack.StunStack validateMessageIntegrity

FINEST: Successfully verified msg integrity

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckServer processRequest

FINER: Received request StunMessageEvent:

                Message=BINDING-REQUEST(0x1)[attrib.count=6 len=84
tranID=0x7A4D3532656D547432796F31]
remoteAddr=[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:52856/udp
localAddr=[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:10000/udp

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger warn

WARNING: Unknown DTLS handshake message type: -20

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger warn

WARNING: Unable to load jnopenssl: java.lang.UnsatisfiedLinkError: no
jnopenssl in java.library.path

_______________________________________________
dev mailing list
dev@jitsi.org
Unsubscribe instructions and other list options:
http://lists.jitsi.org/mailman/listinfo/dev

_______________________________________________
dev mailing list
dev@jitsi.org
Unsubscribe instructions and other list options:
http://lists.jitsi.org/mailman/listinfo/dev


#4

Any ideas where to search?

···

-----Original Message-----
From: dev [mailto:dev-bounces@jitsi.org] On Behalf Of Boris Grozev
Sent: Friday, October 14, 2016 07:51
To: Jitsi Developers <dev@jitsi.org>
Subject: Re: [jitsi-dev] Unable to load jnopenssl

Note that the "TERMINATED" state in ICE is normal (successful).

Boris

On 14/10/16 17:38, Damian Minkov wrote:

Hi,

I don't think the jnopenssl is the problem here. This is an
optimization for encryption/decryption available for linux, and it
should use default implementation when this is absent. Maybe DTLS, not
sure about that.

Regards
damencho

On Fri, Oct 14, 2016 at 9:13 AM, Oliver Hausler <oliver@closeup.cc> wrote:

I am seeing this WARNING in the log, when running JVB on Windows. Is
this a bug or is there anything I miss? I am running from source.
Shouldn't all required libraries be packaged?

Everything seems fine, then suddenly, just before video should start,
I see "Unknown DTLS handshake" and short after "no jnopenssl in
java.library.path", then ICE terminates.

Oct 13, 2016 8:28:59 PM org.ice4j.ice.ConnectivityCheckClient
processSuccessResponse

INFO: IsControlling: true USE-CANDIDATE:true

Oct 13, 2016 8:28:59 PM org.ice4j.ice.ConnectivityCheckClient
processSuccessResponse

INFO: Nomination confirmed for pair:
[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:10000/udp/host ->
[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:63289/udp/host (stream.RTP)

Oct 13, 2016 8:28:59 PM org.ice4j.ice.CheckList
handleNominationConfirmed

INFO: Selected pair for stream stream.RTP:
[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:10000/udp/host ->
[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:63289/udp/host (stream.RTP)

Oct 13, 2016 8:28:59 PM org.ice4j.ice.Agent checkListStatesUpdated

INFO: CheckList of stream stream is COMPLETED

Oct 13, 2016 8:28:59 PM org.ice4j.ice.Agent setState

INFO: ICE state changed from Running to Completed

Oct 13, 2016 8:28:59 PM org.jitsi.util.Logger info

INFO: ICE processing state of IceUdpTransportManager #1b17be59 (for
channels
63e8cb78d1af9b27 23eb458898b9f8ec) of conference 3b87bb78418f90f
changed from Running to Completed.

Oct 13, 2016 8:28:59 PM org.ice4j.ice.Agent logCandTypes

INFO: Harvester used for selected pair for stream.RTP: host

Oct 13, 2016 8:28:59 PM org.jitsi.util.Logger info

INFO: Transport connected for channel 63e8cb78d1af9b27 of content
audio of conference 3b87bb78418f90f

Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger info

INFO: Transport connected for channel 171963c0dc0f231d of content
video of conference 3b87bb78418f90f

Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger info

INFO: Transport connected for channel 23eb458898b9f8ec of content
video of conference 3b87bb78418f90f

Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger warn

WARNING: Unknown DTLS handshake message type: -38
<--------------------------------------------------------------------
--------------------------

Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger warn

WARNING: Unknown DTLS handshake message type: -66
<--------------------------------------------------------------------
--------------------------

Oct 13, 2016 8:29:00 PM org.jitsi.util.Logger warn

WARNING: Unable to load jnopenssl: java.lang.UnsatisfiedLinkError: no
jnopenssl in java.library.path
<--------------------------------------------------------------------
--------------------------

Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info

INFO: AES benchmark (of execution times expressed in nanoseconds):
BouncyCastle 21571101, SunJCE 8423859

Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info

INFO: Will employ AES implemented by SunJCE.

Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info

INFO: There are no receive streams to build report blocks for.

Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info

INFO: There are no receive streams to build report blocks for.

Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info

INFO: Resetting queue, last seq added: 9223372036854775806, current seq:
1072

Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info

INFO: Resetting queue, last seq added: 9223372036854775806, current seq:
14042

Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info

INFO: Starting RTPSourceStream.

Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info

INFO: Starting RTPSourceStream.

Oct 13, 2016 8:29:02 PM net.sf.fmj.media.Log info

INFO: Resetting queue, last seq added: 9223372036854775806, current seq:
19793

Oct 13, 2016 8:29:02 PM org.jitsi.util.Logger info

INFO: There are no receive streams to build report blocks for.

Oct 13, 2016 8:29:02 PM org.ice4j.ice.Agent setState

INFO: ICE state changed from Completed to Terminated

When I switch to FINEST log, I see this:

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger trace

FINER: Fingerprint
E8:3A:85:03:B7:BD:F8:2E:B7:92:39:B8:8E:E8:CC:5D:CB:D4:18:D3:47:9D:27:
18:B9:72:FC:00:6B:6C:38:CD matches the sha-256-hashed certificate.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger trace

FINER: Fingerprint
E8:94:D3:60:01:2E:11:7E:0D:25:1D:87:4A:C8:B3:71:4C:40:42:B0:F5:DF:B7:
2E:E7:1F:CC:74:BA:E8:F8:63 matches the sha-256-hashed certificate.

Oct 13, 2016 8:44:46 PM
org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM
org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM
org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM
org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM
org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM
org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.ice4j.stack.Connector run

FINEST: received datagram packet - addr:
/2001:0:5ef5:79fb:24e1:2427:bb9f:8a46 port: 52856

Oct 13, 2016 8:44:46 PM org.ice4j.stack.MessageProcessor run

FINEST: Dispatching a StunMessageEvent.

Oct 13, 2016 8:44:46 PM org.ice4j.stack.StunStack handleMessageEvent

FINEST: Received a message on
[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:10000/udp of type:1

Oct 13, 2016 8:44:46 PM org.ice4j.stack.StunStack handleMessageEvent

FINEST: parsing request

Oct 13, 2016 8:44:46 PM org.ice4j.stack.StunStack handleMessageEvent

FINEST: existing transaction not found

Oct 13, 2016 8:44:46 PM org.ice4j.stack.StunStack
validateMessageIntegrity

FINEST: Successfully verified msg integrity

Oct 13, 2016 8:44:46 PM org.ice4j.ice.ConnectivityCheckServer
processRequest

FINER: Received request StunMessageEvent:

                Message=BINDING-REQUEST(0x1)[attrib.count=6 len=84
tranID=0x7A4D3532656D547432796F31]
remoteAddr=[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:52856/udp
localAddr=[2001:0:5ef5:79fb:24e1:2427:bb9f:8a46]:10000/udp

Oct 13, 2016 8:44:46 PM
org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM
org.ice4j.ice.ConnectivityCheckClient$PaceMaker run

FINEST: will skip a check beat.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger warn

WARNING: Unknown DTLS handshake message type: -20

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger debug

FINE: Caught a non-RTCP/RTP packet.

Oct 13, 2016 8:44:46 PM org.jitsi.util.Logger warn

WARNING: Unable to load jnopenssl: java.lang.UnsatisfiedLinkError: no
jnopenssl in java.library.path

_______________________________________________
dev mailing list
dev@jitsi.org
Unsubscribe instructions and other list options:
http://lists.jitsi.org/mailman/listinfo/dev

_______________________________________________
dev mailing list
dev@jitsi.org
Unsubscribe instructions and other list options:
http://lists.jitsi.org/mailman/listinfo/dev

_______________________________________________
dev mailing list
dev@jitsi.org
Unsubscribe instructions and other list options:
http://lists.jitsi.org/mailman/listinfo/dev


#5

I have no more ideas where to look. Does my colibri look good? Where can I see if there is a problem with DTLS?

{
  "contents": [
    {
      "channels": [
        {
          "endpoint": "AJZlIwJRoUeVgkIH18oH8es|||||AKFtPVVgAEYRg9Mpo8PTsX4",
          "channel-bundle-id": "AJZlIwJRoUeVgkIH18oH8es|||||AKFtPVVgAEYRg9Mpo8PTsX4",
          "sources": [
            2956762772
          ],
          "rtp-level-relay-type": "mixer",
          "expire": 300,
          "initiator": true,
          "ssrcs": [
            4093533850
          ],
          "id": "ed1fd6c4582ecdf9",
          "direction": "sendrecv"
        },
        {
          "endpoint": "AJZlIwJRoUeVgkIH18oH8es|||||APPwUUiockANs8z0uuWut0o",
          "channel-bundle-id": "AJZlIwJRoUeVgkIH18oH8es|||||APPwUUiockANs8z0uuWut0o",
          "sources": [
            150240950
          ],
          "rtp-level-relay-type": "mixer",
          "expire": 300,
          "initiator": true,
          "ssrcs": [
            150240950,
            2401775013
          ],
          "id": "f8f1184a69ee8efc",
          "direction": "sendrecv"
        }
      ],
      "name": "audio"
    },
    {
      "channels": [
        {
          "endpoint": "AJZlIwJRoUeVgkIH18oH8es|||||APPwUUiockANs8z0uuWut0o",
          "channel-bundle-id": "AJZlIwJRoUeVgkIH18oH8es|||||APPwUUiockANs8z0uuWut0o",
          "sources": [
            308971506
          ],
          "rtp-level-relay-type": "translator",
          "expire": 300,
          "initiator": true,
          "ssrcs": [
            308971506,
            1662882679
          ],
          "id": "5a040525d873f958",
          "receive-simulcast-layer": null,
          "direction": "sendrecv",
          "last-n": -1
        },
        {
          "endpoint": "AJZlIwJRoUeVgkIH18oH8es|||||AKFtPVVgAEYRg9Mpo8PTsX4",
          "channel-bundle-id": "AJZlIwJRoUeVgkIH18oH8es|||||AKFtPVVgAEYRg9Mpo8PTsX4",
          "sources": [
            308971506
          ],
          "rtp-level-relay-type": "translator",
          "expire": 300,
          "initiator": true,
          "ssrcs": [
            308971506,
            4094226272
          ],
          "id": "10d867680407e9fc",
          "receive-simulcast-layer": null,
          "direction": "sendrecv",
          "last-n": -1
        }
      ],
      "name": "video"
    },
    {
      "sctpconnections": [
        {
          "endpoint": "AJZlIwJRoUeVgkIH18oH8es|||||APPwUUiockANs8z0uuWut0o",
          "channel-bundle-id": "AJZlIwJRoUeVgkIH18oH8es|||||APPwUUiockANs8z0uuWut0o",
          "port": 5000,
          "expire": 60,
          "initiator": true,
          "id": "98562f80cf87f701"
        },
        {
          "endpoint": "AJZlIwJRoUeVgkIH18oH8es|||||AKFtPVVgAEYRg9Mpo8PTsX4",
          "channel-bundle-id": "AJZlIwJRoUeVgkIH18oH8es|||||AKFtPVVgAEYRg9Mpo8PTsX4",
          "port": 5000,
          "expire": 60,
          "initiator": true,
          "id": "f1306efa305ee599"
        }
      ],
      "name": "data"
    }
  ],
  "id": "b3733b44d05e94af"
}

This is the sdp for one of the clients:

v=0
o=- 5914814291097100623 2 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE audio video
a=msid-semantic: WMS hZ2qA6v2Jw3L46fya1NtHpqDfyr8zbPAfJQH
m=audio 60290 UDP/TLS/RTP/SAVPF 111 103 9 0 8 106 105 13 126
c=IN IP4 68.96.117.185
a=rtcp:9 IN IP4 0.0.0.0
a=candidate:599753277 1 udp 2122255103 2001::9d38:6ab8:247f:146f:bb9f:8a46 60289 typ host generation 0 network-id 2 network-cost 50
a=candidate:3351850224 1 udp 2122194687 192.168.0.5 60290 typ host generation 0 network-id 1 network-cost 10
a=candidate:1855365925 1 udp 1685987071 68.96.117.185 60290 typ srflx raddr 192.168.0.5 rport 60290 generation 0 network-id 1 network-cost 10
a=ice-ufrag:AUes
a=ice-pwd:g4fl9//uvxbNdeybUBuNSb+X
a=fingerprint:sha-256 0E:76:0F:79:DA:CA:87:EC:8C:CB:5F:E3:1E:66:6C:95:0A:32:D5:20:3B:F7:93:D8:85:F7:E3:97:DF:D3:A1:A8
a=setup:active
a=mid:audio
a=sendonly
a=rtcp-mux
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10;useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:126 telephone-event/8000
a=ssrc:1356548349 cname:3UL7+BBCKQQEpnCY
a=ssrc:1356548349 msid:hZ2qA6v2Jw3L46fya1NtHpqDfyr8zbPAfJQH c1e9b19b-ea14-4613-acbb-d7b27338ada1
a=ssrc:1356548349 mslabel:hZ2qA6v2Jw3L46fya1NtHpqDfyr8zbPAfJQH
a=ssrc:1356548349 label:c1e9b19b-ea14-4613-acbb-d7b27338ada1
m=video 9 UDP/TLS/RTP/SAVPF 100 116 117 96
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:AUes
a=ice-pwd:g4fl9//uvxbNdeybUBuNSb+X
a=fingerprint:sha-256 0E:76:0F:79:DA:CA:87:EC:8C:CB:5F:E3:1E:66:6C:95:0A:32:D5:20:3B:F7:93:D8:85:F7:E3:97:DF:D3:A1:A8
a=setup:active
a=mid:video
a=sendrecv
a=rtcp-mux
a=rtpmap:100 VP8/90000
a=rtcp-fb:100 ccm fir
a=rtcp-fb:100 nack
a=rtcp-fb:100 nack pli
a=rtcp-fb:100 goog-remb
a=rtpmap:116 red/90000
a=rtpmap:117 ulpfec/90000
a=rtpmap:96 rtx/90000
a=fmtp:96 apt=100
a=ssrc-group:FID 1919409531 181491116
a=ssrc:1919409531 cname:3UL7+BBCKQQEpnCY
a=ssrc:1919409531 msid:hZ2qA6v2Jw3L46fya1NtHpqDfyr8zbPAfJQH d0e848bb-4463-45bf-899a-93118e2bf905
a=ssrc:1919409531 mslabel:hZ2qA6v2Jw3L46fya1NtHpqDfyr8zbPAfJQH
a=ssrc:1919409531 label:d0e848bb-4463-45bf-899a-93118e2bf905
a=ssrc:181491116 cname:3UL7+BBCKQQEpnCY
a=ssrc:181491116 msid:hZ2qA6v2Jw3L46fya1NtHpqDfyr8zbPAfJQH d0e848bb-4463-45bf-899a-93118e2bf905
a=ssrc:181491116 mslabel:hZ2qA6v2Jw3L46fya1NtHpqDfyr8zbPAfJQH
a=ssrc:181491116 label:d0e848bb-4463-45bf-899a-93118e2bf905

IMHO the ssrcs appear in the correct place. Candidates are not replayed here, but I think that's quite normal. Candidates seem to have been exchanged as well.