Improve call compatibility with Dino

inuit-lang commented 3 years ago

Hello,

Dino is beta-testing audio/video calls. According to the tests I did between Siskin and Dino, the calls work pretty well (at least for audio) when Siskin initiates the call but fail when Dino does.

Please add compatibility to receive a call from Dino to Siskin.

Secondly, about the video calls: I don't know if the issue is located in Dino or in Siskin, but on one side Siskin displays well video stream received from Dino but video stream received in Dino from Siskin works randomly (sometimes works well, sometimes get blurred or black screen).

Thank you

woj-tek commented 3 years ago

when Siskin initiates the call but fail when Dino does. Please add compatibility to receive a call from Dino to Siskin.

Could you please give more details?

How it fails? Any error?

inuit-lang commented 3 years ago

When Dino initiates a call to Siskin, the phone (Siskin) rings, and when I answer it displays "Call failed"

hantu85 commented 3 years ago

Siskin may cancel/reject a call if it is a video call and you've not granted permission to use the camera/microphone by the app. It may also drop the call if:

there is no compatible codec offered by the caller
there is no viable way to establish a connection (no STUN/TURN servers)
there is an error/inconsistency in the request to establish the call

At this point as Siskin works quite well with Conversations and I've tested it with a few other clients I wonder why it is rejecting calls from Dino? What is being done in a different way by Dino?

inuit-lang commented 3 years ago

Siskin has camera/microphone permission and I think that if it was a STUN/TURN problem, calls would fail in both directions

inuit-lang commented 3 years ago

As now I have a fine configured Stun/Turn service on my server (Calls Siskin to Siskin work, and as I previously said Siskin to Dino) but calls Dino to Siskin don't work at all

woj-tek commented 3 years ago

Could you share stanza exchange during the call negotiation?

inuit-lang commented 3 years ago

How can I get it ?

woj-tek commented 3 years ago

You said: "service on my server" so I assumed you also own XMPP server so you could extract that from it's logs.

inuit-lang commented 3 years ago

I assumed you also own XMPP server

Right ! Here's the logs but they don't give so much info (server is running ejabberd) :

2021-04-21 21:21:59.190 [info] <0.1955.0> Creating TURN allocation (lifetime: 599 seconds) [UDP, session 2lqc, user 1619073684:ffef2e75f68144fc, client **.***.***.**:58675, relay **.**.***.***:63142]
2021-04-21 21:21:59.191 [info] <0.1956.0> Creating TURN allocation (lifetime: 599 seconds) [UDP, session 2lr8, user 1619073684:ffef2e75f68144fc, client **.***.***.**:58968, relay **.**.***.***:60769]
2021-04-21 21:21:59.417 [info] <0.1957.0> Creating TURN allocation (lifetime: 600 seconds) [UDP, session 2ltw, user 1619073684:ffef2e75f68144fc, client **.***.***.**:58577, relay **.**.***.***:49888]
2021-04-21 21:21:59.418 [info] <0.1958.0> Creating TURN allocation (lifetime: 600 seconds) [UDP, session 2lus, user 1619073684:ffef2e75f68144fc, client **.***.***.**:59863, relay **.**.***.***:63733]
2021-04-21 21:21:59.687 [info] <0.1960.0> Creating TURN allocation (lifetime: 599 seconds) [UDP, session 2m3o, user 1619073684:ffef2e75f68144fc, client **.***.***.**:57376, relay **.**.***.***:61033]
2021-04-21 21:21:59.688 [info] <0.1961.0> Creating TURN allocation (lifetime: 600 seconds) [TLS, session 51xe, user 1619073684:ffef2e75f68144fc, client **.***.***.**:51127, relay **.**.***.***:52552]
2021-04-21 21:21:59.688 [info] <0.1959.0> Creating TURN allocation (lifetime: 599 seconds) [TLS, session 51ya, user 1619073684:ffef2e75f68144fc, client **.***.***.**:51126, relay **.**.***.***:58512]
2021-04-21 21:21:59.733 [info] <0.1962.0> Creating TURN allocation (lifetime: 600 seconds) [TLS, session 520y, user 1619073684:ffef2e75f68144fc, client **.***.***.**:51130, relay **.**.***.***:62548]
2021-04-21 21:21:59.733 [info] <0.1963.0> Creating TURN allocation (lifetime: 600 seconds) [TLS, session 5202, user 1619073684:ffef2e75f68144fc, client **.***.***.**:51128, relay **.**.***.***:59687]
2021-04-21 21:21:59.734 [info] <0.1964.0> Creating TURN allocation (lifetime: 599 seconds) [TLS, session 51z6, user 1619073684:ffef2e75f68144fc, client **.***.***.**:51129, relay **.**.***.***:51750]
2021-04-21 21:22:07.561 [info] <0.1962.0> Relayed 0 KiB (in 0 B / 0 packets, out 0 B / 0 packets), duration: 7 seconds [TLS, session 520y, user 1619073684:ffef2e75f68144fc, client **.***.***.**:51130, relay **.**.***.***:62548]
2021-04-21 21:22:07.561 [info] <0.1961.0> Relayed 0 KiB (in 0 B / 0 packets, out 0 B / 0 packets), duration: 7 seconds [TLS, session 51xe, user 1619073684:ffef2e75f68144fc, client **.***.***.**:51127, relay **.**.***.***:52552]
2021-04-21 21:22:07.562 [info] <0.1964.0> Relayed 0 KiB (in 0 B / 0 packets, out 0 B / 0 packets), duration: 7 seconds [TLS, session 51z6, user 1619073684:ffef2e75f68144fc, client **.***.***.**:51129, relay **.**.***.***:51750]
2021-04-21 21:22:07.562 [info] <0.1963.0> Relayed 0 KiB (in 0 B / 0 packets, out 0 B / 0 packets), duration: 7 seconds [TLS, session 5202, user 1619073684:ffef2e75f68144fc, client **.***.***.**:51128, relay **.**.***.***:59687]
2021-04-21 21:22:07.567 [info] <0.1946.0>@mod_stream_mgmt:transition_to_pending:459 (tls|<0.1946.0>) Closing c2s connection for user@server/iPhone: Connection failed: connection closed; waiting 90 seconds for stream resumption

