[jitsi-dev] JVB: Failure to analyze packet (WebRTC, JSON API)


#1

Hi guys i have following problem:

2015-12-17 16:01:18.334 INFO: [68] org.ice4j.ice.Agent.logCandTypes()
Harvester used for selected pair for video.RTP: host
2015-12-17 16:01:18.338 INFO: [68] org.ice4j.ice.Agent.logCandTypes()
Harvester used for selected pair for video.RTCP: host
2015-12-17 16:01:18.344 INFO: [75] org.jitsi.videobridge.Channel.info()
Transport connected for channel 5a909cd29fd4607b of content audio of
conference 676e2141f3d1aeca
2015-12-17 16:01:18.352 INFO: [76] org.jitsi.videobridge.Channel.info()
Transport connected for channel b8c0b2d4f5508067 of content video of
conference 676e2141f3d1aeca
2015-12-17 16:01:18.682 INFO: [75]
org.jitsi.impl.neomedia.MediaStreamImpl.info() *audio codec/freq: null/null
Hz*
2015-12-17 16:01:18.687 INFO: [75]
org.jitsi.impl.neomedia.MediaStreamImpl.info() audio remote IP/port:
192.168.5.199/54433
2015-12-17 16:01:18.759 INFO: [76]
org.jitsi.impl.neomedia.MediaStreamImpl.info() *video codec/freq: null/null
Hz*
2015-12-17 16:01:18.877 INFO: [76]
org.jitsi.impl.neomedia.MediaStreamImpl.info() video remote IP/port:
192.168.5.199/54435
2015-12-17 16:01:19.522 INFO: [111]
org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.info()* Failed to
analyze an incoming RTCP packet for the purposes of statistics.*
*net.sf.fmj.media.rtp.util.BadVersionException: version must be 2.
(base.length 70, base.offset 0, firstbyte 0x58, offset 28)*
        at
net.sf.fmj.media.rtp.RTCPPacketParser.parse(RTCPPacketParser.java:139)
        at
org.jitsi.impl.neomedia.rtcp.RTCPPacketParserEx.parse(RTCPPacketParserEx.java:80)
        at
org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.reverseTransform(StatisticsEngine.java:955)
        at
org.jitsi.impl.neomedia.transform.SinglePacketTransformer.reverseTransform(SinglePacketTransformer.java:182)
        at
org.jitsi.impl.neomedia.transform.TransformEngineChain$PacketTransformerChain.reverseTransform(TransformEngineChain.java:299)
        at
org.jitsi.impl.neomedia.transform.TransformInputStream.createRawPacket(TransformInputStream.java:84)
        at
org.jitsi.impl.neomedia.RTPConnectorInputStream.runInReceiveThread(RTPConnectorInputStream.java:822)
        at
org.jitsi.impl.neomedia.RTPConnectorInputStream.access$000(RTPConnectorInputStream.java:41)
        at
org.jitsi.impl.neomedia.RTPConnectorInputStream$3.run(RTPConnectorInputStream.java:622)
2015-12-17 16:01:19.604 INFO: [112]
org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.info() Failed to
analyze an incoming RTCP packet for the purposes of statistics.
net.sf.fmj.media.rtp.util.BadVersionException: version must be 2.
(base.length 70, base.offset 0, firstbyte 0xf, offset 28)
        at
net.sf.fmj.media.rtp.RTCPPacketParser.parse(RTCPPacketParser.java:139)
        at
org.jitsi.impl.neomedia.rtcp.RTCPPacketParserEx.parse(RTCPPacketParserEx.java:80)
        at
org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.reverseTransform(StatisticsEngine.java:955)
        at
org.jitsi.impl.neomedia.transform.SinglePacketTransformer.reverseTransform(SinglePacketTransformer.java:182)
        at
org.jitsi.impl.neomedia.transform.TransformEngineChain$PacketTransformerChain.reverseTransform(TransformEngineChain.java:299)
        at
org.jitsi.impl.neomedia.transform.TransformInputStream.createRawPacket(TransformInputStream.java:84)
        at
org.jitsi.impl.neomedia.RTPConnectorInputStream.runInReceiveThread(RTPConnectorInputStream.java:822)
        at
org.jitsi.impl.neomedia.RTPConnectorInputStream.access$000(RTPConnectorInputStream.java:41)
        at
