search

QuickBlox / quickblox-ios-sdk

QuickBlox iOS SDK for messaging and video calling
https://quickblox.com/developers/IOS
MIT License
397 stars 358 forks source link

Users not receiving video on many occasions #606

Closed shalem93 closed 7 years ago

shalem93 commented 8 years ago

Help avoid duplicate issue reports, check existing issues

Environment details iOS version, Quickblox iOS SDK version, QuickbloxWebRTC SDK version (optional) iOS 9 + 10, QB WebRTC 2.2

Did this work before? No

Expected behavior Video being received every time

Actual behavior Video being received by only one user in about 50 % of the sessions

Logs Attach full logs that will describe your problem (please, use github gist)

Steps to reproduce the behavior When getting into a video chat, One user doesn't receive video very frequently. This is generally when the devices are different, (5 and 6 being paired), (5 and 6+). It has happened with two 5's, or two 6's so that is not directly the issue though.

Any others comments? The logs are a standard

ghost commented 8 years ago

Could you please enable stats report and send us this information?

[QBRTCConfig setStatsReportTimeInterval:1.f]; - method for enabling stats report.

In your controller you should adopt QBRTCClientDelegate and use this delegate method.

shalem93 commented 8 years ago 
2016-10-03 18:00:37.451717 XpandU[1760:3340247] [DYMTLInitPlatform] platform initialization successful
2016-10-03 18:00:40.946990 XpandU[1760:3340218] rtc::Initialize SSL... (v2.2, r11951)
2016-10-03 18:00:40:945 XpandU[1760:3340040] rtc::Initialize SSL... (v2.2, r11951)
2016-10-03 18:00:41.127327 XpandU[1760:3340040] [MC] System group container for systemgroup.com.apple.configurationprofiles path is /private/var/containers/Shared/SystemGroup/systemgroup.com.apple.configurationprofiles
2016-10-03 18:00:41.135171 XpandU[1760:3340040] [MC] Reading from public effective user settings.
2016-10-03 18:00:41.224992 XpandU[1760:3340040] Configuring the default app.
2016-10-03 18:00:41.276: <FIRInstanceID/WARNING> FIRInstanceID AppDelegate proxy enabled, will swizzle app delegate remote notification handlers. To disable add "FirebaseAppDelegateProxyEnabled" to your Info.plist and set it to NO
2016-10-03 18:00:41.305953 XpandU[1760:3340218] <FIRAnalytics/INFO> Firebase Analytics v.3301000 started
2016-10-03 18:00:41.310 XpandU[1760:] <FIRAnalytics/INFO> Firebase Analytics v.3301000 started
2016-10-03 18:00:41.311001 XpandU[1760:3340218] <FIRAnalytics/INFO> To enable debug logging set the following application argument: -FIRAnalyticsDebugEnabled (see http://goo.gl/Y0Yjwu)
2016-10-03 18:00:41.313 XpandU[1760:] <FIRAnalytics/INFO> To enable debug logging set the following application argument: -FIRAnalyticsDebugEnabled (see http://goo.gl/Y0Yjwu)
2016-10-03 18:00:41.315903 XpandU[1760:3340040] CONNECTION LOG getUniversities
2016-10-03 18:00:41.341453 XpandU[1760:3340216] Heap: Enabling Event Visualizer. Connecting to Heap server.
2016-10-03 18:00:41.390919 XpandU[1760:3340302] <FIRAnalytics/INFO> Successfully created Firebase Analytics App Delegate Proxy automatically. To disable the proxy, set the flag FirebaseAppDelegateProxyEnabled to NO in the Info.plist
2016-10-03 18:00:41.391 XpandU[1760:] <FIRAnalytics/INFO> Successfully created Firebase Analytics App Delegate Proxy automatically. To disable the proxy, set the flag FirebaseAppDelegateProxyEnabled to NO in the Info.plist
2016-10-03 18:00:41.598213 XpandU[1760:3340219] <FIRAnalytics/INFO> Firebase Analytics enabled
2016-10-03 18:00:41.598 XpandU[1760:] <FIRAnalytics/INFO> Firebase Analytics enabled
2016-10-03 18:00:41.944129 XpandU[1760:3340040] deep link data: {
    "+clicked_branch_link" = 0;
    "+is_first_session" = 0;
}
2016-10-03 18:00:42.523571 XpandU[1760:3340040] logInWithChatWithUser
2016-10-03 18:00:42.845377 XpandU[1760:3340040] Successfull response!
2016-10-03 18:00:44.763471 XpandU[1760:3340040] CONNECTION LOG getXWXonnections
2016-10-03 18:00:44.771062 XpandU[1760:3340040] CONNECTION LOG getConnectRequests
2016-10-03 18:00:44.779559 XpandU[1760:3340311] rtc::<QBRTCClient 0x1740494b0, delegates[0]> did connect.
2016-10-03 18:00:44:762 XpandU[1760:3340040] rtc::<QBRTCClient 0x1740494b0, delegates[0]> did connect.
2016-10-03 18:00:45.562840 XpandU[1760:3340040] CONNECTION LOG getConnectPendding
2016-10-03 18:00:45.700072 XpandU[1760:3340040] getConnectionsFromMeToTheOtherPerson (
    "
  [QBUUser]:
  ID:6365046
  created at:2015-10-25 21:52:15 +0000
  updated at:2016-10-03 02:35:01 +0000
  externalUserID:33650460
  blobID:0
  facebookID:(null)
  twitterID:(null)
  twitterDigitsID:(null)
  full name:Erik DeMar
  email:edemar@asu.edu
  login:edemar@asu.edu
  phone:000000
  tags:(\n    Finance,\n    asu\n)
  lastRequestAt:2016-10-03 02:25:54 +0000
  customData:Finance,4
  website:https://api.quickblox.com/blobs/3110130/download",
    "
  [QBUUser]:
  ID:11259893
  created at:2016-03-30 04:53:14 +0000
  updated at:2016-09-19 05:26:29 +0000
  externalUserID:112598930
  blobID:0
  facebookID:(null)
  twitterID:(null)
  twitterDigitsID:(null)
  full name:Jacob
  email:jshuch@asu.edu
  login:(null)
  phone:000000
  tags:(\n    Economics\n)
  lastRequestAt:2016-09-19 05:26:25 +0000
  customData:Economics,2
  website:https://api.quickblox.com/blobs/3348e8569e384b88accebde44673d54e00.json",
    "
  [QBUUser]:
  ID:11305992
  created at:2016-03-31 20:26:07 +0000
  updated at:2016-09-28 04:59:2
2016-10-03 18:00:45.931240 XpandU[1760:3340040] CONNECTION LOG refreshTopicParticipants
2016-10-03 18:00:45.933552 XpandU[1760:3340040] CONNECTION LOG getUniversities
2016-10-03 18:00:46.603005 XpandU[1760:3340040] Just checking OUT THIS STALLERRRR
2016-10-03 18:00:46.764541 XpandU[1760:3340040] [Warning] WARNING: A Gesture recognizer (<UITapGestureRecognizer: 0x1741a54e0; state = Possible; view = <UIView 0x11be62af0>>) was setup in a storyboard/xib to be added to more than one view (-><QBRTCRemoteVideoView: 0x11be62c90; frame = (0 0; 1000 1000); autoresize = RM+BM; gestureRecognizers = <NSArray: 0x1742480a0>; layer = <CALayer: 0x174234b20>>) at a time, this was never allowed, and is now enforced. Beginning with iOS 9.0 it will be put in the first view it is loaded into.
2016-10-03 18:00:46.768226 XpandU[1760:3340040] THE MOST EPIC TEST OF ALL!!!!!!
2016-10-03 18:00:46.769411 XpandU[1760:3340040] Bool : NO
2016-10-03 18:00:46.866318 XpandU[1760:3340040] Headphone Out
2016-10-03 18:00:46.868470 XpandU[1760:3340040] Headphone Out
2016-10-03 18:00:46.868545 XpandU[1760:3340040] SoundRoute Speaker PLZ
2016-10-03 18:00:46.869689 XpandU[1760:3340263] rtc::Set override Speaker
2016-10-03 18:00:46:869 XpandU[1760:3340219] rtc::Set override Speaker
2016-10-03 18:00:46.883529 XpandU[1760:3340302] rtc::Current camera position: Front
2016-10-03 18:00:46:883 XpandU[1760:3340040] rtc::Current camera position: Front
2016-10-03 18:00:46.914603 XpandU[1760:3340263] rtc::Video capturer initialized: <XUCameraCapture 0x1741058e0> state: Idle
2016-10-03 18:00:46:914 XpandU[1760:3340040] rtc::Video capturer initialized: <XUCameraCapture 0x1741058e0> state: Idle
2016-10-03 18:00:46.915416 XpandU[1760:3340263] rtc::Initialize SSL... (v2.2, r11951)
2016-10-03 18:00:46.916168 XpandU[1760:3340040] SUB COUNT == 0
2016-10-03 18:00:46.916240 XpandU[1760:3340040] 2 THE MOST EPIC TEST OF ALL!!!!!!
2016-10-03 18:00:46.916268 XpandU[1760:3340040] CONNECTION LOG getXonnectRequests
2016-10-03 18:00:46:914 XpandU[1760:3340040] rtc::Initialize SSL... (v2.2, r11951)
2016-10-03 18:00:46.917747 XpandU[1760:3340263] rtc::Set override None
2016-10-03 18:00:46:915 XpandU[1760:3340302] rtc::Set override None
2016-10-03 18:00:46.920761 XpandU[1760:3340301] rtc::Start capture session.
2016-10-03 18:00:46:915 XpandU[1760:3340302] rtc::Start capture session.
2016-10-03 18:00:46.926860 XpandU[1760:3340301] rtc::Best device format is: <AVCaptureDeviceFormat: 0x170206090 'vide'/'420f'  640x 480, { 1- 30 fps}, fov:55.200, max zoom:60.00 (upscales @2.00), ISO:47.0-1504.0, SS:0.000031-1.000000>
2016-10-03 18:00:46:917 XpandU[1760:3340302] rtc::Best device format is: <AVCaptureDeviceFormat: 0x170206090 'vide'/'420f'  640x 480, { 1- 30 fps}, fov:55.200, max zoom:60.00 (upscales @2.00), ISO:47.0-1504.0, SS:0.000031-1.000000>
2016-10-03 18:00:46.964697 XpandU[1760:3340317] VIDCHATController startSession
2016-10-03 18:00:46.973828 XpandU[1760:3340317] rtc::HDR enabled NO
2016-10-03 18:00:46:921 XpandU[1760:3340302] rtc::HDR enabled NO
2016-10-03 18:00:47.241015 XpandU[1760:3340040] getConnectionsFromTheOtherPersonToMe (
    "
  [QBUUser]:
  ID:11348510
  created at:2016-04-02 09:32:51 +0000
  updated at:2016-09-22 09:42:47 +0000
  externalUserID:313485100
  blobID:0
  facebookID:(null)
  twitterID:(null)
  twitterDigitsID:(null)
  full name:Miroslav Kutak
  email:mk@asu.edu
  login:mk@asu.edu
  phone:00000001
  tags:(\n    software\n)
  lastRequestAt:2016-09-22 09:42:09 +0000
  customData:0,1
  website:https://api.quickblox.com/blobs/64fc0d974a314ecc90efa52697a647b300.json",
    "
  [QBUUser]:
  ID:12432988
  created at:2016-05-08 01:37:07 +0000
  updated at:2016-09-24 00:43:53 +0000
  externalUserID:324329880
  blobID:0
  facebookID:(null)
  twitterID:(null)
  twitterDigitsID:(null)
  full name:Eric Lustig
  email:eml5266@psu.edu
  login:eml5266@psu.edu
  phone:00000001
  tags:(null)
  lastRequestAt:2016-09-24 00:41:29 +0000
  customData:EBF,2
  website:https://api.quickblox.com/blobs/0bd772984212437bb42fdcbd273cf3ac00.json",
    "
  [QBUUser]:
  ID:12433230
  created at:2016-05-08 01:49:07 +0000
  updated at:2016-05-11
2016-10-03 18:00:47.281367 XpandU[1760:3340040] DIDAPPEARSKI
2016-10-03 18:00:47.287712 XpandU[1760:3340040] 33 THE MOST EPIC TEST OF ALL!!!!!!
2016-10-03 18:00:47.287797 XpandU[1760:3340040] VideoChatRoom has APPeared
2016-10-03 18:00:47.287978 XpandU[1760:3340367] rtc::<QBRTCClient 0x1740494b0, delegates[1]> added <VideoChatRoomViewController: 0x11c0ff000> to list of delegates
2016-10-03 18:00:47:287 XpandU[1760:3340040] rtc::<QBRTCClient 0x1740494b0, delegates[1]> added <VideoChatRoomViewController: 0x11c0ff000> to list of delegates
2016-10-03 18:00:47.315507 XpandU[1760:3340040] Change orientation
2016-10-03 18:00:47.398626 XpandU[1760:3340219] rtc::Capture session started <AVCaptureSession: 0x170205fe0 [AVCaptureSessionPresetInputPriority]>
    <AVCaptureDeviceInput: 0x174427480 [Front Camera]> -> <AVCaptureVideoPreviewLayer: 0x174426440>
    <AVCaptureDeviceInput: 0x174427480 [Front Camera]> -> <AVCaptureVideoDataOutput: 0x174428c60>
2016-10-03 18:00:47:398 XpandU[1760:3340040] rtc::Capture session started <AVCaptureSession: 0x170205fe0 [AVCaptureSessionPresetInputPriority]>
    <AVCaptureDeviceInput: 0x174427480 [Front Camera]> -> <AVCaptureVideoPreviewLayer: 0x174426440>
    <AVCaptureDeviceInput: 0x174427480 [Front Camera]> -> <AVCaptureVideoDataOutput: 0x174428c60>
2016-10-03 18:00:47.404894 XpandU[1760:3340263] Response String: {"status":"error","code":214,"name":"List_AlreadySubscribed","error":"shalem@asu.edu is already subscribed to the list."}
2016-10-03 18:00:47.607640 XpandU[1760:3340040] chatDialog.unreadMessagesCount 0
2016-10-03 18:00:47.621275 XpandU[1760:3340040] chatDialog.unreadMessagesCount 0
2016-10-03 18:00:47.632684 XpandU[1760:3340040] chatDialog.unreadMessagesCount 0
2016-10-03 18:00:47.644444 XpandU[1760:3340040] chatDialog.unreadMessagesCount 0
2016-10-03 18:00:47.653329 XpandU[1760:3340040] chatDialog.unreadMessagesCount 0
2016-10-03 18:00:47.663569 XpandU[1760:3340040] chatDialog.unreadMessagesCount 0
2016-10-03 18:00:49.254671 XpandU[1760:3340040] Change orientation
2016-10-03 18:00:51.859747 XpandU[1760:3340040] SUB COUNT == 0
2016-10-03 18:00:51.859863 XpandU[1760:3340040] 2 THE MOST EPIC TEST OF ALL!!!!!!
2016-10-03 18:00:51.859903 XpandU[1760:3340040] CONNECTION LOG getXonnectRequests
2016-10-03 18:00:56.756605 XpandU[1760:3340040] 1
2016-10-03 18:00:56.762083 XpandU[1760:3340040] Login Bool : NO
2016-10-03 18:00:56.791272 XpandU[1760:3340040] Multiple gravity behavior per animator is undefined and may assert in the future
2016-10-03 18:00:56.793910 XpandU[1760:3340040] Multiple gravity behavior per animator is undefined and may assert in the future
2016-10-03 18:00:57.456947 XpandU[1760:3340040] You 12074064 have logged into Topic 1 successfully
2016-10-03 18:00:57.457942 XpandU[1760:3340040] state changed from    0   1
2016-10-03 18:00:57.843711 XpandU[1760:3340040] Set External User ID 320740640 * 10 SUCCESS
2016-10-03 18:00:57.896156 XpandU[1760:3340040] getCandidates topic: 1  (
)
2016-10-03 18:00:57.896343 XpandU[1760:3340040] arrAlreadyContacted  (
)
2016-10-03 18:00:57.896415 XpandU[1760:3340040] getCandidates  (
)
2016-10-03 18:00:57.896480 XpandU[1760:3340040] BVP CHECK OFF
2016-10-03 18:01:05.581045 XpandU[1760:3340040] Change orientation
2016-10-03 18:01:07.332922 XpandU[1760:3340293] AVAudioSessionRouteChangeReasonNewDeviceAvailable
2016-10-03 18:01:07.333014 XpandU[1760:3340293] Headphone/Line plugged in
2016-10-03 18:01:07.334175 XpandU[1760:3340367] rtc::audioSessionDidChangeRoute <RTCAudioSession: 0x17008aaf0> reason 1
2016-10-03 18:01:07:332 XpandU[1760:3340293] rtc::audioSessionDidChangeRoute <RTCAudioSession: 0x17008aaf0> reason 1
2016-10-03 18:01:07.988065 XpandU[1760:3340219] rtc::audioSessionDidChangeRoute <RTCAudioSession: 0x17008aaf0> reason 8
2016-10-03 18:01:07:987 XpandU[1760:3340293] rtc::audioSessionDidChangeRoute <RTCAudioSession: 0x17008aaf0> reason 8
2016-10-03 18:01:08.315535 XpandU[1760:3340040] Change orientation
2016-10-03 18:01:21.550271 XpandU[1760:3340417] rtc::Did receive signal: call from: 11872460
2016-10-03 18:01:21:549 XpandU[1760:3340219] rtc::Did receive signal: call from: 11872460
2016-10-03 18:01:21.555943 XpandU[1760:3340417] rtc::Started timer: session answer time out, background task id 2 ...
2016-10-03 18:01:21:555 XpandU[1760:3340219] rtc::Started timer: session answer time out, background task id 2 ...
2016-10-03 18:01:21.560248 XpandU[1760:3340040] >>>>>>>>>>>>>>>>>>>>>>>>>>>>> NEW SESSION
2016-10-03 18:01:21.566896 XpandU[1760:3340040] state changed from    1   3
2016-10-03 18:01:21.567582 XpandU[1760:3340417] rtc::<QBRTCSession: 0x1740d7990, ID: 0C1212B1-5567-4468-8913-36D479DEEEBA, initiatorID: 11872460, opponentsIDs: 12074064, conferenceType: video(1), connections: 0> created new <Peer:0x1700f4400, UID:11872460, r:Answer>
2016-10-03 18:01:21:556 XpandU[1760:3340219] rtc::<QBRTCSession: 0x1740d7990, ID: 0C1212B1-5567-4468-8913-36D479DEEEBA, initiatorID: 11872460, opponentsIDs: 12074064, conferenceType: video(1), connections: 0> created new <Peer:0x1700f4400, UID:11872460, r:Answer>
2016-10-03 18:01:21.571804 XpandU[1760:3340537] rtc::<QBRTCClient 0x1740494b0, delegates[1]> created new <QBRTCSession: 0x1740d7990, ID: 0C1212B1-5567-4468-8913-36D479DEEEBA, initiatorID: 11872460, opponentsIDs: 12074064, conferenceType: video(1), connections: 1>
2016-10-03 18:01:21:557 XpandU[1760:3340219] rtc::<QBRTCClient 0x1740494b0, delegates[1]> created new <QBRTCSession: 0x1740d7990, ID: 0C1212B1-5567-4468-8913-36D479DEEEBA, initiatorID: 11872460, opponentsIDs: 12074064, conferenceType: video(1), connections: 1>
2016-10-03 18:01:21.607275 XpandU[1760:3340539] rtc::Invalidated timer:session answer time out, background task id 2 ...
2016-10-03 18:01:21:562 XpandU[1760:3340040] rtc::Invalidated timer:session answer time out, background task id 2 ...
2016-10-03 18:01:21.608401 XpandU[1760:3340537] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued Remote SDP
2016-10-03 18:01:21:583 XpandU[1760:3340219] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued Remote SDP
2016-10-03 18:01:21.609229 XpandU[1760:3340539] rtc::Create audio track: <RTCAudioTrack: 0x17424cbd0>
2016-10-03 18:01:21:604 XpandU[1760:3340219] rtc::Create audio track: <RTCAudioTrack: 0x17424cbd0>
2016-10-03 18:01:21.708749 XpandU[1760:3340538] rtc::Started rtc video capturer
2016-10-03 18:01:21:706 XpandU[1760:3340288] rtc::Started rtc video capturer
2016-10-03 18:01:21.719017 XpandU[1760:3340538] rtc::Create video track: <RTCVideoTrack: 0x17466d9c0>
2016-10-03 18:01:21:718 XpandU[1760:3340219] rtc::Create video track: <RTCVideoTrack: 0x17466d9c0>
2016-10-03 18:01:21.721772 XpandU[1760:3340368] rtc::Created local media stream [com.quickblox.mediastream.device-iphone.5s_ios-10.0.2_userid-12074064]:A=1:V=1]
2016-10-03 18:01:21:720 XpandU[1760:3340219] rtc::Created local media stream [com.quickblox.mediastream.device-iphone.5s_ios-10.0.2_userid-12074064]:A=1:V=1]
2016-10-03 18:01:21.744988 XpandU[1760:3340538] rtc::Added Stream: [com.quickblox.mediastream.device-iphone.5s_ios-10.0.2_userid-12074064]:A=1:V=1], to <Peer:0x1700f4400, UID:11872460, r:Answer>
2016-10-03 18:01:21:744 XpandU[1760:3340219] rtc::Added Stream: [com.quickblox.mediastream.device-iphone.5s_ios-10.0.2_userid-12074064]:A=1:V=1], to <Peer:0x1700f4400, UID:11872460, r:Answer>
2016-10-03 18:01:21:752 XpandU[1760:3340289] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Renegotiation needed
2016-10-03 18:01:21.768139 XpandU[1760:3340538] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Renegotiation needed
2016-10-03 18:01:21.769132 XpandU[1760:3340538] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Set remote session description
2016-10-03 18:01:21:753 XpandU[1760:3340219] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Set remote session description
2016-10-03 18:01:22.020754 XpandU[1760:3340040] >>>>>>>>>>>>>>>>>>>>>>>>>>>>> initializedLocalMediaStream <XUCameraCapture 0x1741058e0> state: Running
2016-10-03 18:01:22.022554 XpandU[1760:3340040] >>>>>>>>>>>>>>>>>>>>>>>>>>>>> - receivedRemoteVideoTrack by User
2016-10-03 18:01:22:019 XpandU[1760:3340289] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Received remote video track
2016-10-03 18:01:22.023405 XpandU[1760:3340368] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Received remote video track
2016-10-03 18:01:22.023574 XpandU[1760:3340368] rtc::Started timer: Stats report, background task id 3 ...
2016-10-03 18:01:22:020 XpandU[1760:3340219] rtc::Started timer: Stats report, background task id 3 ...
2016-10-03 18:01:22:021 XpandU[1760:3340219] rtc::Destroy session answer time out
2016-10-03 18:01:22.024126 XpandU[1760:3340538] rtc::Destroy session answer time out
2016-10-03 18:01:22.025783 XpandU[1760:3340538] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Signaling state changed: Have Remote Offer
2016-10-03 18:01:22:021 XpandU[1760:3340219] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Signaling state changed: Have Remote Offer
2016-10-03 18:01:22:022 XpandU[1760:3340219] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Set session description
2016-10-03 18:01:22.030669 XpandU[1760:3340219] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Set session description
2016-10-03 18:01:22.030835 XpandU[1760:3340219] rtc::Create Answer: <Peer:0x1700f4400, UID:11872460, r:Answer>
2016-10-03 18:01:22:023 XpandU[1760:3340219] rtc::Create Answer: <Peer:0x1700f4400, UID:11872460, r:Answer>
2016-10-03 18:01:22:030 XpandU[1760:3340289] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Created session description:v=0