woj-tek commented 3 years ago

Don't you have detailed logs from stream negotiation (i.e. IQ stanzas that are being send between Dino and Siskin)?

Btw. could you use 3x tick symbol before and after to indicate the block? Single tick doesn't format it well.

inuit-lang commented 3 years ago

In the server I know three log files (it runs on a Raspberry Pi) in /var/log/ejabberd/ : ejabberd.log, error.log and crash.log. Only ejabberd.log is written when attempting to call (the log I already posted), the others don't (I don't see others log files).

When I launch Dino in my desktop from terminal, I see these infos when attempting to call Siskin:

(dino:9103): GLib-GObject-CRITICAL **: 14:11:55.373: g_object_ref: assertion 'G_IS_OBJECT (object)' failed

(dino:9103): ice-WARNING **: 14:12:46.024: dtls_srtp.vala:155: DTLS handshake timeouted

(dino:9103): ice-WARNING **: 14:12:46.024: dtls_srtp.vala:184: DTLS handshake failed: (unknown error code)

(dino:9103): GLib-GObject-CRITICAL **: 14:12:50.105: g_object_ref: assertion 'G_IS_OBJECT (object)' failed

licaon-kter commented 3 years ago

@inuit-lang can you please execute Dino with env G_MESSAGES_DEBUG=ice,rtp ./build/dino, once for Dino -> Siskin and once for Siskin -> Dino and show the outputs?

inuit-lang commented 3 years ago

Dino to Siskin: Call failed:

(dino:3549): rtp-DEBUG: 17:20:35.551: plugin.vala:137: echo-probe changed state to GST_STATE_READY
(dino:3549): rtp-DEBUG: 17:20:35.551: plugin.vala:137: rtpbin0 changed state to GST_STATE_READY
(dino:3549): rtp-DEBUG: 17:20:35.551: plugin.vala:137: pipeline0 changed state to GST_STATE_READY
(dino:3549): rtp-DEBUG: 17:20:35.551: plugin.vala:137: echo-probe changed state to GST_STATE_PAUSED
(dino:3549): rtp-DEBUG: 17:20:35.551: plugin.vala:137: rtpbin0 changed state to GST_STATE_PAUSED
(dino:3549): rtp-DEBUG: 17:20:35.551: plugin.vala:137: pipeline0 changed state to GST_STATE_PAUSED
(dino:3549): rtp-DEBUG: 17:20:35.551: plugin.vala:155: New clock.
(dino:3549): rtp-DEBUG: 17:20:35.552: plugin.vala:137: echo-probe changed state to GST_STATE_PLAYING
(dino:3549): rtp-DEBUG: 17:20:35.552: plugin.vala:137: rtpbin0 changed state to GST_STATE_PLAYING
(dino:3549): rtp-DEBUG: 17:20:35.552: plugin.vala:137: pipeline0 changed state to GST_STATE_PLAYING
(dino:3549): ice-DEBUG: 17:20:36.357: plugin.vala:33: Server offers STUN server: 0.0.0.0:3478, resolved to 0.0.0.0
(dino:3549): ice-DEBUG: 17:20:36.357: plugin.vala:37: Server offers TURN server: 0.0.0.0:3478, resolved to 0.0.0.0
(dino:3549): ice-DEBUG: 17:20:36.402: plugin.vala:33: Server offers STUN server: stun.server.org:3478, resolved to **.**.***.***
(dino:3549): ice-DEBUG: 17:20:36.437: plugin.vala:37: Server offers TURN server: turn.server.org:3478, resolved to **.**.***.***
(dino:3549): ice-DEBUG: 17:20:36.669: plugin.vala:33: Server offers STUN server: turn.jabberfr.org:3478, resolved to 178.170.40.189
(dino:3549): ice-DEBUG: 17:20:36.674: plugin.vala:37: Server offers TURN server: turn.jabberfr.org:3478, resolved to 178.170.40.189
(dino:3549): rtp-DEBUG: 17:21:01.964: module.vala:92: using opusenc to encode opus
(dino:3549): rtp-DEBUG: 17:21:02.012: module.vala:106: using opusdec to decode opus
(dino:3549): rtp-DEBUG: 17:21:02.078: module.vala:92: using speexenc to encode speex
(dino:3549): rtp-DEBUG: 17:21:02.142: module.vala:106: using speexdec to decode speex
(dino:3549): rtp-DEBUG: 17:21:02.277: module.vala:92: using mulawenc to encode pcmu
(dino:3549): rtp-DEBUG: 17:21:02.413: module.vala:106: using mulawdec to decode pcmu
(dino:3549): rtp-DEBUG: 17:21:02.549: module.vala:92: using alawenc to encode pcma
(dino:3549): rtp-DEBUG: 17:21:02.684: module.vala:106: using alawdec to decode pcma
(dino:3549): ice-DEBUG: 17:21:02.711: module.vala:26: STUN server for libnice 178.170.40.189 3478
(dino:3549): ice-DEBUG: 17:21:02.751: dtls_srtp.vala:129: Setting up DTLS connection. We're DINO_PLUGINS_ICE_DTLS_SRTP_MODE_SERVER
(dino:3549): ice-DEBUG: 17:21:02.753: transport_parameters.vala:97: TURN info (component 1) 178.170.40.189:3478
(dino:3549): ice-DEBUG: 17:21:02.753: transport_parameters.vala:97: TURN info (component 2) 178.170.40.189:3478
(dino:3549): ice-DEBUG: 17:21:02.754: transport_parameters.vala:224: stream 1 component 1 state changed to gathering
(dino:3549): ice-DEBUG: 17:21:02.754: transport_parameters.vala:224: stream 1 component 2 state changed to gathering
(dino:3549): ice-DEBUG: 17:21:02.955: transport_parameters.vala:125: on_candidate_gathering_done in 1
(dino:3549): ice-DEBUG: 17:21:02.955: transport_parameters.vala:132: Local candidate summary: a=candidate:2 1 UDP 2013266430 2a01:cb1c:555:3200:1496:bcc3:185c:dcf6 34138 typ host
(dino:3549): ice-DEBUG: 17:21:02.955: transport_parameters.vala:132: Local candidate summary: a=candidate:3 1 UDP 2013266429 192.168.1.13 47594 typ host
(dino:3549): ice-DEBUG: 17:21:02.955: transport_parameters.vala:132: Local candidate summary: a=candidate:4 1 UDP 1677722111 **.**.***.*** 47594 typ srflx raddr 192.168.1.13 rport 47594
(dino:3549): ice-DEBUG: 17:21:02.955: transport_parameters.vala:132: Local candidate summary: a=candidate:5 1 UDP 503316991 178.170.40.189 54860 typ relay raddr 192.168.1.13 rport 47594
(dino:3549): ice-DEBUG: 17:21:02.955: transport_parameters.vala:132: Local candidate summary: a=candidate:2 2 UDP 2013266429 2a01:cb1c:555:3200:1496:bcc3:185c:dcf6 49775 typ host
(dino:3549): ice-DEBUG: 17:21:02.955: transport_parameters.vala:132: Local candidate summary: a=candidate:3 2 UDP 2013266428 192.168.1.13 45195 typ host
(dino:3549): ice-DEBUG: 17:21:02.955: transport_parameters.vala:132: Local candidate summary: a=candidate:4 2 UDP 1677722110 **.**.***.*** 45195 typ srflx raddr 192.168.1.13 rport 45195
(dino:3549): ice-DEBUG: 17:21:02.956: transport_parameters.vala:132: Local candidate summary: a=candidate:5 2 UDP 503316990 178.170.40.189 50636 typ relay raddr 192.168.1.13 rport 45195

(dino:3549): GLib-GObject-CRITICAL **: 17:21:08.952: g_object_ref: assertion 'G_IS_OBJECT (object)' failed

And then Siskin to Dino (which is much longer): Call successfull:

(dino:3934): rtp-DEBUG: 17:24:07.359: plugin.vala:137: echo-probe changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:07.360: plugin.vala:137: rtpbin0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:07.360: plugin.vala:137: pipeline0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:07.360: plugin.vala:137: echo-probe changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:07.360: plugin.vala:137: rtpbin0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:07.360: plugin.vala:137: pipeline0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:07.360: plugin.vala:155: New clock.
(dino:3934): rtp-DEBUG: 17:24:07.360: plugin.vala:137: echo-probe changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:07.360: plugin.vala:137: rtpbin0 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:07.360: plugin.vala:137: pipeline0 changed state to GST_STATE_PLAYING
(dino:3934): ice-DEBUG: 17:24:08.211: plugin.vala:33: Server offers STUN server: 0.0.0.0:3478, resolved to 0.0.0.0
(dino:3934): ice-DEBUG: 17:24:08.211: plugin.vala:37: Server offers TURN server: 0.0.0.0:3478, resolved to 0.0.0.0
(dino:3934): ice-DEBUG: 17:24:08.252: plugin.vala:33: Server offers STUN server: stun.server.org:3478, resolved to **.**.***.***
(dino:3934): ice-DEBUG: 17:24:08.287: plugin.vala:37: Server offers TURN server: turn.server.org:3478, resolved to **.**.***.***
(dino:3934): ice-DEBUG: 17:24:08.502: plugin.vala:33: Server offers STUN server: turn.jabberfr.org:3478, resolved to 178.170.40.189
(dino:3934): ice-DEBUG: 17:24:08.506: plugin.vala:37: Server offers TURN server: turn.jabberfr.org:3478, resolved to 178.170.40.189
(dino:3934): ice-DEBUG: 17:24:11.579: module.vala:26: STUN server for libnice 178.170.40.189 3478
(dino:3934): ice-DEBUG: 17:24:11.665: transport_parameters.vala:97: TURN info (component 1) 178.170.40.189:3478
(dino:3934): ice-DEBUG: 17:24:11.665: transport_parameters.vala:97: TURN info (component 2) 178.170.40.189:3478
(dino:3934): ice-DEBUG: 17:24:11.667: transport_parameters.vala:224: stream 1 component 1 state changed to gathering
(dino:3934): ice-DEBUG: 17:24:11.667: transport_parameters.vala:224: stream 1 component 2 state changed to gathering
(dino:3934): rtp-DEBUG: 17:24:11.715: module.vala:92: using opusenc to encode opus
(dino:3934): rtp-DEBUG: 17:24:11.760: module.vala:106: using opusdec to decode opus
(dino:3934): ice-DEBUG: 17:24:11.867: transport_parameters.vala:125: on_candidate_gathering_done in 1
(dino:3934): ice-DEBUG: 17:24:11.867: transport_parameters.vala:132: Local candidate summary: a=candidate:2 1 UDP 2013266430 2a01:cb1c:555:3200:1496:bcc3:185c:dcf6 45082 typ host
(dino:3934): ice-DEBUG: 17:24:11.867: transport_parameters.vala:132: Local candidate summary: a=candidate:3 1 UDP 2013266429 192.168.1.13 37741 typ host
(dino:3934): ice-DEBUG: 17:24:11.867: transport_parameters.vala:132: Local candidate summary: a=candidate:4 1 UDP 1677722111 **.**.***.*** 37741 typ srflx raddr 192.168.1.13 rport 37741
(dino:3934): ice-DEBUG: 17:24:11.867: transport_parameters.vala:132: Local candidate summary: a=candidate:5 1 UDP 503316991 178.170.40.189 63447 typ relay raddr 192.168.1.13 rport 37741
(dino:3934): ice-DEBUG: 17:24:11.867: transport_parameters.vala:132: Local candidate summary: a=candidate:2 2 UDP 2013266429 2a01:cb1c:555:3200:1496:bcc3:185c:dcf6 49413 typ host
(dino:3934): ice-DEBUG: 17:24:11.867: transport_parameters.vala:132: Local candidate summary: a=candidate:3 2 UDP 2013266428 192.168.1.13 48368 typ host
(dino:3934): ice-DEBUG: 17:24:11.867: transport_parameters.vala:132: Local candidate summary: a=candidate:4 2 UDP 1677722110 **.**.***.*** 48368 typ srflx raddr 192.168.1.13 rport 48368
(dino:3934): ice-DEBUG: 17:24:11.867: transport_parameters.vala:132: Local candidate summary: a=candidate:5 2 UDP 503316990 178.170.40.189 54045 typ relay raddr 192.168.1.13 rport 48368
(dino:3934): ice-DEBUG: 17:24:12.627: transport_parameters.vala:191: create_transport_connection: 286C67CE-C020-4952-9E15-137E35D7FE5F
(dino:3934): ice-DEBUG: 17:24:12.627: transport_parameters.vala:192: local_credentials: Iaz1 UcGI39p8oXO83E1er4nW5F
(dino:3934): ice-DEBUG: 17:24:12.627: transport_parameters.vala:193: remote_credentials: B9Um oLiB8ntKaYjvf+lbWsScDca9
(dino:3934): ice-DEBUG: 17:24:12.627: transport_parameters.vala:194: expected incoming credentials: Iaz1:B9Um UcGI39p8oXO83E1er4nW5F
(dino:3934): ice-DEBUG: 17:24:12.627: transport_parameters.vala:195: expected outgoing credentials: B9Um:Iaz1 oLiB8ntKaYjvf+lbWsScDca9
(dino:3934): ice-DEBUG: 17:24:12.627: transport_parameters.vala:209: remote candidate: a=candidate:774828525 1 UDP 2122262784 2a01:cb1c:555:3200:85df:f792:7bb4:1e6 61922 typ host
(dino:3934): ice-DEBUG: 17:24:12.627: transport_parameters.vala:209: remote candidate: a=candidate:1493399139 1 UDP 2122194688 192.168.1.12 58875 typ host
(dino:3934): ice-DEBUG: 17:24:12.627: transport_parameters.vala:209: remote candidate: a=candidate:82057994 1 UDP 2122129152 10.12.118.156 59282 typ host
(dino:3934): ice-DEBUG: 17:24:12.627: transport_parameters.vala:224: stream 1 component 1 state changed to connecting
(dino:3934): ice-DEBUG: 17:24:12.627: transport_parameters.vala:213: Initiated component 1 with 3 remote candidates
(dino:3934): ice-DEBUG: 17:24:12.627: transport_parameters.vala:213: Initiated component 2 with 0 remote candidates
(dino:3934): rtp-DEBUG: 17:24:12.627: device.vala:124: Creating device pulsedevice1
(dino:3934): rtp-DEBUG: 17:24:12.629: device.vala:124: Creating device pulsedevice2
(dino:3934): rtp-DEBUG: 17:24:12.631: plugin.vala:90: pad added: send_rtcp_src_0
(dino:3934): rtp-DEBUG: 17:24:12.631: plugin.vala:90: pad added: recv_rtcp_sink_0
(dino:3934): rtp-DEBUG: 17:24:12.631: codec_util.vala:237: Pipeline to encode audio opus: audioconvert name=encode-0-convert ! audioresample name=encode-0-resample ! opusenc audio-type=voice ! rtpopuspay pt=111 name=encode-0-rtp-pay
(dino:3934): rtp-DEBUG: 17:24:12.632: plugin.vala:90: pad added: send_rtp_src_0
(dino:3934): rtp-DEBUG: 17:24:12.632: plugin.vala:103: pad send_rtp_src_0 for stream 0
(dino:3934): rtp-DEBUG: 17:24:12.632: stream.vala:378: Link send_rtp_src_0 to audio send_rtp for 0
(dino:3934): rtp-DEBUG: 17:24:12.632: plugin.vala:90: pad added: send_rtp_sink_0
(dino:3934): rtp-DEBUG: 17:24:12.633: codec_util.vala:214: Pipeline to decode audio opus: rtpopusdepay name=decode-0-rtp-depay ! opusdec name=decode-0-decode ! audioconvert name=decode-0-convert ! audioresample name=decode-0-resample
(dino:3934): rtp-DEBUG: 17:24:12.634: plugin.vala:90: pad added: recv_rtp_sink_0
(dino:3934): rtp-DEBUG: 17:24:12.634: plugin.vala:39: Continue pipe after modifications
(dino:3934): rtp-DEBUG: 17:24:12.658: plugin.vala:137: rtpssrcdemux0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.658: plugin.vala:137: rtpssrcdemux0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.658: plugin.vala:137: rtpssrcdemux0 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.658: plugin.vala:137: rtpsession0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.658: plugin.vala:137: rtpsession0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.658: plugin.vala:137: rtpsession0 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.658: plugin.vala:137: funnel0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.658: plugin.vala:137: funnel0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.658: plugin.vala:137: funnel0 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.658: plugin.vala:137: funnel1 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.658: plugin.vala:137: funnel1 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.658: plugin.vala:137: funnel1 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.658: plugin.vala:137: rtpstorage0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.658: plugin.vala:137: rtpstorage0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.658: plugin.vala:137: rtpstorage0 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.658: plugin.vala:137: rtcp-sink-0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: rtcp-sink-0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: rtcp-sink-0 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: rtp-sink-0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: rtp-sink-0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: rtp-sink-0 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: pulsedevice2 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: pulsedevice2 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: pulsedevice2 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: rtcp-src-0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:145: rtcp-src-0 stream changed status to GST_STREAM_STATUS_TYPE_CREATE
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: rtcp-src-0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: rtcp-src-0 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: encode-0-rtp-pay changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:145: rtcp-src-0 stream changed status to GST_STREAM_STATUS_TYPE_ENTER
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: opusenc2 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: encode-0-resample changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: encode-0-convert changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: encode-0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: encode-0-rtp-pay changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: opusenc2 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: encode-0-resample changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: encode-0-convert changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: encode-0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: encode-0-rtp-pay changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: opusenc2 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: encode-0-resample changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: encode-0-convert changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: encode-0 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: rtp-src-0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:145: rtp-src-0 stream changed status to GST_STREAM_STATUS_TYPE_CREATE
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: rtp-src-0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: rtp-src-0 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: pulsedevice2-caps-filter changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: pulsedevice2-caps-filter changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: pulsedevice2-caps-filter changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: pulsedevice1-tee changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: pulsedevice1-tee changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: pulsedevice1-tee changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: decode-0-resample changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: decode-0-convert changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: decode-0-decode changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: decode-0-rtp-depay changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: decode-0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: decode-0-resample changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: decode-0-convert changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:145: rtp-src-0 stream changed status to GST_STREAM_STATUS_TYPE_ENTER
(dino:3934): rtp-DEBUG: 17:24:12.659: plugin.vala:137: decode-0-decode changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:137: decode-0-rtp-depay changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:137: decode-0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:137: decode-0-resample changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:137: decode-0-convert changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:137: decode-0-decode changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:137: decode-0-rtp-depay changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:137: decode-0 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:137: pulsedevice1-dsp changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:137: pulsedevice1-dsp changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:137: pulsedevice1-dsp changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:137: pulsedevice1-caps-filter changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:137: pulsedevice1-caps-filter changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:137: pulsedevice1-caps-filter changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:137: pulsedevice1 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:145: pulsedevice1 stream changed status to GST_STREAM_STATUS_TYPE_CREATE
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:137: pulsedevice1 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:137: pulsedevice1 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:145: pulsedevice1 stream changed status to GST_STREAM_STATUS_TYPE_ENTER
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:145: pulsedevice1 stream changed status to GST_STREAM_STATUS_TYPE_ENTER
(dino:3934): rtp-DEBUG: 17:24:12.660: plugin.vala:164: Pipe bus message: GST_MESSAGE_LATENCY
(dino:3934): rtp-DEBUG: 17:24:12.667: plugin.vala:164: Pipe bus message: GST_MESSAGE_LATENCY
(dino:3934): ice-DEBUG: 17:24:13.416: transport_parameters.vala:242: initial_binding_request_received
(dino:3934): ice-DEBUG: 17:24:13.416: transport_parameters.vala:247: new_selected_pair_full 1 [a=candidate:3 1 UDP 2013266429 192.168.1.13 37741 typ host, a=candidate:1493399139 1 UDP 2122194688 192.168.1.12 58875 typ host]
(dino:3934): ice-DEBUG: 17:24:13.416: transport_parameters.vala:224: stream 1 component 1 state changed to ready
(dino:3934): ice-DEBUG: 17:24:13.416: dtls_srtp.vala:129: Setting up DTLS connection. We're DINO_PLUGINS_ICE_DTLS_SRTP_MODE_CLIENT
(dino:3934): ice-DEBUG: 17:24:13.418: transport_parameters.vala:224: stream 1 component 1 state changed to ready
(dino:3934): ice-DEBUG: 17:24:17.487: transport_parameters.vala:247: new_selected_pair_full 1 [a=candidate:2 1 UDP 2013266430 2a01:cb1c:555:3200:1496:bcc3:185c:dcf6 45082 typ host, a=candidate:774828525 1 UDP 2122262784 2a01:cb1c:555:3200:85df:f792:7bb4:1e6 61922 typ host]
(dino:3934): ice-DEBUG: 17:24:17.556: transport_parameters.vala:169: on_transport_info from user@server.org/iPhone
(dino:3934): ice-DEBUG: 17:24:17.556: transport_parameters.vala:186: Updated to 4 remote candidates for candidate 1 via transport info
(dino:3934): ice-DEBUG: 17:24:17.556: transport_parameters.vala:186: Updated to 0 remote candidates for candidate 2 via transport info
(dino:3934): ice-DEBUG: 17:24:17.588: transport_parameters.vala:169: on_transport_info from user@server.org/iPhone
(dino:3934): ice-DEBUG: 17:24:17.588: transport_parameters.vala:186: Updated to 5 remote candidates for candidate 1 via transport info
(dino:3934): ice-DEBUG: 17:24:17.588: transport_parameters.vala:186: Updated to 0 remote candidates for candidate 2 via transport info
(dino:3934): ice-DEBUG: 17:24:17.589: transport_parameters.vala:169: on_transport_info from user@server.org/iPhone
(dino:3934): ice-DEBUG: 17:24:17.589: transport_parameters.vala:186: Updated to 6 remote candidates for candidate 1 via transport info
(dino:3934): ice-DEBUG: 17:24:17.589: transport_parameters.vala:186: Updated to 0 remote candidates for candidate 2 via transport info
(dino:3934): ice-DEBUG: 17:24:17.591: transport_parameters.vala:169: on_transport_info from user@server.org/iPhone
(dino:3934): ice-DEBUG: 17:24:17.591: transport_parameters.vala:224: stream 1 component 1 state changed to connected
(dino:3934): ice-DEBUG: 17:24:17.591: transport_parameters.vala:186: Updated to 7 remote candidates for candidate 1 via transport info
(dino:3934): ice-DEBUG: 17:24:17.591: transport_parameters.vala:186: Updated to 0 remote candidates for candidate 2 via transport info
(dino:3934): ice-DEBUG: 17:24:17.593: transport_parameters.vala:169: on_transport_info from user@server.org/iPhone
(dino:3934): ice-DEBUG: 17:24:17.593: transport_parameters.vala:186: Updated to 8 remote candidates for candidate 1 via transport info
(dino:3934): ice-DEBUG: 17:24:17.593: transport_parameters.vala:186: Updated to 0 remote candidates for candidate 2 via transport info
(dino:3934): ice-DEBUG: 17:24:17.700: transport_parameters.vala:224: stream 1 component 1 state changed to ready
(dino:3934): ice-DEBUG: 17:24:17.810: dtls_srtp.vala:195: Finished DTLS connection. We're DINO_PLUGINS_ICE_DTLS_SRTP_MODE_CLIENT
(dino:3934): rtp-DEBUG: 17:24:18.872: stream.vala:355: RTCP is ready, resending rtcp: true
(dino:3934): rtp-DEBUG: 17:24:18.899: plugin.vala:137: rtpptdemux0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:18.900: plugin.vala:137: rtpptdemux0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:18.900: plugin.vala:137: rtpptdemux0 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:18.900: plugin.vala:137: rtpjitterbuffer0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:18.900: plugin.vala:145: rtpjitterbuffer0 stream changed status to GST_STREAM_STATUS_TYPE_CREATE
(dino:3934): rtp-DEBUG: 17:24:18.900: plugin.vala:137: rtpjitterbuffer0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:18.900: plugin.vala:137: rtpjitterbuffer0 changed state to GST_STATE_PLAYING
(dino:3934): rtp-DEBUG: 17:24:18.900: plugin.vala:145: rtpjitterbuffer0 stream changed status to GST_STREAM_STATUS_TYPE_ENTER
(dino:3934): rtp-DEBUG: 17:24:18.977: plugin.vala:90: pad added: recv_rtp_src_0_495265068_111
(dino:3934): rtp-DEBUG: 17:24:18.977: stream.vala:359: New ssrc 495265068 with pad recv_rtp_src_0_495265068_111
(dino:3934): rtp-DEBUG: 17:24:18.977: stream.vala:368: Link recv_rtp_src_0_495265068_111 to audio decode for 0
(dino:3934): rtp-DEBUG: 17:24:18.977: plugin.vala:39: Continue pipe after modifications
(dino:3934): rtp-DEBUG: 17:24:19.018: plugin.vala:164: Pipe bus message: GST_MESSAGE_LATENCY
(dino:3934): rtp-DEBUG: 17:24:19.883: device.vala:232: Destroyed device pulsedevice1
(dino:3934): rtp-DEBUG: 17:24:19.888: device.vala:232: Destroyed device pulsedevice2