org.jitsi.impl.neomedia.RTPConnectorInputStream$3.run(RTPConnectorInputStream.java:622)
2015-12-17 16:01:19.788 INFO: [104] net.sf.fmj.media.Log.info() Resetting
queue, last seq added: 9223372036854775806, current seq: 14967
2015-12-17 16:01:19.912 INFO: [111]
org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.info() Failed to
analyze an incoming RTCP packet for the purposes of statistics.
net.sf.fmj.media.rtp.util.BadFormatException: p == null
        at
net.sf.fmj.media.rtp.RTCPPacketParser.parse(RTCPPacketParser.java:425)
        at
org.jitsi.impl.neomedia.rtcp.RTCPPacketParserEx.parse(RTCPPacketParserEx.java:80)
        at
org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.reverseTransform(StatisticsEngine.java:955)
        at
org.jitsi.impl.neomedia.transform.SinglePacketTransformer.reverseTransform(SinglePacketTransformer.java:182)
        at
org.jitsi.impl.neomedia.transform.TransformEngineChain$PacketTransformerChain.reverseTransform(TransformEngineChain.java:299)
        at
org.jitsi.impl.neomedia.transform.TransformInputStream.createRawPacket(TransformInputStream.java:84)
        at
org.jitsi.impl.neomedia.RTPConnectorInputStream.runInReceiveThread(RTPConnectorInputStream.java:822)
        at
org.jitsi.impl.neomedia.RTPConnectorInputStream.access$000(RTPConnectorInputStream.java:41)
        at
org.jitsi.impl.neomedia.RTPConnectorInputStream$3.run(RTPConnectorInputStream.java:622)
2015-12-17 16:01:20.007 INFO: [111]
org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.info() Failed to
analyze an incoming RTCP packet for the purposes of statistics.
net.sf.fmj.media.rtp.util.BadVersionException: version must be 2.
(base.length 70, base.offset 0, firstbyte 0x42, offset 28)
        at
net.sf.fmj.media.rtp.RTCPPacketParser.parse(RTCPPacketParser.java:139)
        at
org.jitsi.impl.neomedia.rtcp.RTCPPacketParserEx.parse(RTCPPacketParserEx.java:80)
        at
org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.reverseTransform(StatisticsEngine.java:955)
        at
org.jitsi.impl.neomedia.transform.SinglePacketTransformer.reverseTransform(SinglePacketTransformer.java:182)
        at
org.jitsi.impl.neomedia.transform.TransformEngineChain$PacketTransformerChain.reverseTransform(TransformEngineChain.java:299)
        at
org.jitsi.impl.neomedia.transform.TransformInputStream.createRawPacket(TransformInputStream.java:84)
        at
org.jitsi.impl.neomedia.RTPConnectorInputStream.runInReceiveThread(RTPConnectorInputStream.java:822)
        at
org.jitsi.impl.neomedia.RTPConnectorInputStream.access$000(RTPConnectorInputStream.java:41)
        at