o=- 3398749504043306629 2 IN IP4 127.0.0.1

s=-

t=0 0

a=group:BUNDLE audio video

a=msid-semantic: WMS com.quickblox.mediastream.device-iphone.5s_ios-10.0.2_userid-12074064]

m=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 102 0 8 106 105 13 127 126

c=IN IP4 0.0.0.0

a=rtcp:9 IN IP4 0.0.0.0

a=ice-ufrag:4swuTwlbunGrA2iH

a=ice-pwd:EVde/qc9h6rzL/d17K4N8X65

a=fingerprint:sha-256 B4:B8:5D:CA:3A:21:10:CC:28:37:8F:3C:F8:CB:34:86:AD:99:5C:0D:78:FA:84:4F:36:2F:E0:52:07:1A:D9:6C

a=setup:active

a=mid:audio

a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level

a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time

a=sendrecv

a=rtcp-mux

a=rtpmap:111 opus/48000/2

a=rtcp-fb:111 transport-cc

a=fmtp:111 minptime=10; useinbandfec=1

a=rtpmap:103 ISAC/16000

a=rtpmap:104 ISAC/32000

a=rtpmap:9 G722/8000

a=rtpmap:102 ILBC/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:127 red/8000

a=rtpmap:126 telephone-event/8000

a=maxptime:60