(dino:3934): GLib-GObject-CRITICAL **: 17:24:19.897: g_object_ref: assertion 'G_IS_OBJECT (object)' failed
(dino:3934): rtp-DEBUG: 17:24:19.897: plugin.vala:137: pulsedevice1 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.897: plugin.vala:145: pulsedevice1 stream changed status to GST_STREAM_STATUS_TYPE_LEAVE
(dino:3934): rtp-DEBUG: 17:24:19.897: plugin.vala:145: pulsedevice1 stream changed status to GST_STREAM_STATUS_TYPE_LEAVE
(dino:3934): rtp-DEBUG: 17:24:19.897: plugin.vala:137: pulsedevice1 changed state to GST_STATE_READY

(dino:3934): GStreamer-CRITICAL **: 17:24:19.898: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
(dino:3934): rtp-DEBUG: 17:24:19.898: plugin.vala:137: pulsedevice1-caps-filter changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.898: plugin.vala:137: pulsedevice1-caps-filter changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.898: plugin.vala:137: pulsedevice1-caps-filter changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.898: plugin.vala:137: pulsedevice1-dsp changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.898: plugin.vala:137: pulsedevice1-dsp changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.898: plugin.vala:137: pulsedevice1-dsp changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.898: plugin.vala:137: pulsedevice1-tee changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.898: plugin.vala:137: pulsedevice1-tee changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.898: plugin.vala:137: pulsedevice1-tee changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.898: plugin.vala:137: encode-0-rtp-pay changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.898: plugin.vala:137: opusenc2 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.898: plugin.vala:137: encode-0-resample changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.898: plugin.vala:137: encode-0-convert changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.898: plugin.vala:137: encode-0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.898: plugin.vala:137: encode-0-rtp-pay changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.899: plugin.vala:137: opusenc2 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.899: plugin.vala:137: encode-0-resample changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.899: plugin.vala:137: encode-0-convert changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.899: plugin.vala:137: encode-0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.899: plugin.vala:137: encode-0-rtp-pay changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.899: plugin.vala:137: opusenc2 changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.899: plugin.vala:137: encode-0-resample changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.899: plugin.vala:137: encode-0-convert changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.899: plugin.vala:137: encode-0 changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.899: plugin.vala:137: rtp-sink-0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.899: plugin.vala:137: rtp-sink-0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.899: plugin.vala:137: rtp-sink-0 changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.899: plugin.vala:137: rtp-src-0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.899: plugin.vala:145: rtp-src-0 stream changed status to GST_STREAM_STATUS_TYPE_LEAVE
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: rtp-src-0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: rtp-src-0 changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: decode-0-resample changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: decode-0-convert changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: decode-0-decode changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: decode-0-rtp-depay changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: decode-0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: decode-0-resample changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: decode-0-convert changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: decode-0-decode changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: decode-0-rtp-depay changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: decode-0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: decode-0-resample changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: decode-0-convert changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: decode-0-decode changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: decode-0-rtp-depay changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.900: plugin.vala:137: decode-0 changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.901: plugin.vala:137: pulsedevice2-caps-filter changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.901: plugin.vala:137: pulsedevice2-caps-filter changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.901: plugin.vala:137: pulsedevice2-caps-filter changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.901: plugin.vala:137: pulsedevice2 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.901: plugin.vala:137: pulsedevice2 changed state to GST_STATE_READY

