· 6 years ago · Nov 27, 2019, 11:38 AM
1#############################################################################
2Starting the OMP module tests
3#############################################################################
4
5Stress tests will be run for <1> minute(s)
6Note: Google Test filter = OmpRestModuleTests.injector_multistream
7[==========] Running 1 test from 1 test case.
8[----------] Global test environment set-up.
9[----------] 1 test from OmpRestModuleTests
10[ RUN ] OmpRestModuleTests.injector_multistream
11--- 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=sendrecv\r\na=content:slides\r\n
12Could not extract remote host for connection
13113233.567 INTERNAL_CALL_HANDLER : Info : tID a: session: 586785579155704230 - Successfully handle incoming call to injector_multistream@cisco.com
14113233.610 WS_API : Info : tID a: using Calliope WebSocket API v2
15---- Nga: dsm_cmgr_dme_edonus_new_call, remoteSdp =
16-- Nga: roap offer, remoteParam = (nil)
17113233.611 WS_API : Info : tID a: ciscoMultistream not supported, isCascade flag not set
18113233.611 LOAD : Info : Attempting to go from zero calls to one call: wiping load metric history, resetting squishy port state
19113233.611 LOAD : Info : accept_call: estimated future load metric is: 4(rolling max) + 33(estimated due to recent calls) + 0(phantom) = 37
20113233.611 LOAD : Info : Allow Audio Scale is set to true - ACCEPTING CALL
21113233.612 WS_API : Info : tID a: Incoming call 0x1, trackingID=a, locusID=injector_multistream@cisco.com, remoteFlags=0x0 isTest=false, isTransferred=false, isCmtc=false
22113233.612 WS_API : Info : tID a: is delayed offer
23-- Nga: Set omp call back is true
24Starting call
25Handling response : 1 , call
26-- Nga: call pipeline 2
27113233.621 WS_API : Info : tID a: OMP Call Config : OK = 1, audio = 0, video = 2, aud-ms = 0, vid-ms = 1, streaming = 0, whiteboard = 1
28
29"tID a: OMP Call Config : OK = 1, audio = 0, video = 2, aud-ms = 0, vid-ms = 1, streaming = 0, whiteboard = 1" contains "streaming = 0"
30
31-- Nga: done omp call back, set it back to false
32113233.621 WS_API : Info : tID a: Created OMP object!
33
34"tID a: Created OMP object!" contains "Created OMP object"
35
36--- Nga: set omp: multistream = 1
37--- Nga: set sdp_allowed.extended_video is multistream = 1
38--- Nga: call continue sdp exchange 1
39--- Nga: in continue_sdp_exchange: sdp_allowed.extended_video is multistream = 1
40--- Nga: allowed.extend_video is multistream
41--- Nga: dsm_cmgr_dme_edonus_dial_client_call_with_scf
42113233.671 DSM_CMGR_OMP_CALLS : Info : tID a: 586785579155704230 : Successfully joined whiteboardUrl
43113233.671 SCF_CONN : Info : Made call to injector_multistream@cisco.com with handle1
44--- Nga: setState
45--- Nga: call back to callChange
46--- Nga: dsm_cmgr_dme_edonus_call_change
47--- Nga: get config out, remoteParams is null = 0
48--- Nga: call continue sdp exchange 3
49--- Nga: in continue_sdp_exchange: sdp_allowed.extended_video is multistream = 0
50--- Nga: media exchange
51Nga: ep->local_multistream.extended_video = 0
52113233.675 DSM_CMGR_OMP_CALLS : Info : tID a: 586785579155704230: Creating audio + extended video pipeline
53113233.675 DSP-INTERFACE : Info : tID a: 00010000 video_memstreamer: create video_memstreamer\nhandle 65536\nprofile_type none
54113233.675 DSP-INTERFACE : Info : tID a: 00010000 video_memstreamer: input omp-0
55113233.676 DSM_CMGR_OMP_CALLS : Info : tID a: 586785579155704230: Setting sharpness hint override for OMP pipeline, video type=2
56
57"tID a: 586785579155704230: Setting sharpness hint override for OMP pipeline, video type=2" contains "Setting sharpness hint override for OMP pipeline, video type=2"
58
59113233.677 ECA : Info : tID a: changing outgoing resolution: 0 x 0 -> 1280 x 720
60113233.677 DSP-INTERFACE : Info : tID a: 00010001 mari_encoder: create mari_encoder\nhandle 65537\nprofile_type content
61113233.677 DSP-INTERFACE : Info : tID a: 00010002 video_encoder: create video_encoder\nhandle 65538\nprofile_type content
62113233.677 DSP-INTERFACE : Info : tID a: 00010002 video_encoder: configure h264\npayload_type 100\nuse_discardable_frames 0\npacketization_mode 0\ntip_video_refresh_byte no\ntip_restricted_media no\nallow_passthrough no\nallow_passthrough_packetization_mode1 no
63113233.677 DSP-INTERFACE : Info : tID a: 00010002 video_encoder: ratecontrol\nbit_rate 2000000\nidr_quality normal\nbuffer_mode normal\nmax_qp 50
64113233.678 ENCRYPTION : Info : tID a: 0x10001 mari_encoder: configure crypto
65113233.678 DSP-INTERFACE : Info : tID a: 00010001 mari_encoder: packet_pacing\nbit_rate 8800000\nauto_tune yes
66113233.678 ECA : Info : tID a: No remote FEC PT. Disabling MARI
67113233.678 DSP-INTERFACE : Info : tID a: 00010003 video_compose: create video_compose\nhandle 65539\nprofile_type content
68113233.678 DSP-INTERFACE : Info : tID a: 00010003 video_compose: usage_hint\nserver_encoder\nfixed_framerate\nwait_on_first_frame\nntp_passthrough
69113233.679 DSP-INTERFACE : Info : tID a: 00010001 mari_encoder: packet_pacing\nbit_rate 4400000\nauto_tune yes
70113233.679 ECA : Info : tID a: No remote FEC PT. Disabling MARI
71113233.679 DSM_CMGR_OMP_CALLS : Info : tID a: 586785579155704230 : start rendering whiteboard
72
73"tID a: 586785579155704230 : start rendering whiteboard" contains "start rendering whiteboard"
74
75113233.679 DSM_CMGR_OMP_CALLS : Error : tID a: 586785579155704230 : No whiteboardViewFloor available
76113233.680 DSM_CMGR_OMP_CALLS : Info : ##davew deploy_config::injector_cfg->injector_takes_content_floor=0, wb_url=
77113233.680 DSM_CMGR_OMP_CALLS : Info : tID a: 586785579155704230 : Falling back to contentFloor
78--- Nga: setState
79--- Nga: setState - sdp exchange completed
80113233.680 ECA : Info : tID a: MARI call first negotiation with RSFEC: yes, rate adaptation: yes, MARI Sync: yes
81
82113233.680 CMGR_OMP : Info : tID a: CREATE request for session_id 586785579155704230 with result 1
83--- Nga: offer_sdp =
84v=0
85o=Calliope 412 1 IN IP4 172.17.0.3
86s=-
87i=MediaEngine
88c=IN IP4 172.17.0.3
89b=TIAS:4000000
90t=0 0
91a=cisco-mari:v1
92a=cisco-mari-rate
93m=video 47960 RTP/AVP 126 97 123
94b=TIAS:4000000
95a=rtpmap:126 H264/90000
96a=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
97a=rtpmap:97 H264/90000
98a=fmtp:97 profile-level-id=42e016;max-mbps=244800;max-fs=9000;max-fps=3000;max-dpb=12240;max-rcmd-nalu-size=196608
99a=rtpmap:123 x-ulpfecuc/90000
100a=fmtp:123 max_esel=1440;m=8;max_n=64;multi_ssrc=1;non_seq=1;FEC_ORDER=FEC_SRTP;feedback=0
101a=sprop-source:0 policies=as:1;simul=1,2,3|4,5,6
102a=sprop-simul:0 1 97
103a=sprop-simul:0 2 97
104a=sprop-simul:0 3 97
105a=sprop-simul:0 4 126
106a=sprop-simul:0 5 126
107a=sprop-simul:0 6 126
108a=sprop-total:max-pps=109670400
109a=rtcp-fb:* nack pli
110a=rtcp-fb:* ccm fir
111a=rtcp-fb:* ccm tmmbr
112a=rtcp-fb:* ccm cisco-scr
113a=rtcp-fb:* ccm msync
114a=extmap:1 http://protocols.cisco.com/virtualid
115a=extmap:2 http://protocols.cisco.com/framemarking
116a=extmap:4 http://protocols.cisco.com/timestamp#100us
117a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:ARef+WjefzPetbnhv58psUu1028ZunhXkvAT42F1
118a=crypto:2 AES_CM_128_HMAC_SHA1_80 inline:ARef+WjefzPetbnhv58psUu1028ZunhXkvAT42F1 UNENCRYPTED_SRTCP
119a=crypto:3 AES_CM_128_HMAC_SHA1_32 inline:ARef+WjefzPetbnhv58psUu1028ZunhXkvAT42F1
120a=sendonly
121a=content:slides
122a=label:12
123a=mid:1
124
125--- Nga: done set up call 1 ---
126113233.684 ECA : Info : tID a: received extended video SCR 1 with 3 requests
127--- Nga: multistream is not enabled for tx_video
128113234.396 MEDIA : Info : Scale audio decoder cost estimates by 1000/7000 to better approximate millionths of a DSP
129113234.396 MEDIA : Info : Scale audio encoder cost estimates by 1000/7000 to better approximate millionths of a DSP
130113234.396 MEDIA : Info : Scale video decoder cost estimates by 1000/7000 to better approximate millionths of a DSP
131113234.396 MEDIA : Info : Scale video encoder cost estimates by 1000/7000 to better approximate millionths of a DSP
132113234.396 DSP_PLACEMENT : Info : changed Ad.cost_scale_times_1000 (scale) from 1000 to 7000
133113234.396 DSP_PLACEMENT : Info : changed Ae.cost_scale_times_1000 (scale) from 1000 to 7000
134113234.396 DSP_PLACEMENT : Info : changed Vd.cost_scale_times_1000 (scale) from 1000 to 7000
135113234.397 DSP_PLACEMENT : Info : changed Ve.cost_scale_times_1000 (scale) from 1000 to 7000
136113238.404 CALL_HOME : Info : submitting device inventory
137113238.405 HTTP_DOWNLOAD : Info : {"auth":"None","method":"POST","trackingID":"EDONUS_04ac7252-2ffa-4f2b-89c0-3447651e50b8_1","uri":"https://tools.cisco.com:443/its/service/oddce/services/DDCEService"}
138113238.405 HTTP_DOWNLOAD : Info : tID 04ac7252-2ffa-4f2b-89c0-3447651e50b8: CrashReporting: Operation Finished 404
139113238.405 HTTP_DOWNLOAD : Error : tID 04ac7252-2ffa-4f2b-89c0-3447651e50b8: CrashReporting: HTTP Error code 404 for [https://tools.cisco.com:443/its/service/oddce/services/DDCEService]
140113238.405 HTTP_DOWNLOAD : Info : {"contentLength":0,"response":404,"trackingID":"EDONUS_04ac7252-2ffa-4f2b-89c0-3447651e50b8_1","uri":"https://tools.cisco.com:443/its/service/oddce/services/DDCEService"}
141113238.405 CALL_HOME : Error : download failed
142113238.406 HTTP_DOWNLOAD : Error : CrashReporting: No request found for call to openFile (04ac7252-2ffa-4f2b-89c0-3447651e50b8)
143113238.406 CALL_HOME : Warning : submission of device inventory failed, will be retried
144113241.294 TOKEN_VALIDATOR : Info : Getting verification key for https://idbroker.webex.com/idb
145113241.295 HTTP_DOWNLOAD : Info : [https://idbroker.webex.com/idb/oauth2/v1/keys/verification] is not in cache
146113241.295 HTTP_DOWNLOAD : Info : {"auth":"Bearer","method":"GET","trackingID":"EDONUS_3eaded08-b28e-42a9-b4fb-d13cafb5fe0e_1","uri":"https://idbroker.webex.com/idb/oauth2/v1/keys/verification"}
147113241.295 HTTP_DOWNLOAD : Info : tID 3eaded08-b28e-42a9-b4fb-d13cafb5fe0e: TokenValidatorKeys: Operation Finished 404
148113241.295 HTTP_DOWNLOAD : Error : tID 3eaded08-b28e-42a9-b4fb-d13cafb5fe0e: TokenValidatorKeys: HTTP Error code 404 for [https://idbroker.webex.com/idb/oauth2/v1/keys/verification]
149113241.296 HTTP_DOWNLOAD : Info : {"contentLength":0,"response":404,"trackingID":"EDONUS_3eaded08-b28e-42a9-b4fb-d13cafb5fe0e_1","uri":"https://idbroker.webex.com/idb/oauth2/v1/keys/verification"}
150113241.296 TOKEN_VALIDATOR : Error : Failed to obtain one of the cjose keys - fallback to CI validation until new keys provided
151113243.399 LOAD : Warning : Unexpected number of CPUS reported: 0 (expected 32)
152113243.685 DSP-INTERFACE : Error : ObjectCounter didn't reach expected count
153(Actual: 1 vs Expected: 3)
154
155/home/lal/volume/dsm/build/tests/omp_module_test.cpp:1949: Failure
156Value of: video_encoder_counter->awaitObjectCount(NUM_CHANNEL_REQ, 10, Exactly, &encoder_ids)
157 Actual: false
158Expected: true
159
160
1610% tests passed, 1 tests failed out of 1
162
163Label Time Summary:
164coverage = 10.68 sec*proc (1 test)
165module = 10.68 sec*proc (1 test)
166module_split_3 = 10.68 sec*proc (1 test)
167
168Total Test time (real) = 10.68 sec
169
170The following tests FAILED:
171 15 - omp_module_test (SEGFAULT)
172Errors while running CTest
173lal:
174lal: run error: Process exited with 8