a=ssrc:3146187522 cname:CA5DSTcTILvJ1k1p

a=ssrc:3146187522 msid:com.quickblox.mediastream.device-iphone.5s_ios-10.0.2_userid-12074064] audioTrack

a=ssrc:3146187522 mslabel:com.quickblox.mediastream.device-iphone.5s_ios-10.0.2_userid-12074064]

a=ssrc:3146187522 label:audioTrack

m=video 9 UDP/TLS/RTP/SAVPF 107 100 101 116 117 96 97 99 98

c=IN IP4 0.0.0.0

a=rtcp:9 IN IP4 0.0.0.0

a=ice-ufrag:4swuTwlbunGrA2iH

a=ice-pwd:EVde/qc9h6rzL/d17K4N8X65

a=fingerprint:sha-256 B4:B8:5D:CA:3A:21:10:CC:28:37:8F:3C:F8:CB:34:86:AD:99:5C:0D:78:FA:84:4F:36:2F:E0:52:07:1A:D9:6C

a=setup:active

a=mid:video

a=extmap:2 urn:ietf:params:rtp-hdrext:toffset

a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time

a=extmap:4 urn:3gpp:video-orientation

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=rtcp-fb:100 transport-cc

a=rtpmap:101 VP9/90000

a=rtcp-fb:101 ccm fir