(dino:3934): GStreamer-CRITICAL **: 17:24:19.901: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
(dino:3934): rtp-DEBUG: 17:24:19.901: plugin.vala:137: rtcp-src-0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.901: plugin.vala:145: rtcp-src-0 stream changed status to GST_STREAM_STATUS_TYPE_LEAVE
(dino:3934): rtp-DEBUG: 17:24:19.901: plugin.vala:137: rtcp-src-0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.901: plugin.vala:137: rtcp-src-0 changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.901: plugin.vala:137: rtcp-sink-0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.901: plugin.vala:137: rtcp-sink-0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.901: plugin.vala:137: rtcp-sink-0 changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.902: plugin.vala:137: rtpssrcdemux0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.902: plugin.vala:137: rtpssrcdemux0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.902: plugin.vala:137: rtpssrcdemux0 changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.902: plugin.vala:137: rtpsession0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.902: plugin.vala:137: rtpsession0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.902: plugin.vala:137: rtpsession0 changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.902: plugin.vala:137: rtpptdemux0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.902: plugin.vala:137: rtpptdemux0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.902: plugin.vala:137: rtpptdemux0 changed state to GST_STATE_NULL
(dino:3934): rtp-DEBUG: 17:24:19.902: plugin.vala:137: rtpjitterbuffer0 changed state to GST_STATE_PAUSED
(dino:3934): rtp-DEBUG: 17:24:19.902: plugin.vala:145: rtpjitterbuffer0 stream changed status to GST_STREAM_STATUS_TYPE_LEAVE
(dino:3934): rtp-DEBUG: 17:24:19.902: plugin.vala:137: rtpjitterbuffer0 changed state to GST_STATE_READY
(dino:3934): rtp-DEBUG: 17:24:19.902: plugin.vala:137: rtpjitterbuffer0 changed state to GST_STATE_NULL