org.jitsi.impl.neomedia.RTPConnectorInputStream$3.run(RTPConnectorInputStream.java:622)
2015-12-17 16:01:20.383 WARNING: [95]
org.jitsi.impl.neomedia.transform.srtp.HMACSHA1.warn() Failed to employ
OpenSSL (Crypto) for an optimized HMAC-SHA1 implementation: no jnopenssl in
java.library.path
2015-12-17 16:01:20.393 WARNING: [93]
org.jitsi.impl.neomedia.transform.srtp.HMACSHA1.warn() Failed to employ
OpenSSL (Crypto) for an optimized HMAC-SHA1 implementation: EVP_sha1
2015-12-17 16:01:20.395 WARNING: [94]
org.jitsi.impl.neomedia.transform.srtp.HMACSHA1.warn() Failed to employ
OpenSSL (Crypto) for an optimized HMAC-SHA1 implementation: EVP_sha1
2015-12-17 16:01:20.404 WARNING: [96]
org.jitsi.impl.neomedia.transform.srtp.HMACSHA1.warn() Failed to employ
OpenSSL (Crypto) for an optimized HMAC-SHA1 implementation: EVP_sha1
2015-12-17 16:01:20.409 WARNING: [93]
org.jitsi.impl.neomedia.transform.srtp.SHA1.warn() Failed to employ OpenSSL
(Crypto) for an optimized SHA-1 implementation: EVP_sha1
2015-12-17 16:01:20.413 WARNING: [95]
org.jitsi.impl.neomedia.transform.srtp.SHA1.warn() Failed to employ OpenSSL
(Crypto) for an optimized SHA-1 implementation: no jnopenssl in
java.library.path
2015-12-17 16:01:21.257 INFO: [89] org.ice4j.ice.Agent.setState() ICE state
changed from Completed to Terminated
2015-12-17 16:01:21.263 INFO: [89]
org.jitsi.videobridge.IceUdpTransportManager.info() ICE processing state of
IceUdpTransportManager #6a1b684e (for channels 5a909cd29fd4607b) of
conference 676e2141f3d1aeca changed from Completed to Terminated.
2015-12-17 16:01:21.334 INFO: [91] org.ice4j.ice.Agent.setState() ICE state
changed from Completed to Terminated
2015-12-17 16:01:21.337 INFO: [91]
org.jitsi.videobridge.IceUdpTransportManager.info() ICE processing state of
IceUdpTransportManager #45ce6e09 (for channels b8c0b2d4f5508067) of
conference 676e2141f3d1aeca changed from Completed to Terminated.
2015-12-17 16:03:37.028 INFO: [107] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Total packets added: 1
2015-12-17 16:03:37.096 INFO: [107] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Times reset() called: 1
2015-12-17 16:03:37.101 INFO: [108] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Total packets added: 0
2015-12-17 16:03:37.103 INFO: [107] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Times grow() called: 0
2015-12-17 16:03:37.107 INFO: [107] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because full: 0
2015-12-17 16:03:37.109 INFO: [107] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped while shrinking: 0
2015-12-17 16:03:37.112 INFO: [107] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were
late: 0
2015-12-17 16:03:37.116 INFO: [107] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late
by more than MAX_SIZE: 0
2015-12-17 16:03:37.116 INFO: [107] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped in reset(): 0
2015-12-17 16:03:37.118 INFO: [107] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Max size reached: 4
2015-12-17 16:03:37.119 INFO: [107] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Adaptive jitter buffer mode was enabled
2015-12-17 16:03:37.120 INFO: [107] net.sf.fmj.media.Log.info() Stopping
RTPSourceStream.
2015-12-17 16:03:37.123 INFO: [108] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Times reset() called: 0
2015-12-17 16:03:37.124 INFO: [108] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Times grow() called: 0
2015-12-17 16:03:37.125 INFO: [108] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because full: 0
2015-12-17 16:03:37.126 INFO: [108] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped while shrinking: 0
2015-12-17 16:03:37.127 INFO: [108] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were
late: 0
2015-12-17 16:03:37.129 INFO: [108] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late
by more than MAX_SIZE: 0
2015-12-17 16:03:37.131 INFO: [108] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped in reset(): 0
2015-12-17 16:03:37.144 INFO: [108] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Max size reached: 0
2015-12-17 16:03:37.146 INFO: [108] net.sf.fmj.media.Log.info()
net.sf.fmj.media.rtp.RTPSourceStream Adaptive jitter buffer mode was enabled
2015-12-17 16:03:37.147 INFO: [108] net.sf.fmj.media.Log.info() Stopping
RTPSourceStream.
2015-12-17 16:03:49.433 INFO: [87]
org.ice4j.ice.ConnectivityCheckClient.processTimeout() timeout for pair:
192.168.5.199:10010/udp/host -> 192.168.5.199:54434/udp/host (audio.RTCP),
failing.
2015-12-17 16:03:49.562 INFO: [81]
org.ice4j.ice.ConnectivityCheckClient.processTimeout() timeout for pair:
192.168.5.199:10011/udp/host -> 192.168.5.199:54435/udp/host (video.RTP),
failing.
2015-12-17 16:03:49.575 INFO: [83]
org.ice4j.ice.ConnectivityCheckClient.processTimeout() timeout for pair:
192.168.5.199:10012/udp/host -> 192.168.5.199:54436/udp/host (video.RTCP),
failing.
2015-12-17 16:03:49.582 INFO: [80]
org.ice4j.ice.ConnectivityCheckClient.processTimeout() timeout for pair:
192.168.5.199:10009/udp/host -> 192.168.5.199:54433/udp/host (audio.RTP),
failing.
2015-12-17 16:03:58.596 INFO: [14]
org.jitsi.impl.neomedia.MediaStreamImpl.info()
Receive stream stats: discarded RTP packets: 0
Receive stream stats: decoded with FEC: 0
2015-12-17 16:03:58.606 WARNING: [66] org.ice4j.stack.Connector.run()
Connector died: 192.168.5.199:10010/udp -> null
java.net.SocketException: socket closed
....

As seen codecs are both null and i think this issue is caused because
payload types are not set/registed with JVB.
I am using REST interface of JVB with following flow (on JVB side):