a=rtcp-fb:101 nack

a=rtcp-fb:101 nack pli

a=rtcp-fb:101 goog-remb

a=rtcp-fb:101 transport-cc

a=rtpmap:107 H264/90000

a=rtcp-fb:107 ccm fir

a=rtcp-fb:107 nack

a=rtcp-fb:107 nack pli

a=rtcp-fb:107 goog-remb

a=rtcp-fb:107 transport-cc

a=rtpmap:116 red/90000

a=rtpmap:117 ulpfec/90000

a=rtpmap:96 rtx/90000

a=fmtp:96 apt=100

a=rtpmap:97 rtx/90000

a=fmtp:97 apt=101

a=rtpmap:99 rtx/90000

a=fmtp:99 apt=107

a=rtpmap:98 rtx/90000

a=fmtp:98 apt=116

a=ssrc-group:FID 3301555767 4159385412

a=ssrc:3301555767 cname:CA5DSTcTILvJ1k1p

a=ssrc:3301555767 msid:com.quickblox.mediastream.device-iphone.5s_ios-10.0.2_userid-12074064] videoTrack

a=ssrc:3301555767 mslabel:com.quickblox.mediastream.device-iphone.5s_ios-10.0.2_userid-12074064]

a=ssrc:3301555767 label:videoTrack