fiaxh commented 2 years ago

I looked into why calls from Dino to Siskin won't establish. This is due to three issues in Siskin: #167, #168 and #169. When solving these issues, both audio and video calls from Dino to Siskin establish fine.

In calls from Dino to Siskin, both audio and video work well. In calls from Siskin to Dino, I noticed that the video from Dino freezes in Siskin after a few seconds (Dino keeps sending video).

hantu85 commented 2 years ago

@fiaxh Thank you for your analysis. I'll look into that shortly and try to fix those issues in version 7.1

In calls from Siskin to Dino, I noticed that the video from Dino freezes in Siskin after a few seconds (Dino keeps sending video).

Was that after all the changes to fix issues which you reported were applied on Dino side?

I'm asking about it, as I had some issues with frozen stream between Siskin and Conversations and adding group was fixing the issue AFAIR.

fiaxh commented 2 years ago

Was that after all the changes to fix issues which you reported were applied on Dino side?

No, the video freeze happens regardless of whether these issues are fixed/worked-around or not. Those three issues are only problematic for Dino->Siskin. However the freezing video only occurs for Siskin->Dino. The issues/changes don't have any effect on that call direction.

I'm asking about it, as I had some issues with frozen stream between Siskin and Conversations and adding group was fixing the issue AFAIR.

Since Dino->Siskin works well without a (meaningful) group, this can't be a general issue. Either way, adding a group to your outgoing session-initiate is independent of enforcing it on incoming session-initiate. Since Conversations parses and sends the group node anyways, not enforcing the group node shouldn't cause any additional issues (but it would only solve some).

tigase / siskin-im

Improve call compatibility with Dino #110