· 6 years ago · Nov 27, 2019, 10:16 AM
1Note: Google Test filter = OmpRestModuleTests.injector_multistream
2[==========] Running 1 test from 1 test case.
3[----------] Global test environment set-up.
4[----------] 1 test from OmpRestModuleTests
5[ RUN ] OmpRestModuleTests.injector_multistream
6--- Nga: remote SDP = v=0\r\no=TEST 0 0 IN IP4 10.10.10.10\r\ns=-\r\ni=TEST\r\nc=IN IP4 10.10.10.10\r\nb=TIAS:4000000\r\nt=0 0\r\na=cisco-mari:v0,v1\r\nm=video 51776 RTP/AVP 100\r\nb=TIAS:4000000\r\na=rtpmap:100 H264/90000\r\na=fmtp:100 profile-level-id=420014;max-mbps=108000;max-fs=3600;max-fps=3000;max-br=1500;max-dpb=11520\r\na=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:1111111111111111111111111111111111111111\r\na=crypto:2 AES_CM_128_HMAC_SHA1_80 inline:1111111111111111111111111111111111111111 UNENCRYPTED_SRTCP\r\na=crypto:3 AES_CM_128_HMAC_SHA1_32 inline:1111111111111111111111111111111111111111\r\na=rtcp-mux\r\na=rtcp:51776\r\na=rtcp-fb:* nack pli\r\na=rtcp-fb:* ccm cisco-scr\r\na=sendrecv\r\na=content:slides\r\na=extmap:1/sendrecv http://protocols.cisco.com/virtualid\r\na=extmap:2/sendrecv http://protocols.cisco.com/framemarking\r\na=extmap:3/sendrecv urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:5/sendrecv urn:3gpp:video-orientation\r\na=sprop-source:0 count=20;policies=as:1;lrotation=1\r\na=sprop-simul:0 0 *\r\na=sprop-simul:0 1 *\r\na=sprop-simul:0 2 *\r\na=sprop-simul:0 3 *\r\n
7Could not extract remote host for connection
8095847.747 INTERNAL_CALL_HANDLER : Info : tID a: session: 5836693099953094447 - Successfully handle incoming call to injector_multistream@cisco.com
9095847.789 WS_API : Info : tID a: using Calliope WebSocket API v2
10---- Nga: dsm_cmgr_dme_edonus_new_call, remoteSdp =
11-- Nga: roap offer, remoteParam = (nil)
12095847.791 WS_API : Info : tID a: ciscoMultistream not supported, isCascade flag not set
13095847.791 LOAD : Info : Attempting to go from zero calls to one call: wiping load metric history, resetting squishy port state
14095847.791 LOAD : Info : accept_call: estimated future load metric is: 4(rolling max) + 33(estimated due to recent calls) + 0(phantom) = 37
15095847.791 LOAD : Info : Allow Audio Scale is set to true - ACCEPTING CALL
16095847.791 WS_API : Info : tID a: Incoming call 0x1, trackingID=a, locusID=injector_multistream@cisco.com, remoteFlags=0x0 isTest=false, isTransferred=false, isCmtc=false
17095847.791 WS_API : Info : tID a: is delayed offer
18-- Nga: Set omp call back is true
19Starting call
20Handling response : 1 , call
21-- Nga: call pipeline 2
22095847.801 WS_API : Info : tID a: OMP Call Config : OK = 1, audio = 0, video = 2, aud-ms = 0, vid-ms = 1, streaming = 0, whiteboard = 1
23
24"tID a: OMP Call Config : OK = 1, audio = 0, video = 2, aud-ms = 0, vid-ms = 1, streaming = 0, whiteboard = 1" contains "streaming = 0"
25
26-- Nga: done omp call back, set it back to false
27095847.801 WS_API : Info : tID a: Created OMP object!
28
29"tID a: Created OMP object!" contains "Created OMP object"
30
31--- Nga: set omp: multistream = 1
32--- Nga: set sdp_allowed.extended_video is multistream = 1
33--- Nga: call continue sdp exchange 1
34--- Nga: in continue_sdp_exchange: sdp_allowed.extended_video is multistream = 1
35--- Nga: allowed.extend_video is multistream
36--- Nga: dsm_cmgr_dme_edonus_dial_client_call_with_scf
37095847.851 DSM_CMGR_OMP_CALLS : Info : tID a: 5836693099953094447 : Successfully joined whiteboardUrl
38095847.852 SCF_CONN : Info : Made call to injector_multistream@cisco.com with handle1
39--- Nga: setState
40--- Nga: call back to callChange
41--- Nga: dsm_cmgr_dme_edonus_call_change
42--- Nga: get config out, remoteParams is null = 0
43--- Nga: call continue sdp exchange 3
44--- Nga: in continue_sdp_exchange: sdp_allowed.extended_video is multistream = 1
45--- Nga: allowed.extend_video is multistream
46--- Nga: media exchange
47Nga: ep->local_multistream.extended_video = 1
48095847.854 ECA : Info : tID a: Cisco multistream mode
49095847.854 ECA : Warning : tID a: rx_set_video_multistream_payload: did not find matching or primary codec. not setting rx_video
50095847.855 ECA : Info : tID a: Sent SCR for single video sub-stream seq no: 1 to EP[1] numRequests(1) is_content(true)
51095847.855 ECA : Info : tID a: triggering unsolicited extended video SCA 1 New SDP exchange completed
52095847.855 ECA : Info : tID a: Sent SCR for single video sub-stream seq no: 2 to EP[1] numRequests(1) is_content(true)
53095847.857 DSM_CMGR_OMP_CALLS : Info : tID a: 5836693099953094447: Creating audio + extended video pipeline
54095847.857 DSP-INTERFACE : Info : tID a: 00010000 video_memstreamer: create video_memstreamer\nhandle 65536\nprofile_type none
55095847.857 DSP-INTERFACE : Info : tID a: 00010000 video_memstreamer: input omp-0
56095847.857 ECA : Info : tID a: triggering unsolicited extended video SCA 2 omp pipeline reconfigure
57095847.858 DSM_CMGR_OMP_CALLS : Info : tID a: 5836693099953094447: Setting sharpness hint override for OMP pipeline, video type=2
58
59"tID a: 5836693099953094447: Setting sharpness hint override for OMP pipeline, video type=2" contains "Setting sharpness hint override for OMP pipeline, video type=2"
60
61095847.858 DSM_CMGR_OMP_CALLS : Info : tID a: 5836693099953094447 : start rendering whiteboard
62
63"tID a: 5836693099953094447 : start rendering whiteboard" contains "start rendering whiteboard"
64
65095847.858 DSM_CMGR_OMP_CALLS : Error : tID a: 5836693099953094447 : No whiteboardViewFloor available
66095847.858 DSM_CMGR_OMP_CALLS : Info : ##davew deploy_config::injector_cfg->injector_takes_content_floor=0, wb_url=
67095847.858 DSM_CMGR_OMP_CALLS : Info : tID a: 5836693099953094447 : Falling back to contentFloor
68--- Nga: setState
69--- Nga: setState - sdp exchange completed
70095847.858 ECA : Info : tID a: MARI call first negotiation with RSFEC: yes, rate adaptation: yes, MARI Sync: yes
71
72095847.858 CMGR_OMP : Info : tID a: CREATE request for session_id 5836693099953094447 with result 1
73--- Nga: offer_sdp =
74v=0
75o=Calliope 387 1 IN IP4 172.17.0.3
76s=-
77i=MediaEngine
78c=IN IP4 172.17.0.3
79b=TIAS:4000000
80t=0 0
81a=cisco-mari:v1
82a=cisco-mari-rate
83m=video 41036 RTP/AVP 126 97 123
84b=TIAS:4000000
85a=rtpmap:126 H264/90000
86a=fmtp:126 profile-level-id=42e016;packetization-mode=1;max-mbps=244800;max-fs=9000;max-fps=3000;max-dpb=12240;max-rcmd-nalu-size=196608
87a=rtpmap:97 H264/90000
88a=fmtp:97 profile-level-id=42e016;max-mbps=244800;max-fs=9000;max-fps=3000;max-dpb=12240;max-rcmd-nalu-size=196608
89a=rtpmap:123 x-ulpfecuc/90000
90a=fmtp:123 max_esel=1440;m=8;max_n=64;multi_ssrc=1;non_seq=1;FEC_ORDER=FEC_SRTP;feedback=0
91a=sprop-source:0 policies=as:1;simul=1,2,3|4,5,6
92a=sprop-simul:0 1 97
93a=sprop-simul:0 2 97
94a=sprop-simul:0 3 97
95a=sprop-simul:0 4 126
96a=sprop-simul:0 5 126
97a=sprop-simul:0 6 126
98a=sprop-total:max-pps=109670400
99a=rtcp-fb:* nack pli
100a=rtcp-fb:* ccm fir
101a=rtcp-fb:* ccm tmmbr
102a=rtcp-fb:* ccm cisco-scr
103a=rtcp-fb:* ccm msync
104a=extmap:1 http://protocols.cisco.com/virtualid
105a=extmap:2 http://protocols.cisco.com/framemarking
106a=extmap:4 http://protocols.cisco.com/timestamp#100us
107a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:OHD5k1OySk3eJ/LN2HwQEsUKy5hj+Rzzlt8dm4Nq
108a=crypto:2 AES_CM_128_HMAC_SHA1_80 inline:OHD5k1OySk3eJ/LN2HwQEsUKy5hj+Rzzlt8dm4Nq UNENCRYPTED_SRTCP
109a=crypto:3 AES_CM_128_HMAC_SHA1_32 inline:OHD5k1OySk3eJ/LN2HwQEsUKy5hj+Rzzlt8dm4Nq
110a=sendonly
111a=content:slides
112a=label:12
113a=mid:1
114
115095848.605 MEDIA : Info : Scale audio decoder cost estimates by 1000/7000 to better approximate millionths of a DSP
116095848.606 MEDIA : Info : Scale audio encoder cost estimates by 1000/7000 to better approximate millionths of a DSP
117095848.606 MEDIA : Info : Scale video decoder cost estimates by 1000/7000 to better approximate millionths of a DSP
118095848.606 MEDIA : Info : Scale video encoder cost estimates by 1000/7000 to better approximate millionths of a DSP
119095848.606 DSP_PLACEMENT : Info : changed Ad.cost_scale_times_1000 (scale) from 1000 to 7000
120095848.606 DSP_PLACEMENT : Info : changed Ae.cost_scale_times_1000 (scale) from 1000 to 7000
121095848.606 DSP_PLACEMENT : Info : changed Vd.cost_scale_times_1000 (scale) from 1000 to 7000
122095848.606 DSP_PLACEMENT : Info : changed Ve.cost_scale_times_1000 (scale) from 1000 to 7000
123095852.614 CALL_HOME : Info : submitting device inventory
124095852.614 HTTP_DOWNLOAD : Info : {"auth":"None","method":"POST","trackingID":"EDONUS_5bed5df5-130f-4fe3-839f-c23342159ff4_1","uri":"https://tools.cisco.com:443/its/service/oddce/services/DDCEService"}
125095852.615 HTTP_DOWNLOAD : Info : tID 5bed5df5-130f-4fe3-839f-c23342159ff4: CrashReporting: Operation Finished 404
126095852.615 HTTP_DOWNLOAD : Error : tID 5bed5df5-130f-4fe3-839f-c23342159ff4: CrashReporting: HTTP Error code 404 for [https://tools.cisco.com:443/its/service/oddce/services/DDCEService]
127095852.615 HTTP_DOWNLOAD : Info : {"contentLength":0,"response":404,"trackingID":"EDONUS_5bed5df5-130f-4fe3-839f-c23342159ff4_1","uri":"https://tools.cisco.com:443/its/service/oddce/services/DDCEService"}
128095852.615 CALL_HOME : Error : download failed
129095852.615 HTTP_DOWNLOAD : Error : CrashReporting: No request found for call to openFile (5bed5df5-130f-4fe3-839f-c23342159ff4)
130095852.615 CALL_HOME : Warning : submission of device inventory failed, will be retried
131095855.503 TOKEN_VALIDATOR : Info : Getting verification key for https://idbroker.webex.com/idb
132095855.503 HTTP_DOWNLOAD : Info : [https://idbroker.webex.com/idb/oauth2/v1/keys/verification] is not in cache
133095855.503 HTTP_DOWNLOAD : Info : {"auth":"Bearer","method":"GET","trackingID":"EDONUS_663b8c87-414a-4f01-8929-c721fe6ae85c_1","uri":"https://idbroker.webex.com/idb/oauth2/v1/keys/verification"}
134095855.504 HTTP_DOWNLOAD : Info : tID 663b8c87-414a-4f01-8929-c721fe6ae85c: TokenValidatorKeys: Operation Finished 404
135095855.504 HTTP_DOWNLOAD : Error : tID 663b8c87-414a-4f01-8929-c721fe6ae85c: TokenValidatorKeys: HTTP Error code 404 for [https://idbroker.webex.com/idb/oauth2/v1/keys/verification]
136095855.504 HTTP_DOWNLOAD : Info : {"contentLength":0,"response":404,"trackingID":"EDONUS_663b8c87-414a-4f01-8929-c721fe6ae85c_1","uri":"https://idbroker.webex.com/idb/oauth2/v1/keys/verification"}
137095855.504 TOKEN_VALIDATOR : Error : Failed to obtain one of the cjose keys - fallback to CI validation until new keys provided
138095857.608 LOAD : Warning : Unexpected number of CPUS reported: 0 (expected 32)
139095857.862 DSP-INTERFACE : Error : ObjectCounter didn't reach expected count
140(Actual: 0 vs Expected: 1)
141
142/home/lal/volume/dsm/build/tests/omp_module_test.cpp:1896: Failure
143Value of: video_encoder_counter->awaitObjectCount(1, 10, Exactly)
144 Actual: false
145Expected: true