a=ssrc:4159385412 cname:CA5DSTcTILvJ1k1p

a=ssrc:4159385412 msid:com.quickblox.mediastream.device-iphone.5s_ios-10.0.2_userid-12074064] videoTrack

a=ssrc:4159385412 mslabel:com.quickblox.mediastream.device-iphone.5s_ios-10.0.2_userid-12074064]

a=ssrc:4159385412 label:videoTrack

2016-10-03 18:01:22.032955 XpandU[1760:3340368] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Created session description:v=0

o=- 3398749504043306629 2 IN IP4 127.0.0.1

s=-

t=0 0

a=group:BUNDLE audio video

a=msid-semantic: WMS com.quickblox.mediastream.device-iphone.5s_ios-10.0.2_userid-12074064]

m=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 102 0 8 106 105 13 127 126

c=IN IP4 0.0.0.0

a=rtcp:9 IN IP4 0.0.0.0

a=ice-ufrag:4swuTwlbunGrA2iH

a=ice-pwd:EVde/qc9h6rzL/d17K4N8X65

a=fingerprint:sha-256 B4:B8:5D:CA:3A:21:10:CC:28:37:8F:3C:F8:CB:34:86:AD:99:5C:0D:78:FA:84:4F:36:2F:E0:52:07:1A:D9:6C