1.) Allocate video/audio channels using POST (creating new conference)
2.) Get related data from WebRTC client and translate it to json for PATCH
transport and payload data
{
  "contents": [
    {
      "channels": [
        {
          "sources": [
            3794968865
          ],
         * "payload-types": [*
* {*
* "id": 111,*
* "name": "opus",*
* "clockrate": 48000,*
* "channels": 2,*
* "parameters": {*
* "minptime": "10",*
* "useinbandfec": "1"*
* }*
* },*
* {*
* "id": 0,*
* "name": "PCMU",*
* "clockrate": 8000,*
* "channels": 1*
* },*
* {*
* "id": 8,*
* "name": "PCMA",*
* "clockrate": 8000,*
* "channels": 1*
* }*
* ]*,
          "initiator": true,
          "transport": {
            "xmlns": "urn:xmpp:jingle:transports:ice-udp:1",
            "ufrag": "cRRBLBfZ/Yrn+SAa",
            "pwd": "ZnEXywCMZ4Bq0j/chwpH/FD2",
            "fingerprints": [
              {
                "hash": "sha-256",
                "fingerprint":
"6F:C1:99:AD:47:40:D5:CF:19:29:81:1D:9E:B2:10:11:E9:55:D9:B8:04:E0:5F:53:71:07:0A:43:85:40:D6:33"
              }
            ]
          },
          "id": "5a909cd29fd4607b"
        }
      ],
      "name": "audio"
    },
    {
      "channels": [
        {
          "sources": [
            91790717
          ],
          *"payload-types": [*
* {*
* "id": 100,*
* "name": "VP8",*
* "clockrate": 90000,*
* "channels": 1,*
* "rtcp-fbs": [*
* {*
* "type": "ccm",*
* "subtype": "fir"*
* },*
* {*
* "type": "nack"*
* },*
* {*
* "type": "nack",*
* "subtype": "pli"*
* },*
* {*
* "type": "goog-remb"*
* }*
* ]*
* }*
* ]*,
          "initiator": true,
          "transport": {
            "xmlns": "urn:xmpp:jingle:transports:ice-udp:1",
            "ufrag": "EhUHU272C60qTqUU",
            "pwd": "YrJolCMZDb0pFVnEWh8ZZmxk",
            "fingerprints": [
              {
                "hash": "sha-256",
                "fingerprint":
"6F:C1:99:AD:47:40:D5:CF:19:29:81:1D:9E:B2:10:11:E9:55:D9:B8:04:E0:5F:53:71:07:0A:43:85:40:D6:33"
              }
            ]
          },
          "id": "b8c0b2d4f5508067"
        }
      ],
      "name": "video"
    }
  ],
  "id": "676e2141f3d1aeca"
}

3.) Update remote candidates using PATCH
For each gathered candidate call PATCH with following json (example)
{
  "contents": [
    {
      "channels": [
        {
          "initiator": true,
          "transport": {
            "candidates": [
              {
                "foundation": "candidate:1207397209",
                "component": 2,
                "protocol": "udp",
                "priority": 2122194686,
                "ip": "192.168.5.199",
                "port": 54434,
                "type": "host",
                "generation": 0,
                "network": 0
              }
            ],
            "xmlns": "urn:xmpp:jingle:transports:ice-udp:1"
          },
          "id": "5a909cd29fd4607b"
        }
      ],
      "name": "audio"
    }
  ],
  "id": "676e2141f3d1aeca"
}

As you can see media flow is established but as soon packets starts flowing
i get above exception. Using rest api payload types are set (in bold) but
not registered on JVB (both null as you can see from log). Can this be bug??


#2

Hi,

The null codecs are a red herring. FMJ prints it because it doesn't have any send or receive streams (which is normal, we handle the streams before they actually reach FMJ).

The exceptions when parsing RTCP packets are not normal, but they shouldn't cause any issues, as long as only a few of them appear. We have it on our task list to fix them.

The messages regarding openssl may mean that you have degraded performance, and may indicate a problem elsewhere, but shouldn't cause any failures. Verify that you are running with java.library.path that includes the lib/native/linux-64/ (if you are running on linux x86_64).

Regards,
Boris

···

On 17/12/15 09:32, David Juran wrote:

Hi guys i have following problem:

2015-12-17 16:01:18.334 INFO: [68] org.ice4j.ice.Agent.logCandTypes()
Harvester used for selected pair for video.RTP: host
2015-12-17 16:01:18.338 INFO: [68] org.ice4j.ice.Agent.logCandTypes()
Harvester used for selected pair for video.RTCP: host
2015-12-17 16:01:18.344 INFO: [75] org.jitsi.videobridge.Channel.info
<http://org.jitsi.videobridge.Channel.info>() Transport connected for
channel 5a909cd29fd4607b of content audio of conference 676e2141f3d1aeca
2015-12-17 16:01:18.352 INFO: [76] org.jitsi.videobridge.Channel.info
<http://org.jitsi.videobridge.Channel.info>() Transport connected for
channel b8c0b2d4f5508067 of content video of conference 676e2141f3d1aeca
2015-12-17 16:01:18.682 INFO: [75]
org.jitsi.impl.neomedia.MediaStreamImpl.info
<http://org.jitsi.impl.neomedia.MediaStreamImpl.info>() *audio
codec/freq: null/null Hz*
2015-12-17 16:01:18.687 INFO: [75]
org.jitsi.impl.neomedia.MediaStreamImpl.info
<http://org.jitsi.impl.neomedia.MediaStreamImpl.info>() audio remote
IP/port: 192.168.5.199/54433 <http://192.168.5.199/54433>
2015-12-17 16:01:18.759 INFO: [76]
org.jitsi.impl.neomedia.MediaStreamImpl.info
<http://org.jitsi.impl.neomedia.MediaStreamImpl.info>() *video
codec/freq: null/null Hz*
2015-12-17 16:01:18.877 INFO: [76]
org.jitsi.impl.neomedia.MediaStreamImpl.info
<http://org.jitsi.impl.neomedia.MediaStreamImpl.info>() video remote
IP/port: 192.168.5.199/54435 <http://192.168.5.199/54435>
2015-12-17 16:01:19.522 INFO: [111]
org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.info
<http://org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.info>()*Failed
to analyze an incoming RTCP packet for the purposes of statistics.*
*net.sf.fmj.media.rtp.util.BadVersionException: version must be 2.
(base.length 70, base.offset 0, firstbyte 0x58, offset 28)*
         at
net.sf.fmj.media.rtp.RTCPPacketParser.parse(RTCPPacketParser.java:139)
         at
org.jitsi.impl.neomedia.rtcp.RTCPPacketParserEx.parse(RTCPPacketParserEx.java:80)
         at
org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.reverseTransform(StatisticsEngine.java:955)
         at
org.jitsi.impl.neomedia.transform.SinglePacketTransformer.reverseTransform(SinglePacketTransformer.java:182)
         at
org.jitsi.impl.neomedia.transform.TransformEngineChain$PacketTransformerChain.reverseTransform(TransformEngineChain.java:299)
         at
org.jitsi.impl.neomedia.transform.TransformInputStream.createRawPacket(TransformInputStream.java:84)
         at
org.jitsi.impl.neomedia.RTPConnectorInputStream.runInReceiveThread(RTPConnectorInputStream.java:822)
         at
org.jitsi.impl.neomedia.RTPConnectorInputStream.access$000(RTPConnectorInputStream.java:41)
         at
org.jitsi.impl.neomedia.RTPConnectorInputStream$3.run(RTPConnectorInputStream.java:622)
2015-12-17 16:01:19.604 INFO: [112]
org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.info
<http://org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.info>()
Failed to analyze an incoming RTCP packet for the purposes of statistics.
net.sf.fmj.media.rtp.util.BadVersionException: version must be 2.
(base.length 70, base.offset 0, firstbyte 0xf, offset 28)
         at
net.sf.fmj.media.rtp.RTCPPacketParser.parse(RTCPPacketParser.java:139)
         at
org.jitsi.impl.neomedia.rtcp.RTCPPacketParserEx.parse(RTCPPacketParserEx.java:80)
         at
org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.reverseTransform(StatisticsEngine.java:955)
         at
org.jitsi.impl.neomedia.transform.SinglePacketTransformer.reverseTransform(SinglePacketTransformer.java:182)
         at
org.jitsi.impl.neomedia.transform.TransformEngineChain$PacketTransformerChain.reverseTransform(TransformEngineChain.java:299)
         at
org.jitsi.impl.neomedia.transform.TransformInputStream.createRawPacket(TransformInputStream.java:84)
         at
org.jitsi.impl.neomedia.RTPConnectorInputStream.runInReceiveThread(RTPConnectorInputStream.java:822)
         at
org.jitsi.impl.neomedia.RTPConnectorInputStream.access$000(RTPConnectorInputStream.java:41)
         at
org.jitsi.impl.neomedia.RTPConnectorInputStream$3.run(RTPConnectorInputStream.java:622)
2015-12-17 16:01:19.788 INFO: [104] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>() Resetting queue, last seq added:
9223372036854775806, current seq: 14967
2015-12-17 16:01:19.912 INFO: [111]
org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.info
<http://org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.info>()
Failed to analyze an incoming RTCP packet for the purposes of statistics.
net.sf.fmj.media.rtp.util.BadFormatException: p == null
         at
net.sf.fmj.media.rtp.RTCPPacketParser.parse(RTCPPacketParser.java:425)
         at
org.jitsi.impl.neomedia.rtcp.RTCPPacketParserEx.parse(RTCPPacketParserEx.java:80)
         at
org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.reverseTransform(StatisticsEngine.java:955)
         at
org.jitsi.impl.neomedia.transform.SinglePacketTransformer.reverseTransform(SinglePacketTransformer.java:182)
         at
org.jitsi.impl.neomedia.transform.TransformEngineChain$PacketTransformerChain.reverseTransform(TransformEngineChain.java:299)
         at
org.jitsi.impl.neomedia.transform.TransformInputStream.createRawPacket(TransformInputStream.java:84)
         at
org.jitsi.impl.neomedia.RTPConnectorInputStream.runInReceiveThread(RTPConnectorInputStream.java:822)
         at
org.jitsi.impl.neomedia.RTPConnectorInputStream.access$000(RTPConnectorInputStream.java:41)
         at
org.jitsi.impl.neomedia.RTPConnectorInputStream$3.run(RTPConnectorInputStream.java:622)
2015-12-17 16:01:20.007 INFO: [111]
org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.info
<http://org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.info>()
Failed to analyze an incoming RTCP packet for the purposes of statistics.
net.sf.fmj.media.rtp.util.BadVersionException: version must be 2.
(base.length 70, base.offset 0, firstbyte 0x42, offset 28)
         at
net.sf.fmj.media.rtp.RTCPPacketParser.parse(RTCPPacketParser.java:139)
         at
org.jitsi.impl.neomedia.rtcp.RTCPPacketParserEx.parse(RTCPPacketParserEx.java:80)
         at
org.jitsi.impl.neomedia.transform.rtcp.StatisticsEngine.reverseTransform(StatisticsEngine.java:955)
         at
org.jitsi.impl.neomedia.transform.SinglePacketTransformer.reverseTransform(SinglePacketTransformer.java:182)
         at
org.jitsi.impl.neomedia.transform.TransformEngineChain$PacketTransformerChain.reverseTransform(TransformEngineChain.java:299)
         at
org.jitsi.impl.neomedia.transform.TransformInputStream.createRawPacket(TransformInputStream.java:84)
         at
org.jitsi.impl.neomedia.RTPConnectorInputStream.runInReceiveThread(RTPConnectorInputStream.java:822)
         at
org.jitsi.impl.neomedia.RTPConnectorInputStream.access$000(RTPConnectorInputStream.java:41)
         at