a=setup:active

a=mid:audio

a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level

a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time

a=sendrecv

a=rtcp-mux

a=rtpmap:111 opus/48000/2

a=rtcp-fb:111 transport-cc

a=fmtp:111 minptime=10; useinbandfec=1

a=rtpmap:103 ISAC/16000

a=rtpmap:104 ISAC/32000

a=rtpmap:9 G722/8000

a=rtpmap:102 ILBC/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:106 CN/32000

a=rtpmap:105 CN/16000

a=rtpm
2016-10-03 18:01:22.040813 XpandU[1760:3340219] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Signaling state changed: Stable
2016-10-03 18:01:22:040 XpandU[1760:3340368] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Signaling state changed: Stable
2016-10-03 18:01:22.041239 XpandU[1760:3340368] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Send queued ICE candidates, count: 0
2016-10-03 18:01:22:040 XpandU[1760:3340368] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Send queued ICE candidates, count: 0
2016-10-03 18:01:22.285514 XpandU[1760:3340293] AVAudioSessionRouteChangeReasonCategoryChange
2016-10-03 18:01:22.304532 XpandU[1760:3340293] Headphone In
2016-10-03 18:01:22.304689 XpandU[1760:3340293] SoundRoute Speaker NOPLZ
2016-10-03 18:01:22.321970 XpandU[1760:3340368] rtc::audioSessionDidChangeRoute <RTCAudioSession: 0x17008aaf0> reason 3
2016-10-03 18:01:22:285 XpandU[1760:3340293] rtc::audioSessionDidChangeRoute <RTCAudioSession: 0x17008aaf0> reason 3
2016-10-03 18:01:22.536372 XpandU[1760:3340040] 318724600 == 132613100
2016-10-03 18:01:23.208713 XpandU[1760:3340417] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> ICE gathering changed: Gathering
2016-10-03 18:01:23:208 XpandU[1760:3340289] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> ICE gathering changed: Gathering
2016-10-03 18:01:23.210806 XpandU[1760:3340537] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Set session description
2016-10-03 18:01:23:208 XpandU[1760:3340219] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Set session description
2016-10-03 18:01:23.212953 XpandU[1760:3340417] rtc::Send Answer: <Peer:0x1700f4400, UID:11872460, r:Answer>
2016-10-03 18:01:23:208 XpandU[1760:3340219] rtc::Send Answer: <Peer:0x1700f4400, UID:11872460, r:Answer>
2016-10-03 18:01:23.233531 XpandU[1760:3340417] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued ICE candidates, count: 0
2016-10-03 18:01:23:233 XpandU[1760:3340219] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued ICE candidates, count: 0
2016-10-03 18:01:23.234229 XpandU[1760:3340541] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Send queued ICE candidates, count: 1
2016-10-03 18:01:23:233 XpandU[1760:3340219] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Send queued ICE candidates, count: 1
2016-10-03 18:01:23.236727 XpandU[1760:3340417] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued ICE candidates, count: 0
2016-10-03 18:01:23:235 XpandU[1760:3340219] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued ICE candidates, count: 0
2016-10-03 18:01:23.237179 XpandU[1760:3340219] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Send queued ICE candidates, count: 1
2016-10-03 18:01:23:236 XpandU[1760:3340219] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Send queued ICE candidates, count: 1
2016-10-03 18:01:23.275504 XpandU[1760:3340040] 318724600 == 132613100
2016-10-03 18:01:23.335229 XpandU[1760:3340368] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued ICE candidates, count: 0
2016-10-03 18:01:23:334 XpandU[1760:3340417] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued ICE candidates, count: 0
2016-10-03 18:01:23.335879 XpandU[1760:3340219] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Send queued ICE candidates, count: 1
2016-10-03 18:01:23:335 XpandU[1760:3340417] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Send queued ICE candidates, count: 1
2016-10-03 18:01:23.340585 XpandU[1760:3340368] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued ICE candidates, count: 0
2016-10-03 18:01:23:338 XpandU[1760:3340417] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued ICE candidates, count: 0
2016-10-03 18:01:23.342738 XpandU[1760:3340368] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Send queued ICE candidates, count: 1
2016-10-03 18:01:23:338 XpandU[1760:3340417] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Send queued ICE candidates, count: 1
2016-10-03 18:01:23.494096 XpandU[1760:3340368] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued ICE candidates, count: 0
2016-10-03 18:01:23:493 XpandU[1760:3340538] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued ICE candidates, count: 0
2016-10-03 18:01:23.500464 XpandU[1760:3340219] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Send queued ICE candidates, count: 1
2016-10-03 18:01:23:493 XpandU[1760:3340538] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Send queued ICE candidates, count: 1
2016-10-03 18:01:23.636960 XpandU[1760:3340417] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued ICE candidates, count: 0
2016-10-03 18:01:23:635 XpandU[1760:3340417] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued ICE candidates, count: 0
2016-10-03 18:01:23.640680 XpandU[1760:3340537] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Send queued ICE candidates, count: 1
2016-10-03 18:01:23:635 XpandU[1760:3340417] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Send queued ICE candidates, count: 1
2016-10-03 18:01:23.864977 XpandU[1760:3340540] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> ICE gathering changed: Complete
2016-10-03 18:01:23:856 XpandU[1760:3340289] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> ICE gathering changed: Complete
2016-10-03 18:01:23.875150 XpandU[1760:3340219] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued ICE candidates, count: 0
2016-10-03 18:01:23:865 XpandU[1760:3340541] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued ICE candidates, count: 0
2016-10-03 18:01:23.877504 XpandU[1760:3340368] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Send queued ICE candidates, count: 1
2016-10-03 18:01:23:868 XpandU[1760:3340541] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Send queued ICE candidates, count: 1
2016-10-03 18:01:23.888022 XpandU[1760:3340040] 318724600 == 132613100
2016-10-03 18:01:23.909104 XpandU[1760:3340040] state changed from    3   4
2016-10-03 18:01:24.320357 XpandU[1760:3340040] Set External User ID 130798664 * 10 SUCCESS
2016-10-03 18:01:24.583908 XpandU[1760:3340040] 318724600 == 132613100
2016-10-03 18:01:25.373608 XpandU[1760:3340540] rtc::Did receive signal: call from: 11872460
2016-10-03 18:01:25:371 XpandU[1760:3340368] rtc::Did receive signal: call from: 11872460
2016-10-03 18:01:25.374754 XpandU[1760:3340541] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued Remote SDP
2016-10-03 18:01:25:372 XpandU[1760:3340368] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Queued Remote SDP
2016-10-03 18:01:25.410753 XpandU[1760:3340040] 318724600 == 132613100
2016-10-03 18:01:26.013860 XpandU[1760:3340040] 318724600 == 132613100
2016-10-03 18:01:26.421901 XpandU[1760:3340219] rtc::Did receive signal: iceCandidates from: 11872460
2016-10-03 18:01:26:420 XpandU[1760:3340368] rtc::Did receive signal: iceCandidates from: 11872460
2016-10-03 18:01:26.423270 XpandU[1760:3340219] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Set remote ICE candidates, count: 28
2016-10-03 18:01:26:422 XpandU[1760:3340537] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Set remote ICE candidates, count: 28
2016-10-03 18:01:26.523915 XpandU[1760:3340368] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Ice connection changed: Checking
2016-10-03 18:01:26:523 XpandU[1760:3340537] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Ice connection changed: Checking
2016-10-03 18:01:26.676438 XpandU[1760:3340040] 318724600 == 132613100
2016-10-03 18:01:27.382473 XpandU[1760:3340040] 318724600 == 132613100
2016-10-03 18:01:28.057522 XpandU[1760:3340040] 132613100 == 132613100
2016-10-03 18:01:28.057909 XpandU[1760:3340040] state changed from    4   6
2016-10-03 18:01:28.817827 XpandU[1760:3340040] >>>>>>>>>>>>>>>>>>>>>>>>>>>>> - connectedToUser
2016-10-03 18:01:28:816 XpandU[1760:3340537] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Ice connection changed: Connected
2016-10-03 18:01:28.822382 XpandU[1760:3340040] Headphone In
2016-10-03 18:01:28.822485 XpandU[1760:3340040] SoundRoute Speaker NOPLZ
2016-10-03 18:01:28.822905 XpandU[1760:3340417] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Ice connection changed: Connected
2016-10-03 18:01:28.824539 XpandU[1760:3340537] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Stopped handling disconnect
2016-10-03 18:01:28:816 XpandU[1760:3340537] rtc::<Peer:0x1700f4400, UID:11872460, r:Answer> Stopped handling disconnect
2016-10-03 18:01:31.124875 XpandU[1760:3340040] CN 1853ms | local->stun/udp | (s)0bps | (r)0bps
VS (input) 480x640@30fps | (sent) 480x640@15fps
VS (enc) 283Kbps/300Kbps | (sent) 0bps/300Kbps | 14ms | VP8
VR (recv) -1x-1@0fps | (decoded)0 | (output)0fps | 0bps/0bps | 0ms
AS 0bps | opus
AR 0bps | opus | 85ms | (expandrate)0.389648
2016-10-03 18:01:34.124710 XpandU[1760:3340040] CN 1533ms | local->stun/udp | (s)312Kbps | (r)38Kbps
VS (input) 480x640@30fps | (sent) 480x640@30fps
VS (enc) 235Kbps/297Kbps | (sent) 266Kbps/297Kbps | 11ms | VP8
VR (recv) -1x-1@2fps | (decoded)0 | (output)0fps | 5Kbps/57Kbps | 0ms
AS 36Kbps | opus
AR 8Kbps | opus | 100ms | (expandrate)0.377869
ghost commented 8 years ago

Hi @shalem93, Please provide logs from both devices

ghost commented 8 years ago

@shalem93 Any updates?

shalem93 commented 7 years ago

Hi sorry. So it seems the first user who initiates call doesn't receive the other persons video if they have never called that user previously. (The other user doesn't send video as well, but receives the initiators video). Im wondering if overlay screens on top of the video screens affect this? I could really use just 10 minutes of your guys time if someone wants to look at this through Skype?

Raikerian commented 7 years ago

Is this problem reproducible in our sample?

Raikerian commented 7 years ago

Hi again @shalem93 ,

We have just released our new WebRTC SDK 2.3. Please, check it out. Here is full changelog of version 2.3: http://quickblox.com/developers/Sample-webrtc-ios#Framework_changelog

Also check out our updated sample for this issue. If the issue is still there, please let us know and open new issue so we can look into it.

Have a nice holidays.