org.jitsi.impl.neomedia.RTPConnectorInputStream$3.run(RTPConnectorInputStream.java:622)
2015-12-17 16:01:20.383 WARNING: [95]
org.jitsi.impl.neomedia.transform.srtp.HMACSHA1.warn() Failed to employ
OpenSSL (Crypto) for an optimized HMAC-SHA1 implementation: no jnopenssl
in java.library.path
2015-12-17 16:01:20.393 WARNING: [93]
org.jitsi.impl.neomedia.transform.srtp.HMACSHA1.warn() Failed to employ
OpenSSL (Crypto) for an optimized HMAC-SHA1 implementation: EVP_sha1
2015-12-17 16:01:20.395 WARNING: [94]
org.jitsi.impl.neomedia.transform.srtp.HMACSHA1.warn() Failed to employ
OpenSSL (Crypto) for an optimized HMAC-SHA1 implementation: EVP_sha1
2015-12-17 16:01:20.404 WARNING: [96]
org.jitsi.impl.neomedia.transform.srtp.HMACSHA1.warn() Failed to employ
OpenSSL (Crypto) for an optimized HMAC-SHA1 implementation: EVP_sha1
2015-12-17 16:01:20.409 WARNING: [93]
org.jitsi.impl.neomedia.transform.srtp.SHA1.warn() Failed to employ
OpenSSL (Crypto) for an optimized SHA-1 implementation: EVP_sha1
2015-12-17 16:01:20.413 WARNING: [95]
org.jitsi.impl.neomedia.transform.srtp.SHA1.warn() Failed to employ
OpenSSL (Crypto) for an optimized SHA-1 implementation: no jnopenssl in
java.library.path
2015-12-17 16:01:21.257 INFO: [89] org.ice4j.ice.Agent.setState() ICE
state changed from Completed to Terminated
2015-12-17 16:01:21.263 INFO: [89]
org.jitsi.videobridge.IceUdpTransportManager.info
<http://org.jitsi.videobridge.IceUdpTransportManager.info>() ICE
processing state of IceUdpTransportManager #6a1b684e (for channels
5a909cd29fd4607b) of conference 676e2141f3d1aeca changed from Completed
to Terminated.
2015-12-17 16:01:21.334 INFO: [91] org.ice4j.ice.Agent.setState() ICE
state changed from Completed to Terminated
2015-12-17 16:01:21.337 INFO: [91]
org.jitsi.videobridge.IceUdpTransportManager.info
<http://org.jitsi.videobridge.IceUdpTransportManager.info>() ICE
processing state of IceUdpTransportManager #45ce6e09 (for channels
b8c0b2d4f5508067) of conference 676e2141f3d1aeca changed from Completed
to Terminated.
2015-12-17 16:03:37.028 INFO: [107] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Total packets added: 1
2015-12-17 16:03:37.096 INFO: [107] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Times reset() called: 1
2015-12-17 16:03:37.101 INFO: [108] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Total packets added: 0
2015-12-17 16:03:37.103 INFO: [107] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Times grow() called: 0
2015-12-17 16:03:37.107 INFO: [107] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because full: 0
2015-12-17 16:03:37.109 INFO: [107] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped while shrinking: 0
2015-12-17 16:03:37.112 INFO: [107] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were
late: 0
2015-12-17 16:03:37.116 INFO: [107] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were
late by more than MAX_SIZE: 0
2015-12-17 16:03:37.116 INFO: [107] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped in reset(): 0
2015-12-17 16:03:37.118 INFO: [107] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Max size reached: 4
2015-12-17 16:03:37.119 INFO: [107] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Adaptive jitter buffer mode was enabled
2015-12-17 16:03:37.120 INFO: [107] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>() Stopping RTPSourceStream.
2015-12-17 16:03:37.123 INFO: [108] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Times reset() called: 0
2015-12-17 16:03:37.124 INFO: [108] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Times grow() called: 0
2015-12-17 16:03:37.125 INFO: [108] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because full: 0
2015-12-17 16:03:37.126 INFO: [108] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped while shrinking: 0
2015-12-17 16:03:37.127 INFO: [108] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were
late: 0
2015-12-17 16:03:37.129 INFO: [108] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were
late by more than MAX_SIZE: 0
2015-12-17 16:03:37.131 INFO: [108] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Packets dropped in reset(): 0
2015-12-17 16:03:37.144 INFO: [108] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Max size reached: 0
2015-12-17 16:03:37.146 INFO: [108] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>()
net.sf.fmj.media.rtp.RTPSourceStream Adaptive jitter buffer mode was enabled
2015-12-17 16:03:37.147 INFO: [108] net.sf.fmj.media.Log.info
<http://net.sf.fmj.media.Log.info>() Stopping RTPSourceStream.
2015-12-17 16:03:49.433 INFO: [87]
org.ice4j.ice.ConnectivityCheckClient.processTimeout() timeout for pair:
192.168.5.199:10010/udp/host <http://192.168.5.199:10010/udp/host> ->
192.168.5.199:54434/udp/host <http://192.168.5.199:54434/udp/host>
(audio.RTCP), failing.
2015-12-17 16:03:49.562 INFO: [81]
org.ice4j.ice.ConnectivityCheckClient.processTimeout() timeout for pair:
192.168.5.199:10011/udp/host <http://192.168.5.199:10011/udp/host> ->
192.168.5.199:54435/udp/host <http://192.168.5.199:54435/udp/host>
(video.RTP), failing.
2015-12-17 16:03:49.575 INFO: [83]
org.ice4j.ice.ConnectivityCheckClient.processTimeout() timeout for pair:
192.168.5.199:10012/udp/host <http://192.168.5.199:10012/udp/host> ->
192.168.5.199:54436/udp/host <http://192.168.5.199:54436/udp/host>
(video.RTCP), failing.
2015-12-17 16:03:49.582 INFO: [80]
org.ice4j.ice.ConnectivityCheckClient.processTimeout() timeout for pair:
192.168.5.199:10009/udp/host <http://192.168.5.199:10009/udp/host> ->
192.168.5.199:54433/udp/host <http://192.168.5.199:54433/udp/host>
(audio.RTP), failing.
2015-12-17 16:03:58.596 INFO: [14]
org.jitsi.impl.neomedia.MediaStreamImpl.info
<http://org.jitsi.impl.neomedia.MediaStreamImpl.info>()
Receive stream stats: discarded RTP packets: 0
Receive stream stats: decoded with FEC: 0
2015-12-17 16:03:58.606 WARNING: [66] org.ice4j.stack.Connector.run()
Connector died: 192.168.5.199:10010/udp <http://192.168.5.199:10010/udp>
-> null
java.net.SocketException: socket closed
....

As seen codecs are both null and i think this issue is caused because
payload types are not set/registed with JVB.

I am using REST interface of JVB with following flow (on JVB side):

1.) Allocate video/audio channels using POST (creating new conference)
2.) Get related data from WebRTC client and translate it to json for PATCH
transport and payload data
{
   "contents": [
     {
       "channels": [
         {
           "sources": [
             3794968865
           ],
*"payload-types": [*
* {*
* "id": 111,*
* "name": "opus",*
* "clockrate": 48000,*
* "channels": 2,*
* "parameters": {*
* "minptime": "10",*
* "useinbandfec": "1"*
* }*
* },*
* {*
* "id": 0,*
* "name": "PCMU",*
* "clockrate": 8000,*
* "channels": 1*
* },*
* {*
* "id": 8,*
* "name": "PCMA",*
* "clockrate": 8000,*
* "channels": 1*
* }*
* ]*,
           "initiator": true,
           "transport": {
             "xmlns": "urn:xmpp:jingle:transports:ice-udp:1",
             "ufrag": "cRRBLBfZ/Yrn+SAa",
             "pwd": "ZnEXywCMZ4Bq0j/chwpH/FD2",
             "fingerprints": [
               {
                 "hash": "sha-256",
                 "fingerprint":
"6F:C1:99:AD:47:40:D5:CF:19:29:81:1D:9E:B2:10:11:E9:55:D9:B8:04:E0:5F:53:71:07:0A:43:85:40:D6:33"
               }
             ]
           },
           "id": "5a909cd29fd4607b"
         }
       ],
       "name": "audio"
     },
     {
       "channels": [
         {
           "sources": [
             91790717
           ],
*"payload-types": [*
* {*
* "id": 100,*
* "name": "VP8",*
* "clockrate": 90000,*
* "channels": 1,*
* "rtcp-fbs": [*
* {*
* "type": "ccm",*
* "subtype": "fir"*
* },*
* {*
* "type": "nack"*
* },*
* {*
* "type": "nack",*
* "subtype": "pli"*
* },*
* {*
* "type": "goog-remb"*
* }*
* ]*
* }*
* ]*,
           "initiator": true,
           "transport": {
             "xmlns": "urn:xmpp:jingle:transports:ice-udp:1",
             "ufrag": "EhUHU272C60qTqUU",
             "pwd": "YrJolCMZDb0pFVnEWh8ZZmxk",
             "fingerprints": [
               {
                 "hash": "sha-256",
                 "fingerprint":
"6F:C1:99:AD:47:40:D5:CF:19:29:81:1D:9E:B2:10:11:E9:55:D9:B8:04:E0:5F:53:71:07:0A:43:85:40:D6:33"
               }
             ]
           },
           "id": "b8c0b2d4f5508067"
         }
       ],
       "name": "video"
     }
   ],
   "id": "676e2141f3d1aeca"
}

3.) Update remote candidates using PATCH
For each gathered candidate call PATCH with following json (example)
{
   "contents": [
     {
       "channels": [
         {
           "initiator": true,
           "transport": {
             "candidates": [
               {
                 "foundation": "candidate:1207397209",
                 "component": 2,
                 "protocol": "udp",
                 "priority": 2122194686,
                 "ip": "192.168.5.199",
                 "port": 54434,
                 "type": "host",
                 "generation": 0,
                 "network": 0
               }
             ],
             "xmlns": "urn:xmpp:jingle:transports:ice-udp:1"
           },
           "id": "5a909cd29fd4607b"
         }
       ],
       "name": "audio"
     }
   ],
   "id": "676e2141f3d1aeca"
}

As you can see media flow is established but as soon packets starts
flowing i get above exception. Using rest api payload types are set (in
bold) but not registered on JVB (both null as you can see from log). Can
this be bug??

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