Too slow unit test

[wangkuiyi]

 72/252 Test  #72: test_CompareTwoNets .........................   Passed   80.78 sec

[chengduoZH]

The time consumed by all single measurements is as follows：

parameter_optimizer_test ....................        0.01 sec
cudnn_helper_test ...........................        0.40 sec
op_proto_maker_test .........................        0.40 sec
prune_test ..................................        0.40 sec
scope_test ..................................        0.40 sec
stringprintf_test ...........................        0.40 sec
test_config_parser ..........................        0.40 sec
var_type_inference_test .....................        0.40 sec
enforce_test ................................        0.41 sec
op_registry_test ............................        0.41 sec
scatter_test ................................        0.41 sec
test_reset_hook .............................        0.41 sec
cpu_info_test ...............................        0.46 sec
save_load_op_test ...........................        0.47 sec
dim_test ....................................        0.50 sec
stringpiece_test ............................        0.50 sec
to_string_test ..............................        0.50 sec
place_test ..................................        0.51 sec
nccl_test ...................................        0.56 sec
math_function_test ..........................        0.58 sec
backward_test ...............................        0.60 sec
ddim_test ...................................        0.60 sec
net_op_test .................................        0.60 sec
operator_test ...............................        0.60 sec
program_desc_test ...........................        0.60 sec
serialization_test ..........................        0.61 sec
testArguments ...............................        0.63 sec
nccl_op_test ................................        0.67 sec
eigen_test ..................................        0.70 sec
lod_tensor_test .............................        0.70 sec
variable_test ...............................        0.70 sec
gather_test .................................        0.71 sec
layers_test .................................        0.72 sec
selected_rows_functor_test ..................        0.83 sec
test_auc_op .................................        0.92 sec
test_rnn_memory_helper_op ...................        0.92 sec
test_image_classification_layer .............        0.96 sec
network_helper_test .........................        1.01 sec
test_program ................................        1.01 sec
test_variable ...............................        1.04 sec
testVector ..................................        1.05 sec
test_infer_shape ............................        1.11 sec
test_seq_concat_op ..........................        1.11 sec
selected_rows_test ..........................        1.12 sec
test_dynamic_recurrent_op ...................        1.12 sec
test_scope ..................................        1.14 sec
test_inference_model_io .....................        1.16 sec
memory_test .................................        1.20 sec
test_regularizer ............................        1.20 sec
dynamic_recurrent_op_test ...................        1.21 sec
test_layers .................................        1.21 sec
test_op_support_gpu .........................        1.22 sec
test_protobuf_descs .........................        1.22 sec
test_positive_negative_pair_op ..............        1.25 sec
test_lod_rank_table .........................        1.26 sec
test_framework_debug_str ....................        1.27 sec
system_allocator_test .......................        1.30 sec
test_exception ..............................        1.30 sec
tensor_array_test ...........................        1.31 sec
testMatrix ..................................        1.31 sec
test_Trainer ................................        1.31 sec
test_cond_op ................................        1.31 sec
test_lod_tensor_array_ops ...................        1.31 sec
test_image ..................................        1.32 sec
test_topology ...............................        1.32 sec
test_executor_and_mul .......................        1.37 sec
test_net ....................................        1.37 sec
test_parameters .............................        1.37 sec
test_rnn_layer ..............................        1.37 sec
creator_test ................................        1.40 sec
test_data_feeder ............................        1.40 sec
test_optimizer ..............................        1.43 sec
test_lod_tensor_array .......................        1.46 sec
lod_tensor_gpu_test .........................        1.50 sec
test_lrn_op .................................        1.50 sec
test_modified_huber_loss_op .................        1.52 sec
test_op .....................................        1.52 sec
test_operator_desc ..........................        1.52 sec
test_default_scope_funcs ....................        1.53 sec
test_precision_recall_op ....................        1.56 sec
test_operator ...............................        1.60 sec
test_nccl_init_op ...........................        1.64 sec
test_parameter ..............................        1.64 sec
test_tensor .................................        1.72 sec
test_accuracy_op ............................        1.73 sec
test_crf_decoding_op ........................        1.75 sec
FunctionTest ................................        1.81 sec
testGradientMachine .........................        1.83 sec
test_sigmoid_cross_entropy_with_logits_op ...        1.91 sec
test_layer ..................................        1.92 sec
transform_test ..............................        1.96 sec
test_feed_fetch_method ......................        2.01 sec
test_selected_rows ..........................        2.02 sec
test_initializer ............................        2.03 sec
test_array_read_write_op ....................        2.06 sec
test_PyDataProviderWrapper ..................        2.11 sec
test_lstm_unit_op ...........................        2.13 sec
strided_memcpy_test .........................        2.15 sec
test_gru_unit_op ............................        2.21 sec
test_ploter .................................        2.21 sec
test_protobuf ...............................        2.21 sec
test_recurrent_op ...........................        2.22 sec
test_tensor_array ...........................        2.22 sec
test_squared_l2_norm_op .....................        2.27 sec
selected_rows_functor_gpu_test ..............        2.31 sec
TensorTypeTest ..............................        2.32 sec
pserver_test ................................        2.32 sec
test_evaluator ..............................        2.42 sec
test_CompareTwoOpts .........................        2.61 sec
test_Thread .................................        2.62 sec
test_compare_op .............................        2.62 sec
test_recognize_digits_mlp ...................        2.69 sec
math_function_gpu_test ......................        2.70 sec
BufferArgTest ...............................        2.72 sec
test_softmax_op .............................        2.73 sec
test_cast_op ................................        2.76 sec
device_context_test .........................        2.81 sec
test_prelu_op ...............................        2.81 sec
test_Compare ................................        2.83 sec
test_sgd_op .................................        2.83 sec
testTrain ...................................        2.84 sec
test_proximal_adagrad_op ....................        2.87 sec
test_scatter_op .............................        2.93 sec
test_linear_chain_crf_op ....................        2.94 sec
test_fill_constant_batch_size_like_op .......        2.95 sec
decorator_test ..............................        3.01 sec
test_ThreadBarrier ..........................        3.01 sec
test_scale_op ...............................        3.02 sec
test_fill_zeros_like_op .....................        3.05 sec
test_proximal_gd_op .........................        3.10 sec
BlockExpandOpTest ...........................        3.11 sec
test_huber_loss_op ..........................        3.11 sec
TensorShapeTest .............................        3.12 sec
test_increment_op ...........................        3.12 sec
test_Expand .................................        3.14 sec
test_sequence_softmax_op ....................        3.14 sec
test_l1_norm_op .............................        3.17 sec
MulOpTest ...................................        3.21 sec
test_multiplex_op ...........................        3.21 sec
test_clip_op ................................        3.22 sec
test_common .................................        3.23 sec
test_pad_op .................................        3.25 sec
test_recurrent_machine_generation ...........        3.32 sec
test_split_op ...............................        3.33 sec
test_lookup_table_op ........................        3.39 sec
tensor_test .................................        3.41 sec
test_argument ...............................        3.42 sec
test_mean_op ................................        3.44 sec
test_conv_shift_op ..........................        3.46 sec
test_adadelta_op ............................        3.49 sec
vol2col_test ................................        3.51 sec
test_adam_op ................................        3.54 sec
im2col_test .................................        3.61 sec
test_word2vec ...............................        3.64 sec
CosSimOpTest ................................        3.71 sec
test_SeqSliceLayerGrad ......................        3.74 sec
test_batch_norm_op ..........................        3.74 sec
test_KmaxSeqScore ...........................        3.75 sec
test_uniform_random_op ......................        3.77 sec
test_sum_op .................................        3.78 sec
CrossMapNormalOpTest ........................        3.83 sec
test_rmsprop_op .............................        3.87 sec
test_elementwise_mul_op .....................        3.90 sec
test_ConvUnify ..............................        3.92 sec
test_ActivationGrad .........................        3.93 sec
test_WarpCTCLayer ...........................        3.93 sec
test_SpinLock ...............................        3.95 sec
test_BatchNorm ..............................        4.02 sec
test_StringUtils ............................        4.03 sec
SwitchOpTest ................................        4.12 sec
test_sign_op ................................        4.13 sec
test_softmax_with_cross_entropy_op ..........        4.15 sec
test_SparseMatrix ...........................        4.21 sec
test_fit_a_line .............................        4.24 sec
test_smooth_l1_loss_op ......................        4.24 sec
test_SIMDFlags ..............................        4.35 sec
test_gaussian_random_op .....................        4.35 sec
test_decayed_adagrad_op .....................        4.36 sec
test_SIMDFunctions ..........................        4.41 sec
test_momentum_op ............................        4.41 sec
test_top_k_op ...............................        4.43 sec
test_rank_loss_op ...........................        4.51 sec
test_CrossEntropyOverBeamGrad ...............        4.52 sec
test_RowBuffer ..............................        4.61 sec
test_CustomStackTracePrint ..................        4.65 sec
test_LinearChainCRF .........................        4.65 sec
test_elementwise_sub_op .....................        4.66 sec
test_seq_expand .............................        4.69 sec
test_gather_op ..............................        4.70 sec
test_ExecViaCpu .............................        4.74 sec
test_PyDataProvider .........................        4.74 sec
test_conv2d_transpose_op ....................        4.77 sec
test_PriorBox ...............................        4.78 sec
test_transpose_op ...........................        4.81 sec
test_Error ..................................        4.85 sec
test_DetectionOutput ........................        4.88 sec
test_concat_op ..............................        4.91 sec
test_adagrad_op .............................        4.96 sec
test_pool2d_op ..............................        5.03 sec
test_reshape_op .............................        5.05 sec
test_reduce_op ..............................        5.06 sec
CropOpTest ..................................        5.14 sec
test_fill_constant_op .......................        5.15 sec
test_minus_op ...............................        5.15 sec
test_squared_l2_distance_op .................        5.15 sec
test_adamax_op ..............................        5.21 sec
test_paramconf_order ........................        5.23 sec
test_margin_rank_loss_op ....................        5.29 sec
GemmConvOpTest ..............................        5.44 sec
test_MultinomialSampler .....................        5.44 sec
test_Evaluator ..............................        5.50 sec
test_CRFLayerGrad ...........................        5.52 sec
test_pool_max_op ............................        5.55 sec
test_NetworkCompare .........................        5.69 sec
test_CpuGpuVector ...........................        5.73 sec
test_GpuProfiler ............................        5.78 sec
test_gru_op .................................        5.82 sec
test_conv3d_transpose_op ....................        5.88 sec
test_activation_op ..........................        5.91 sec
test_elementwise_add_op .....................        6.10 sec
test_cross_entropy_op .......................        6.12 sec
test_crop_op ................................        6.40 sec
test_matmul_op ..............................        6.42 sec
test_pool3d_op ..............................        6.53 sec
test_cos_sim_op .............................        6.63 sec
test_perturbation ...........................        6.63 sec
test_elementwise_div_op .....................        6.94 sec
test_FPException ............................        7.07 sec
RowConvOpTest ...............................        7.52 sec
testTrainer .................................        7.69 sec
test_Allocator ..............................        7.98 sec
PadOpTest ...................................        8.43 sec
pserver_client_test .........................        8.75 sec
test_dropout_op .............................        8.80 sec
ContextProjectionOpTest .....................        8.83 sec
test_MKLDNN .................................        8.88 sec
socket_test .................................        9.17 sec
test_RecurrentGradientMachine ...............        9.75 sec
test_seq_pool ...............................       10.46 sec
test_conv2d_op ..............................       11.85 sec
test_ProtoDataProvider ......................       13.54 sec
test_lstm_op ................................       14.14 sec
test_conv3d_op ..............................       14.21 sec
test_sparseMatrixCompare ....................       15.37 sec
test_ConvTrans ..............................       15.65 sec
master_test .................................       18.41 sec
test_image_classification_train .............       20.18 sec
test_PyDataProvider2 ........................       20.27 sec
test_recognize_digits_conv ..................       22.81 sec
test_CustomStackTrace .......................       24.03 sec
test_layerHelpers ...........................       26.00 sec
DepthwiseConvOpTest .........................       26.77 sec
test_Matrix .................................       27.46 sec
test_TrainerOnePass .........................       32.17 sec
test_TrainingAlgorithm ......................       32.81 sec
Im2ColTest ..................................       33.02 sec
test_seq_conv ...............................       33.14 sec
test_ParameterServer2 .......................       37.81 sec
test_BaseMatrix .............................       41.53 sec
test_RecurrentLayer .........................       49.66 sec
test_matrixCompare ..........................       51.42 sec
test_understand_sentiment_conv ..............       52.29 sec
test_CompareTwoNets .........................       54.21 sec
test_mul_op .................................       55.60 sec
test_recommender_system .....................       63.55 sec
test_ProtoServer ............................       72.85 sec
test_CompareSparse ..........................       80.51 sec
test_LayerGrad ..............................      130.69 sec

[luotao1]

@chengduoZH 能否按照时间从大到小进行排列。另外，请问这个数据是从哪儿获得的呢？从teamcity的log来看，并不是这样的。https://paddleci.ngrok.io/viewLog.html?buildId=18018&buildTypeId=Paddle_PrCi

[luotao1]

[04:39:43] :	 [Step 1/1]   1/272 Test   #2: parameter_optimizer_test ....................   Passed    0.21 sec
[04:39:43] :	 [Step 1/1]   2/272 Test   #1: serialization_test ..........................   Passed    0.61 sec
[04:39:43] :	 [Step 1/1]         Start   9: TensorTypeTest
[04:39:43] :	 [Step 1/1]         Start  10: BufferArgTest
[04:39:45] :	 [Step 1/1]   3/272 Test   #6: network_helper_test .........................   Passed    1.71 sec
[04:39:45] :	 [Step 1/1]   4/272 Test   #9: TensorTypeTest ..............................   Passed    1.50 sec
[04:39:45] :	 [Step 1/1]         Start  11: FunctionTest
[04:39:45] :	 [Step 1/1]         Start  12: ContextProjectionOpTest
[04:39:45] :	 [Step 1/1]   5/272 Test   #4: pserver_test ................................   Passed    2.21 sec
[04:39:45] :	 [Step 1/1]   6/272 Test   #7: CrossMapNormalOpTest ........................   Passed    2.40 sec
[04:39:45] :	 [Step 1/1]   7/272 Test   #8: TensorShapeTest .............................   Passed    2.60 sec
[04:39:45] :	 [Step 1/1]         Start  13: PadOpTest
[04:39:45] :	 [Step 1/1]         Start  14: MulOpTest
[04:39:45] :	 [Step 1/1]         Start  15: CosSimOpTest
[04:39:46] :	 [Step 1/1]   8/272 Test  #10: BufferArgTest ...............................   Passed    2.30 sec
[04:39:46] :	 [Step 1/1]         Start  16: RowConvOpTest
[04:39:47] :	 [Step 1/1]   9/272 Test  #11: FunctionTest ................................   Passed    1.61 sec
[04:39:47] :	 [Step 1/1]         Start  17: BlockExpandOpTest
[04:39:47] :	 [Step 1/1]  10/272 Test  #15: CosSimOpTest ................................   Passed    2.01 sec
[04:39:47] :	 [Step 1/1]  11/272 Test  #14: MulOpTest ...................................   Passed    2.01 sec
[04:39:47] :	 [Step 1/1]         Start  18: CropOpTest
[04:39:47] :	 [Step 1/1]         Start  19: SwitchOpTest
[04:39:48] :	 [Step 1/1]  12/272 Test  #12: ContextProjectionOpTest .....................   Passed    2.82 sec
[04:39:48] :	 [Step 1/1]         Start  20: ScaleSubRegionOpTest
[04:39:49] :	 [Step 1/1]  13/272 Test  #17: BlockExpandOpTest ...........................   Passed    1.81 sec
[04:39:49] :	 [Step 1/1]  14/272 Test  #13: PadOpTest ...................................   Passed    3.31 sec
[04:39:49] :	 [Step 1/1]         Start  21: Im2ColTest
[04:39:49] :	 [Step 1/1]         Start  22: GemmConvOpTest
[04:39:50] :	 [Step 1/1]  15/272 Test  #19: SwitchOpTest ................................   Passed    1.50 sec
[04:39:50] :	 [Step 1/1]  16/272 Test  #16: RowConvOpTest ...............................   Passed    3.01 sec
[04:39:50] :	 [Step 1/1]         Start  23: DepthwiseConvOpTest
[04:39:50] :	 [Step 1/1]         Start  24: test_Thread
[04:39:50] :	 [Step 1/1]  17/272 Test  #18: CropOpTest ..................................   Passed    2.61 sec
[04:39:50] :	 [Step 1/1]         Start  25: test_StringUtils
[04:39:51] :	 [Step 1/1]  18/272 Test  #20: ScaleSubRegionOpTest ........................   Passed    2.31 sec
[04:39:51] :	 [Step 1/1]         Start  26: test_CustomStackTrace
[04:39:52] :	 [Step 1/1]  19/272 Test   #5: pserver_client_test .........................   Passed    8.24 sec
[04:39:52] :	 [Step 1/1]  20/272 Test  #24: test_Thread .................................   Passed    1.71 sec
[04:39:52] :	 [Step 1/1]         Start  27: test_ThreadBarrier
[04:39:52] :	 [Step 1/1]         Start  28: test_SpinLock
[04:39:52] :	 [Step 1/1]  21/272 Test  #22: GemmConvOpTest ..............................   Passed    3.34 sec
[04:39:52] :	 [Step 1/1]         Start  29: test_SIMDFlags
[04:39:54] :	 [Step 1/1]  22/272 Test  #28: test_SpinLock ...............................   Passed    2.03 sec
[04:39:54] :	 [Step 1/1]  23/272 Test  #29: test_SIMDFlags ..............................   Passed    1.41 sec
[04:39:54] :	 [Step 1/1]         Start  30: test_Error
[04:39:54] :	 [Step 1/1]         Start  31: test_CustomStackTracePrint
[04:39:54] :	 [Step 1/1]  24/272 Test  #27: test_ThreadBarrier ..........................   Passed    2.44 sec
[04:39:54] :	 [Step 1/1]         Start  32: test_ExecViaCpu
[04:39:55] :	 [Step 1/1]  25/272 Test  #30: test_Error ..................................   Passed    1.20 sec
[04:39:55] :	 [Step 1/1]         Start  33: test_SIMDFunctions
[04:39:56] :	 [Step 1/1]  26/272 Test  #32: test_ExecViaCpu .............................   Passed    1.80 sec
[04:39:56] :	 [Step 1/1]  27/272 Test  #33: test_SIMDFunctions ..........................   Passed    1.20 sec
[04:39:56] :	 [Step 1/1]         Start  34: test_TrainingAlgorithm
[04:39:56] :	 [Step 1/1]         Start  35: test_SparseMatrix
[04:39:57] :	 [Step 1/1]  28/272 Test  #21: Im2ColTest ..................................   Passed    7.97 sec
[04:39:57] :	 [Step 1/1]         Start  36: test_RowBuffer
[04:39:58] :	 [Step 1/1]  29/272 Test  #23: DepthwiseConvOpTest .........................   Passed    7.77 sec
[04:39:58] :	 [Step 1/1]  30/272 Test  #35: test_SparseMatrix ...........................   Passed    1.11 sec
[04:39:58] :	 [Step 1/1]         Start  37: test_matrixCompare
[04:39:58] :	 [Step 1/1]         Start  38: test_sparseMatrixCompare
[04:40:01] :	 [Step 1/1]  31/272 Test  #38: test_sparseMatrixCompare ....................   Passed    3.04 sec
[04:40:01] :	 [Step 1/1]         Start  39: test_perturbation
[04:40:02] :	 [Step 1/1]  32/272 Test  #39: test_perturbation ...........................   Passed    1.40 sec
[04:40:02] :	 [Step 1/1]         Start  40: test_CpuGpuVector
[04:40:04] :	 [Step 1/1]  33/272 Test  #40: test_CpuGpuVector ...........................   Passed    1.40 sec
[04:40:04] :	 [Step 1/1]         Start  41: test_Allocator
[04:40:05] :	 [Step 1/1]  34/272 Test  #37: test_matrixCompare ..........................   Passed    7.36 sec
[04:40:05] :	 [Step 1/1]  35/272 Test  #41: test_Allocator ..............................   Passed    1.30 sec
[04:40:05] :	 [Step 1/1]         Start  42: test_FPException
[04:40:05] :	 [Step 1/1]         Start  43: test_GpuProfiler
[04:40:07] :	 [Step 1/1]  36/272 Test  #34: test_TrainingAlgorithm ......................   Passed    9.77 sec
[04:40:07] :	 [Step 1/1]  37/272 Test  #42: test_FPException ............................   Passed    1.30 sec
[04:40:07] :	 [Step 1/1]         Start  44: test_BaseMatrix
[04:40:07] :	 [Step 1/1]         Start  45: test_Matrix
[04:40:07] :	 [Step 1/1]  38/272 Test  #43: test_GpuProfiler ............................   Passed    1.81 sec
[04:40:07] :	 [Step 1/1]         Start  46: test_LinearChainCRF
[04:40:09] :	 [Step 1/1]  39/272 Test  #46: test_LinearChainCRF .........................   Passed    1.21 sec
[04:40:09] :	 [Step 1/1]         Start  47: test_MultinomialSampler
[04:40:10] :	 [Step 1/1]  40/272 Test  #31: test_CustomStackTracePrint ..................   Passed   16.80 sec
[04:40:10] :	 [Step 1/1]         Start  48: test_RecurrentLayer
[04:40:11] :	 [Step 1/1]  41/272 Test  #36: test_RowBuffer ..............................   Passed   13.59 sec
[04:40:11] :	 [Step 1/1]  42/272 Test  #47: test_MultinomialSampler .....................   Passed    1.82 sec
[04:40:11] :	 [Step 1/1]         Start  49: test_LayerGrad
[04:40:11] :	 [Step 1/1]         Start  50: test_CRFLayerGrad
[04:40:12] :	 [Step 1/1]  43/272 Test  #25: test_StringUtils ............................   Passed   21.38 sec
[04:40:12] :	 [Step 1/1]         Start  51: test_CrossEntropyOverBeamGrad
[04:40:14] :	 [Step 1/1]  44/272 Test  #26: test_CustomStackTrace .......................   Passed   22.69 sec
[04:40:14] :	 [Step 1/1]  45/272 Test  #50: test_CRFLayerGrad ...........................   Passed    2.52 sec
[04:40:14] :	 [Step 1/1]  46/272 Test  #51: test_CrossEntropyOverBeamGrad ...............   Passed    1.81 sec
[04:40:14] :	 [Step 1/1]         Start  52: test_SeqSliceLayerGrad
[04:40:14] :	 [Step 1/1]         Start  53: test_ActivationGrad
[04:40:14] :	 [Step 1/1]         Start  54: test_ConvTrans
[04:40:15] :	 [Step 1/1]  47/272 Test  #45: test_Matrix .................................   Passed    8.08 sec
[04:40:15] :	 [Step 1/1]  48/272 Test  #44: test_BaseMatrix .............................   Passed    8.38 sec
[04:40:15] :	 [Step 1/1]         Start  55: test_PriorBox
[04:40:15] :	 [Step 1/1]         Start  56: test_DetectionOutput
[04:40:16] :	 [Step 1/1]  49/272 Test  #53: test_ActivationGrad .........................   Passed    1.83 sec
[04:40:16] :	 [Step 1/1]         Start  57: test_ConvUnify
[04:40:17] :	 [Step 1/1]  50/272 Test  #52: test_SeqSliceLayerGrad ......................   Passed    2.25 sec
[04:40:17] :	 [Step 1/1]  51/272 Test  #54: test_ConvTrans ..............................   Passed    2.65 sec
[04:40:17] :	 [Step 1/1]         Start  58: test_BatchNorm
[04:40:17] :	 [Step 1/1]         Start  59: test_KmaxSeqScore
[04:40:17] :	 [Step 1/1]  52/272 Test   #3: master_test .................................   Passed   33.79 sec
[04:40:17] :	 [Step 1/1]  53/272 Test  #55: test_PriorBox ...............................   Passed    1.75 sec
[04:40:17] :	 [Step 1/1]         Start  60: test_Expand
[04:40:17] :	 [Step 1/1]         Start  61: test_MKLDNN
[04:40:18] :	 [Step 1/1]  54/272 Test  #56: test_DetectionOutput ........................   Passed    2.27 sec
[04:40:18] :	 [Step 1/1]         Start  62: test_PyDataProvider
[04:40:19] :	 [Step 1/1]  55/272 Test  #57: test_ConvUnify ..............................   Passed    2.36 sec
[04:40:19] :	 [Step 1/1]         Start  63: test_WarpCTCLayer
[04:40:20] :	 [Step 1/1]  56/272 Test  #59: test_KmaxSeqScore ...........................   Passed    3.36 sec
[04:40:20] :	 [Step 1/1]  57/272 Test  #60: test_Expand .................................   Passed    2.87 sec
[04:40:20] :	 [Step 1/1]         Start  64: test_ProtoDataProvider
[04:40:20] :	 [Step 1/1]         Start  65: test_Evaluator
[04:40:21] :	 [Step 1/1]  58/272 Test  #58: test_BatchNorm ..............................   Passed    3.52 sec
[04:40:21] :	 [Step 1/1]         Start  66: test_RecurrentGradientMachine
[04:40:22] :	 [Step 1/1]  59/272 Test  #62: test_PyDataProvider .........................   Passed    3.19 sec
[04:40:22] :	 [Step 1/1]         Start  67: test_NetworkCompare
[04:40:22] :	 [Step 1/1]  60/272 Test  #63: test_WarpCTCLayer ...........................   Passed    3.30 sec
[04:40:22] :	 [Step 1/1]         Start  68: test_PyDataProvider2
[04:40:25] :	 [Step 1/1]  61/272 Test  #67: test_NetworkCompare .........................   Passed    3.57 sec
[04:40:25] :	 [Step 1/1]  62/272 Test  #65: test_Evaluator ..............................   Passed    4.98 sec
[04:40:25] :	 [Step 1/1]         Start  69: test_common
[04:40:25] :	 [Step 1/1]         Start  70: test_argument
[04:40:28] :	 [Step 1/1]  63/272 Test  #70: test_argument ...............................   Passed    2.13 sec
[04:40:28] :	 [Step 1/1]  64/272 Test  #64: test_ProtoDataProvider ......................   Passed    7.46 sec
[04:40:28] :	 [Step 1/1]         Start  71: socket_test
[04:40:28] :	 [Step 1/1]         Start  72: test_ProtoServer
[04:40:29] :	 [Step 1/1]  65/272 Test  #61: test_MKLDNN .................................   Passed   11.33 sec
[04:40:29] :	 [Step 1/1]  66/272 Test  #69: test_common .................................   Passed    3.35 sec
[04:40:29] :	 [Step 1/1]         Start  73: test_ParameterServer2
[04:40:29] :	 [Step 1/1]         Start  74: test_Compare
[04:40:31] :	 [Step 1/1]  67/272 Test  #74: test_Compare ................................   Passed    1.81 sec
[04:40:31] :	 [Step 1/1]         Start  75: test_Trainer
[04:40:32] :	 [Step 1/1]  68/272 Test  #66: test_RecurrentGradientMachine ...............   Passed   10.46 sec
[04:40:32] :	 [Step 1/1]  69/272 Test  #49: test_LayerGrad ..............................   Passed   20.45 sec
[04:40:32] :	 [Step 1/1]         Start  76: test_TrainerOnePass
[04:40:32] :	 [Step 1/1]         Start  77: test_CompareTwoNets
[04:40:33] :	 [Step 1/1]  70/272 Test  #71: socket_test .................................   Passed    4.94 sec
[04:40:33] :	 [Step 1/1]         Start  78: test_CompareTwoOpts
[04:40:33] :	 [Step 1/1]  71/272 Test  #75: test_Trainer ................................   Passed    1.61 sec
[04:40:33] :	 [Step 1/1]         Start  79: test_CompareSparse
[04:40:36] :	 [Step 1/1]  72/272 Test  #78: test_CompareTwoOpts .........................   Passed    2.42 sec
[04:40:36] :	 [Step 1/1]         Start  80: test_recurrent_machine_generation
[04:40:37] :	 [Step 1/1]  73/272 Test  #48: test_RecurrentLayer .........................   Passed   26.72 sec
[04:40:37] :	 [Step 1/1]         Start  81: test_PyDataProviderWrapper
[04:40:39] :	 [Step 1/1]  74/272 Test  #80: test_recurrent_machine_generation ...........   Passed    2.41 sec
[04:40:39] :	 [Step 1/1]         Start  82: test_config_parser
[04:40:39] :	 [Step 1/1]  75/272 Test  #82: test_config_parser ..........................   Passed    0.17 sec
[04:40:39] :	 [Step 1/1]  76/272 Test  #81: test_PyDataProviderWrapper ..................   Passed    2.17 sec
[04:40:39] :	 [Step 1/1]         Start  83: stringpiece_test
[04:40:39] :	 [Step 1/1]         Start  84: stringprintf_test
[04:40:40] :	 [Step 1/1]  77/272 Test  #84: stringprintf_test ...........................   Passed    0.30 sec
[04:40:40] :	 [Step 1/1]  78/272 Test  #83: stringpiece_test ............................   Passed    0.60 sec
[04:40:40] :	 [Step 1/1]         Start  85: to_string_test
[04:40:40] :	 [Step 1/1]         Start  86: memory_test
[04:40:41] :	 [Step 1/1]  79/272 Test  #85: to_string_test ..............................   Passed    0.70 sec
[04:40:41] :	 [Step 1/1]         Start  87: system_allocator_test
[04:40:41] :	 [Step 1/1]  80/272 Test  #86: memory_test .................................   Passed    0.80 sec
[04:40:41] :	 [Step 1/1]  81/272 Test  #87: system_allocator_test .......................   Passed    0.60 sec
[04:40:41] :	 [Step 1/1]         Start  88: cpu_info_test
[04:40:41] :	 [Step 1/1]         Start  89: place_test
[04:40:42] :	 [Step 1/1]  82/272 Test  #88: cpu_info_test ...............................   Passed    0.30 sec
[04:40:42] :	 [Step 1/1]  83/272 Test  #89: place_test ..................................   Passed    0.60 sec
[04:40:42] :	 [Step 1/1]         Start  90: enforce_test
[04:40:42] :	 [Step 1/1]         Start  91: device_context_test
[04:40:43] :	 [Step 1/1]  84/272 Test  #90: enforce_test ................................   Passed    0.31 sec
[04:40:43] :	 [Step 1/1]         Start  92: cudnn_helper_test
[04:40:43] :	 [Step 1/1]  85/272 Test  #68: test_PyDataProvider2 ........................   Passed   20.42 sec
[04:40:43] :	 [Step 1/1]  86/272 Test  #92: cudnn_helper_test ...........................   Passed    0.23 sec
[04:40:43] :	 [Step 1/1]         Start  93: transform_test
[04:40:43] :	 [Step 1/1]         Start  94: nccl_test
[04:40:44] :	 [Step 1/1]  87/272 Test  #94: nccl_test ...................................   Passed    0.12 sec
[04:40:44] :	 [Step 1/1]         Start  95: ddim_test
[04:40:45] :	 [Step 1/1]  88/272 Test  #95: ddim_test ...................................   Passed    0.10 sec
[04:40:45] :	 [Step 1/1]  89/272 Test  #91: device_context_test .........................   Passed    2.69 sec
[04:40:45] :	 [Step 1/1]         Start  96: dim_test
[04:40:45] :	 [Step 1/1]         Start  97: tensor_test
[04:40:45] :	 [Step 1/1]  90/272 Test  #96: dim_test ....................................   Passed    0.71 sec
[04:40:45] :	 [Step 1/1]         Start  98: eigen_test
[04:40:46] :	 [Step 1/1]  91/272 Test  #93: transform_test ..............................   Passed    2.34 sec
[04:40:46] :	 [Step 1/1]  92/272 Test  #98: eigen_test ..................................   Passed    0.60 sec
[04:40:46] :	 [Step 1/1]         Start  99: lod_tensor_test
[04:40:46] :	 [Step 1/1]         Start 100: lod_tensor_gpu_test
[04:40:47] :	 [Step 1/1]  93/272 Test #100: lod_tensor_gpu_test .........................   Passed    0.71 sec
[04:40:47] :	 [Step 1/1]         Start 101: variable_test
[04:40:47] :	 [Step 1/1]  94/272 Test  #97: tensor_test .................................   Passed    2.13 sec
[04:40:47] :	 [Step 1/1]  95/272 Test  #99: lod_tensor_test .............................   Passed    0.92 sec
[04:40:47] :	 [Step 1/1]  96/272 Test #101: variable_test ...............................   Passed    0.50 sec
[04:40:47] :	 [Step 1/1]         Start 102: scope_test
[04:40:47] :	 [Step 1/1]         Start 103: program_desc_test
[04:40:47] :	 [Step 1/1]         Start 104: op_proto_maker_test
[04:40:48] :	 [Step 1/1]  97/272 Test #104: op_proto_maker_test .........................   Passed    0.20 sec
[04:40:48] :	 [Step 1/1]  98/272 Test #102: scope_test ..................................   Passed    0.50 sec
[04:40:48] :	 [Step 1/1]  99/272 Test #103: program_desc_test ...........................   Passed    0.50 sec
[04:40:48] :	 [Step 1/1]         Start 105: operator_test
[04:40:48] :	 [Step 1/1]         Start 106: op_registry_test
[04:40:48] :	 [Step 1/1]         Start 107: backward_test
[04:40:48] :	 [Step 1/1] 100/272 Test #105: operator_test ...............................   Passed    0.20 sec
[04:40:48] :	 [Step 1/1] 101/272 Test #107: backward_test ...............................   Passed    0.50 sec
[04:40:48] :	 [Step 1/1] 102/272 Test #106: op_registry_test ............................   Passed    0.50 sec
[04:40:48] :	 [Step 1/1]         Start 108: prune_test
[04:40:48] :	 [Step 1/1]         Start 109: tensor_array_test
[04:40:48] :	 [Step 1/1]         Start 110: var_type_inference_test
[04:40:49] :	 [Step 1/1] 103/272 Test #108: prune_test ..................................   Passed    0.20 sec
[04:40:49] :	 [Step 1/1] 104/272 Test #110: var_type_inference_test .....................   Passed    0.50 sec
[04:40:49] :	 [Step 1/1] 105/272 Test #109: tensor_array_test ...........................   Passed    0.50 sec
[04:40:49] :	 [Step 1/1]         Start 111: selected_rows_test
[04:40:49] :	 [Step 1/1]         Start 112: gather_test
[04:40:49] :	 [Step 1/1]         Start 113: net_op_test
[04:40:49] :	 [Step 1/1] 106/272 Test #111: selected_rows_test ..........................   Passed    0.26 sec
[04:40:49] :	 [Step 1/1] 107/272 Test #113: net_op_test .................................   Passed    0.56 sec
[04:40:49] :	 [Step 1/1] 108/272 Test #112: gather_test .................................   Passed    0.56 sec
[04:40:49] :	 [Step 1/1]         Start 114: scatter_test
[04:40:49] :	 [Step 1/1]         Start 115: strided_memcpy_test
[04:40:49] :	 [Step 1/1]         Start 116: dynamic_recurrent_op_test
[04:40:50] :	 [Step 1/1] 109/272 Test #114: scatter_test ................................   Passed    0.20 sec
[04:40:50] :	 [Step 1/1] 110/272 Test #116: dynamic_recurrent_op_test ...................   Passed    0.50 sec
[04:40:50] :	 [Step 1/1]         Start 117: nccl_op_test
[04:40:50] :	 [Step 1/1]         Start 118: save_load_op_test
[04:40:50] :	 [Step 1/1] 111/272 Test #117: nccl_op_test ................................   Passed    0.21 sec
[04:40:50] :	 [Step 1/1]         Start 119: math_function_gpu_test
[04:40:51] :	 [Step 1/1] 112/272 Test #118: save_load_op_test ...........................   Passed    1.31 sec
[04:40:51] :	 [Step 1/1] 113/272 Test #115: strided_memcpy_test .........................   Passed    1.91 sec
[04:40:51] :	 [Step 1/1]         Start 120: selected_rows_functor_gpu_test
[04:40:51] :	 [Step 1/1]         Start 121: math_function_test
[04:40:52] :	 [Step 1/1] 114/272 Test #121: math_function_test ..........................   Passed    0.60 sec
[04:40:52] :	 [Step 1/1]         Start 122: selected_rows_functor_test
[04:40:53] :	 [Step 1/1] 115/272 Test #122: selected_rows_functor_test ..................   Passed    0.66 sec
[04:40:53] :	 [Step 1/1]         Start 123: im2col_test
[04:40:53] :	 [Step 1/1] 116/272 Test #120: selected_rows_functor_gpu_test ..............   Passed    2.16 sec
[04:40:53] :	 [Step 1/1]         Start 124: vol2col_test
[04:40:54] :	 [Step 1/1] 117/272 Test  #72: test_ProtoServer ............................   Passed   26.15 sec
[04:40:54] :	 [Step 1/1] 118/272 Test #123: im2col_test .................................   Passed    1.20 sec
[04:40:54] :	 [Step 1/1]         Start 125: testTrain
[04:40:54] :	 [Step 1/1]         Start 126: testMatrix
[04:40:55] :	 [Step 1/1] 119/272 Test #124: vol2col_test ................................   Passed    1.31 sec
[04:40:55] :	 [Step 1/1]         Start 127: testVector
[04:40:56] :	 [Step 1/1] 120/272 Test #126: testMatrix ..................................   Passed    2.02 sec
[04:40:56] :	 [Step 1/1] 121/272 Test #127: testVector ..................................   Passed    1.49 sec
[04:40:56] :	 [Step 1/1]         Start 128: testTrainer
[04:40:56] :	 [Step 1/1]         Start 129: testArguments
[04:40:57] :	 [Step 1/1] 122/272 Test #129: testArguments ...............................   Passed    0.72 sec
[04:40:57] :	 [Step 1/1]         Start 130: testGradientMachine
[04:40:59] :	 [Step 1/1] 123/272 Test #130: testGradientMachine .........................   Passed    2.32 sec
[04:40:59] :	 [Step 1/1]         Start 131: layers_test
[04:41:00] :	 [Step 1/1] 124/272 Test #125: testTrain ...................................   Passed    5.86 sec
[04:41:00] :	 [Step 1/1] 125/272 Test #131: layers_test .................................   Passed    0.61 sec
[04:41:00] :	 [Step 1/1]         Start 132: test_reset_hook
[04:41:00] :	 [Step 1/1]         Start 133: test_layerHelpers
[04:41:00] :	 [Step 1/1] 126/272 Test #119: math_function_gpu_test ......................   Passed    9.43 sec
[04:41:00] :	 [Step 1/1] 127/272 Test #132: test_reset_hook .............................   Passed    0.50 sec
[04:41:00] :	 [Step 1/1]         Start 134: test_op
[04:41:00] :	 [Step 1/1]         Start 135: test_image
[04:41:02] :	 [Step 1/1] 128/272 Test  #76: test_TrainerOnePass .........................   Passed   29.48 sec
[04:41:02] :	 [Step 1/1]         Start 136: test_layer
[04:41:03] :	 [Step 1/1] 129/272 Test #134: test_op .....................................   Passed    2.52 sec
[04:41:03] :	 [Step 1/1] 130/272 Test #135: test_image ..................................   Passed    2.82 sec
[04:41:03] :	 [Step 1/1]         Start 137: test_topology
[04:41:03] :	 [Step 1/1]         Start 138: test_rnn_layer
[04:41:04] :	 [Step 1/1] 131/272 Test #136: test_layer ..................................   Passed    1.72 sec
[04:41:04] :	 [Step 1/1] 132/272 Test #128: testTrainer .................................   Passed    7.59 sec
[04:41:04] :	 [Step 1/1]         Start 139: test_parameters
[04:41:04] :	 [Step 1/1]         Start 140: test_data_feeder
[04:41:05] :	 [Step 1/1] 133/272 Test #137: test_topology ...............................   Passed    1.63 sec
[04:41:05] :	 [Step 1/1] 134/272 Test #138: test_rnn_layer ..............................   Passed    1.83 sec
[04:41:05] :	 [Step 1/1]         Start 141: test_paramconf_order
[04:41:05] :	 [Step 1/1]         Start 142: creator_test
[04:41:06] :	 [Step 1/1] 135/272 Test #139: test_parameters .............................   Passed    2.18 sec
[04:41:06] :	 [Step 1/1]         Start 143: decorator_test
[04:41:07] :	 [Step 1/1] 136/272 Test #140: test_data_feeder ............................   Passed    2.59 sec
[04:41:07] :	 [Step 1/1]         Start 144: test_ploter
[04:41:08] :	 [Step 1/1] 137/272 Test #142: creator_test ................................   Passed    1.93 sec
[04:41:08] :	 [Step 1/1]         Start 145: test_word2vec
[04:41:09] :	 [Step 1/1] 138/272 Test #143: decorator_test ..............................   Passed    3.01 sec
[04:41:09] :	 [Step 1/1]         Start 146: test_cos_sim_op
[04:41:10] :	 [Step 1/1] 139/272 Test #144: test_ploter .................................   Passed    2.51 sec
[04:41:10] :	 [Step 1/1] 140/272 Test  #73: test_ParameterServer2 .......................   Passed   40.33 sec
[04:41:10] :	 [Step 1/1]         Start 147: test_program
[04:41:10] :	 [Step 1/1]         Start 148: test_exception
[04:41:10] :	 [Step 1/1] 141/272 Test #145: test_word2vec ...............................   Passed    2.34 sec
[04:41:10] :	 [Step 1/1]         Start 149: test_auc_op
[04:41:11] :	 [Step 1/1] 142/272 Test #148: test_exception ..............................   Passed    1.04 sec
[04:41:11] :	 [Step 1/1] 143/272 Test #147: test_program ................................   Passed    1.08 sec
[04:41:11] :	 [Step 1/1] 144/272 Test #141: test_paramconf_order ........................   Passed    5.45 sec
[04:41:11] :	 [Step 1/1]         Start 150: test_pool3d_op
[04:41:11] :	 [Step 1/1]         Start 151: test_selected_rows
[04:41:11] :	 [Step 1/1]         Start 152: test_pool2d_op
[04:41:12] :	 [Step 1/1] 145/272 Test #149: test_auc_op .................................   Passed    1.57 sec
[04:41:12] :	 [Step 1/1] 146/272 Test #146: test_cos_sim_op .............................   Passed    3.37 sec
[04:41:12] :	 [Step 1/1]         Start 153: test_image_classification_train
[04:41:12] :	 [Step 1/1]         Start 154: test_matmul_op
[04:41:13] :	 [Step 1/1] 147/272 Test #151: test_selected_rows ..........................   Passed    2.00 sec
[04:41:13] :	 [Step 1/1]         Start 155: test_crop_op
[04:41:15] :	 [Step 1/1] 148/272 Test #152: test_pool2d_op ..............................   Passed    4.10 sec
[04:41:15] :	 [Step 1/1]         Start 156: test_executor_and_mul
[04:41:16] :	 [Step 1/1] 149/272 Test #150: test_pool3d_op ..............................   Passed    4.82 sec
[04:41:16] :	 [Step 1/1]         Start 157: test_modified_huber_loss_op
[04:41:17] :	 [Step 1/1] 150/272 Test #155: test_crop_op ................................   Passed    3.45 sec
[04:41:17] :	 [Step 1/1] 151/272 Test #156: test_executor_and_mul .......................   Passed    1.33 sec
[04:41:17] :	 [Step 1/1]         Start 158: test_adamax_op
[04:41:17] :	 [Step 1/1]         Start 159: test_operator_desc
[04:41:18] :	 [Step 1/1] 152/272 Test #157: test_modified_huber_loss_op .................   Passed    1.74 sec
[04:41:18] :	 [Step 1/1]         Start 160: test_seq_conv
[04:41:19] :	 [Step 1/1] 153/272 Test #159: test_operator_desc ..........................   Passed    2.01 sec
[04:41:19] :	 [Step 1/1]         Start 161: test_scope
[04:41:21] :	 [Step 1/1] 154/272 Test #154: test_matmul_op ..............................   Passed    8.47 sec
[04:41:21] :	 [Step 1/1] 155/272 Test #161: test_scope ..................................   Passed    2.10 sec
[04:41:21] :	 [Step 1/1] 156/272 Test #158: test_adamax_op ..............................   Passed    4.41 sec
[04:41:21] :	 [Step 1/1]         Start 162: test_regularizer
[04:41:21] :	 [Step 1/1]         Start 163: test_top_k_op
[04:41:21] :	 [Step 1/1]         Start 164: test_accuracy_op
[04:41:23] :	 [Step 1/1] 157/272 Test #162: test_regularizer ............................   Passed    1.13 sec
[04:41:23] :	 [Step 1/1]         Start 165: test_gaussian_random_op
[04:41:24] :	 [Step 1/1] 158/272 Test #163: test_top_k_op ...............................   Passed    2.58 sec
[04:41:24] :	 [Step 1/1] 159/272 Test #164: test_accuracy_op ............................   Passed    2.86 sec
[04:41:24] :	 [Step 1/1]         Start 166: test_gather_op
[04:41:24] :	 [Step 1/1]         Start 167: test_prelu_op
[04:41:26] :	 [Step 1/1] 160/272 Test #165: test_gaussian_random_op .....................   Passed    3.56 sec
[04:41:26] :	 [Step 1/1]         Start 168: test_default_scope_funcs
[04:41:28] :	 [Step 1/1] 161/272 Test #168: test_default_scope_funcs ....................   Passed    1.52 sec
[04:41:28] :	 [Step 1/1]         Start 169: test_l1_norm_op
[04:41:29] :	 [Step 1/1] 162/272 Test #166: test_gather_op ..............................   Passed    4.16 sec
[04:41:29] :	 [Step 1/1]         Start 170: test_sum_op
[04:41:29] :	 [Step 1/1] 163/272 Test  #77: test_CompareTwoNets .........................   Passed   56.80 sec
[04:41:29] :	 [Step 1/1] 164/272 Test #167: test_prelu_op ...............................   Passed    4.66 sec
[04:41:29] :	 [Step 1/1]         Start 171: test_pool_max_op
[04:41:29] :	 [Step 1/1]         Start 172: test_rmsprop_op
[04:41:32] :	 [Step 1/1] 165/272 Test #169: test_l1_norm_op .............................   Passed    3.36 sec
[04:41:32] :	 [Step 1/1]         Start 173: test_activation_op
[04:41:33] :	 [Step 1/1] 166/272 Test #170: test_sum_op .................................   Passed    4.44 sec
[04:41:33] :	 [Step 1/1]         Start 174: test_mean_op
[04:41:34] :	 [Step 1/1] 167/272 Test #172: test_rmsprop_op .............................   Passed    3.93 sec
[04:41:34] :	 [Step 1/1] 168/272 Test #171: test_pool_max_op ............................   Passed    3.95 sec
[04:41:34] :	 [Step 1/1]         Start 175: test_minus_op
[04:41:34] :	 [Step 1/1]         Start 176: test_gru_unit_op
[04:41:35] :	 [Step 1/1] 169/272 Test  #79: test_CompareSparse ..........................   Passed   61.38 sec
[04:41:35] :	 [Step 1/1]         Start 177: test_infer_shape
[04:41:36] :	 [Step 1/1] 170/272 Test #176: test_gru_unit_op ............................   Passed    1.85 sec
[04:41:36] :	 [Step 1/1] 171/272 Test #173: test_activation_op ..........................   Passed    4.29 sec
[04:41:36] :	 [Step 1/1]         Start 178: test_lrn_op
[04:41:36] :	 [Step 1/1]         Start 179: test_recognize_digits_mlp
[04:41:37] :	 [Step 1/1] 172/272 Test #174: test_mean_op ................................   Passed    3.22 sec
[04:41:37] :	 [Step 1/1] 173/272 Test #133: test_layerHelpers ...........................   Passed   36.68 sec
[04:41:37] :	 [Step 1/1]         Start 180: test_smooth_l1_loss_op
[04:41:37] :	 [Step 1/1]         Start 181: test_variable
[04:41:37] :	 [Step 1/1] 174/272 Test #177: test_infer_shape ............................   Passed    1.29 sec
[04:41:37] :	 [Step 1/1]         Start 182: test_pad_op
[04:41:38] :	 [Step 1/1] 175/272 Test #178: test_lrn_op .................................   Passed    1.78 sec
[04:41:38] :	 [Step 1/1] 176/272 Test #181: test_variable ...............................   Passed    1.34 sec
[04:41:38] :	 [Step 1/1]         Start 183: test_adadelta_op
[04:41:38] :	 [Step 1/1]         Start 184: test_optimizer
[04:41:39] :	 [Step 1/1] 177/272 Test #179: test_recognize_digits_mlp ...................   Passed    2.70 sec
[04:41:39] :	 [Step 1/1]         Start 185: test_clip_op
[04:41:39] :	 [Step 1/1] 178/272 Test #175: test_minus_op ...............................   Passed    5.10 sec
[04:41:39] :	 [Step 1/1]         Start 186: test_momentum_op
[04:41:40] :	 [Step 1/1] 179/272 Test #184: test_optimizer ..............................   Passed    1.84 sec
[04:41:40] :	 [Step 1/1]         Start 187: test_sequence_softmax_op
[04:41:41] :	 [Step 1/1] 180/272 Test #180: test_smooth_l1_loss_op ......................   Passed    3.81 sec
[04:41:41] :	 [Step 1/1]         Start 188: test_protobuf
[04:41:42] :	 [Step 1/1] 181/272 Test #183: test_adadelta_op ............................   Passed    3.36 sec
[04:41:42] :	 [Step 1/1]         Start 189: test_feed_fetch_method
[04:41:42] :	 [Step 1/1] 182/272 Test #182: test_pad_op .................................   Passed    4.18 sec
[04:41:42] :	 [Step 1/1] 183/272 Test #188: test_protobuf ...............................   Passed    0.94 sec
[04:41:42] :	 [Step 1/1] 184/272 Test #185: test_clip_op ................................   Passed    3.38 sec
[04:41:42] :	 [Step 1/1]         Start 190: test_rank_loss_op
[04:41:42] :	 [Step 1/1]         Start 191: test_reduce_op
[04:41:42] :	 [Step 1/1]         Start 192: test_seq_expand
[04:41:43] :	 [Step 1/1] 185/272 Test #186: test_momentum_op ............................   Passed    2.79 sec
[04:41:43] :	 [Step 1/1]         Start 193: test_reshape_op
[04:41:44] :	 [Step 1/1] 186/272 Test #187: test_sequence_softmax_op ....................   Passed    3.01 sec
[04:41:44] :	 [Step 1/1] 187/272 Test #189: test_feed_fetch_method ......................   Passed    1.59 sec
[04:41:44] :	 [Step 1/1]         Start 194: test_sign_op
[04:41:44] :	 [Step 1/1]         Start 195: test_scale_op
[04:41:44] :	 [Step 1/1] 188/272 Test #153: test_image_classification_train .............   Passed   31.60 sec
[04:41:44] :	 [Step 1/1]         Start 196: test_scatter_op
[04:41:45] :	 [Step 1/1] 189/272 Test #190: test_rank_loss_op ...........................   Passed    2.76 sec
[04:41:45] :	 [Step 1/1]         Start 197: test_elementwise_add_op
[04:41:45] :	 [Step 1/1] 190/272 Test #191: test_reduce_op ..............................   Passed    2.77 sec
[04:41:45] :	 [Step 1/1] 191/272 Test #192: test_seq_expand .............................   Passed    2.96 sec
[04:41:45] :	 [Step 1/1] 192/272 Test #193: test_reshape_op .............................   Passed    2.55 sec
[04:41:45] :	 [Step 1/1]         Start 198: test_multiplex_op
[04:41:45] :	 [Step 1/1]         Start 199: test_lstm_unit_op
[04:41:45] :	 [Step 1/1]         Start 200: test_nccl_init_op
[04:41:46] :	 [Step 1/1] 193/272 Test #194: test_sign_op ................................   Passed    2.04 sec
[04:41:46] :	 [Step 1/1] 194/272 Test #195: test_scale_op ...............................   Passed    2.24 sec
[04:41:46] :	 [Step 1/1]         Start 201: test_parameter
[04:41:46] :	 [Step 1/1]         Start 202: test_protobuf_descs
[04:41:47] :	 [Step 1/1] 195/272 Test #200: test_nccl_init_op ...........................   Passed    0.86 sec
[04:41:47] :	 [Step 1/1] 196/272 Test #196: test_scatter_op .............................   Passed    2.20 sec
[04:41:47] :	 [Step 1/1]         Start 203: test_uniform_random_op
[04:41:47] :	 [Step 1/1]         Start 204: test_gru_op
[04:41:47] :	 [Step 1/1] 197/272 Test #199: test_lstm_unit_op ...........................   Passed    1.27 sec
[04:41:47] :	 [Step 1/1] 198/272 Test #201: test_parameter ..............................   Passed    0.86 sec
[04:41:47] :	 [Step 1/1] 199/272 Test #202: test_protobuf_descs .........................   Passed    1.16 sec
[04:41:47] :	 [Step 1/1]         Start 205: test_squared_l2_distance_op
[04:41:47] :	 [Step 1/1]         Start 206: test_tensor_array
[04:41:47] :	 [Step 1/1]         Start 207: test_batch_norm_op
[04:41:48] :	 [Step 1/1] 200/272 Test #160: test_seq_conv ...............................   Passed   29.13 sec
[04:41:48] :	 [Step 1/1] 201/272 Test #197: test_elementwise_add_op .....................   Passed    2.71 sec
[04:41:48] :	 [Step 1/1] 202/272 Test #198: test_multiplex_op ...........................   Passed    2.18 sec
[04:41:48] :	 [Step 1/1]         Start 208: test_conv2d_op
[04:41:48] :	 [Step 1/1]         Start 209: test_transpose_op
[04:41:48] :	 [Step 1/1]         Start 210: test_initializer
[04:41:49] :	 [Step 1/1] 203/272 Test #206: test_tensor_array ...........................   Passed    1.63 sec
[04:41:49] :	 [Step 1/1] 204/272 Test #203: test_uniform_random_op ......................   Passed    2.13 sec
[04:41:49] :	 [Step 1/1] 205/272 Test #210: test_initializer ............................   Passed    1.32 sec
[04:41:49] :	 [Step 1/1]         Start 211: test_fill_constant_op
[04:41:49] :	 [Step 1/1]         Start 212: test_adam_op
[04:41:49] :	 [Step 1/1]         Start 213: test_recurrent_op
[04:41:50] :	 [Step 1/1] 206/272 Test #205: test_squared_l2_distance_op .................   Passed    2.64 sec
[04:41:50] :	 [Step 1/1] 207/272 Test #207: test_batch_norm_op ..........................   Passed    3.13 sec
[04:41:50] :	 [Step 1/1]         Start 214: test_adagrad_op
[04:41:50] :	 [Step 1/1]         Start 215: test_cross_entropy_op
[04:41:51] :	 [Step 1/1] 208/272 Test #204: test_gru_op .................................   Passed    3.74 sec
[04:41:51] :	 [Step 1/1] 209/272 Test #213: test_recurrent_op ...........................   Passed    1.61 sec
[04:41:51] :	 [Step 1/1] 210/272 Test #209: test_transpose_op ...........................   Passed    3.01 sec
[04:41:51] :	 [Step 1/1]         Start 216: test_lookup_table_op
[04:41:51] :	 [Step 1/1]         Start 217: test_compare_op
[04:41:51] :	 [Step 1/1]         Start 218: test_lod_rank_table
[04:41:52] :	 [Step 1/1] 211/272 Test #211: test_fill_constant_op .......................   Passed    2.19 sec
[04:41:52] :	 [Step 1/1] 212/272 Test #212: test_adam_op ................................   Passed    2.59 sec
[04:41:52] :	 [Step 1/1]         Start 219: test_net
[04:41:52] :	 [Step 1/1]         Start 220: test_operator
[04:41:53] :	 [Step 1/1] 213/272 Test #208: test_conv2d_op ..............................   Passed    4.83 sec
[04:41:53] :	 [Step 1/1] 214/272 Test #218: test_lod_rank_table .........................   Passed    1.62 sec
[04:41:53] :	 [Step 1/1] 215/272 Test #214: test_adagrad_op .............................   Passed    2.30 sec
[04:41:53] :	 [Step 1/1] 216/272 Test #220: test_operator ...............................   Passed    1.11 sec
[04:41:53] :	 [Step 1/1] 217/272 Test #215: test_cross_entropy_op .......................   Passed    2.35 sec
[04:41:53] :	 [Step 1/1]         Start 221: test_layers
[04:41:53] :	 [Step 1/1]         Start 222: test_decayed_adagrad_op
[04:41:53] :	 [Step 1/1]         Start 223: test_seq_pool
[04:41:53] :	 [Step 1/1]         Start 224: test_concat_op
[04:41:53] :	 [Step 1/1]         Start 225: test_sgd_op
[04:41:54] :	 [Step 1/1] 218/272 Test #219: test_net ....................................   Passed    1.37 sec
[04:41:54] :	 [Step 1/1]         Start 226: test_fit_a_line
[04:41:54] :	 [Step 1/1] 219/272 Test #216: test_lookup_table_op ........................   Passed    2.48 sec
[04:41:54] :	 [Step 1/1] 220/272 Test #217: test_compare_op .............................   Passed    2.68 sec
[04:41:54] :	 [Step 1/1] 221/272 Test #221: test_layers .................................   Passed    1.01 sec
[04:41:54] :	 [Step 1/1]         Start 227: test_tensor
[04:41:54] :	 [Step 1/1]         Start 228: test_array_read_write_op
[04:41:54] :	 [Step 1/1]         Start 229: test_sigmoid_cross_entropy_with_logits_op
[04:41:55] :	 [Step 1/1] 222/272 Test #228: test_array_read_write_op ....................   Passed    0.91 sec
[04:41:55] :	 [Step 1/1] 223/272 Test #222: test_decayed_adagrad_op .....................   Passed    2.13 sec
[04:41:55] :	 [Step 1/1] 224/272 Test #229: test_sigmoid_cross_entropy_with_logits_op ...   Passed    1.11 sec
[04:41:55] :	 [Step 1/1] 225/272 Test #224: test_concat_op ..............................   Passed    2.42 sec
[04:41:55] :	 [Step 1/1] 226/272 Test #225: test_sgd_op .................................   Passed    2.42 sec
[04:41:55] :	 [Step 1/1]         Start 230: test_elementwise_div_op
[04:41:55] :	 [Step 1/1]         Start 231: test_elementwise_mul_op
[04:41:55] :	 [Step 1/1]         Start 232: test_elementwise_sub_op
[04:41:55] :	 [Step 1/1]         Start 233: test_lstm_op
[04:41:55] :	 [Step 1/1]         Start 234: test_margin_rank_loss_op
[04:41:56] :	 [Step 1/1] 227/272 Test #227: test_tensor .................................   Passed    1.64 sec
[04:41:56] :	 [Step 1/1]         Start 235: test_cast_op
[04:41:57] :	 [Step 1/1] 228/272 Test #226: test_fit_a_line .............................   Passed    2.55 sec
[04:41:57] :	 [Step 1/1]         Start 236: test_fill_zeros_like_op
[04:41:57] :	 [Step 1/1] 229/272 Test #234: test_margin_rank_loss_op ....................   Passed    2.18 sec
[04:41:57] :	 [Step 1/1]         Start 237: test_softmax_with_cross_entropy_op
[04:41:59] :	 [Step 1/1] 230/272 Test #235: test_cast_op ................................   Passed    2.68 sec
[04:41:59] :	 [Step 1/1] 231/272 Test #230: test_elementwise_div_op .....................   Passed    3.41 sec
[04:41:59] :	 [Step 1/1]         Start 238: test_dropout_op
[04:41:59] :	 [Step 1/1]         Start 239: test_softmax_op
[04:41:59] :	 [Step 1/1] 232/272 Test #232: test_elementwise_sub_op .....................   Passed    3.61 sec
[04:41:59] :	 [Step 1/1] 233/272 Test #231: test_elementwise_mul_op .....................   Passed    3.71 sec
[04:41:59] :	 [Step 1/1] 234/272 Test #223: test_seq_pool ...............................   Passed    6.34 sec
[04:41:59] :	 [Step 1/1]         Start 240: test_split_op
[04:41:59] :	 [Step 1/1]         Start 241: test_mul_op
[04:41:59] :	 [Step 1/1]         Start 242: test_conv_shift_op
[04:42:00] :	 [Step 1/1] 235/272 Test #236: test_fill_zeros_like_op .....................   Passed    2.79 sec
[04:42:00] :	 [Step 1/1] 236/272 Test #237: test_softmax_with_cross_entropy_op ..........   Passed    2.42 sec
[04:42:00] :	 [Step 1/1]         Start 243: test_dynamic_recurrent_op
[04:42:00] :	 [Step 1/1]         Start 244: test_cond_op
[04:42:01] :	 [Step 1/1] 237/272 Test #239: test_softmax_op .............................   Passed    2.21 sec
[04:42:01] :	 [Step 1/1] 238/272 Test #244: test_cond_op ................................   Passed    1.19 sec
[04:42:01] :	 [Step 1/1] 239/272 Test #243: test_dynamic_recurrent_op ...................   Passed    1.33 sec
[04:42:01] :	 [Step 1/1]         Start 245: test_op_support_gpu
[04:42:01] :	 [Step 1/1]         Start 246: test_proximal_gd_op
[04:42:01] :	 [Step 1/1]         Start 247: test_positive_negative_pair_op
[04:42:02] :	 [Step 1/1] 240/272 Test #240: test_split_op ...............................   Passed    2.57 sec
[04:42:02] :	 [Step 1/1]         Start 248: test_lod_array_length_op
[04:42:03] :	 [Step 1/1] 241/272 Test #242: test_conv_shift_op ..........................   Passed    2.88 sec
[04:42:03] :	 [Step 1/1] 242/272 Test #245: test_op_support_gpu .........................   Passed    1.24 sec
[04:42:03] :	 [Step 1/1]         Start 249: test_seq_concat_op
[04:42:03] :	 [Step 1/1]         Start 250: test_proximal_adagrad_op
[04:42:03] :	 [Step 1/1] 243/272 Test #247: test_positive_negative_pair_op ..............   Passed    1.74 sec
[04:42:03] :	 [Step 1/1] 244/272 Test #248: test_lod_array_length_op ....................   Passed    1.12 sec
[04:42:03] :	 [Step 1/1]         Start 251: test_squared_l2_norm_op
[04:42:03] :	 [Step 1/1]         Start 252: test_understand_sentiment_conv
[04:42:04] :	 [Step 1/1] 245/272 Test #246: test_proximal_gd_op .........................   Passed    2.26 sec
[04:42:04] :	 [Step 1/1] 246/272 Test #238: test_dropout_op .............................   Passed    5.11 sec
[04:42:04] :	 [Step 1/1]         Start 253: test_conv3d_transpose_op
[04:42:04] :	 [Step 1/1]         Start 254: test_framework_debug_str
[04:42:05] :	 [Step 1/1] 247/272 Test #249: test_seq_concat_op ..........................   Passed    1.73 sec
[04:42:05] :	 [Step 1/1]         Start 255: test_inference_model_io
[04:42:05] :	 [Step 1/1] 248/272 Test #254: test_framework_debug_str ....................   Passed    1.01 sec
[04:42:05] :	 [Step 1/1]         Start 256: test_recognize_digits_conv
[04:42:06] :	 [Step 1/1] 249/272 Test #250: test_proximal_adagrad_op ....................   Passed    2.76 sec
[04:42:06] :	 [Step 1/1] 250/272 Test #251: test_squared_l2_norm_op .....................   Passed    2.65 sec
[04:42:06] :	 [Step 1/1]         Start 257: test_conv2d_transpose_op
[04:42:06] :	 [Step 1/1]         Start 258: test_understand_sentiment_lstm
[04:42:07] :	 [Step 1/1] 251/272 Test #255: test_inference_model_io .....................   Passed    1.93 sec
[04:42:07] :	 [Step 1/1]         Start 259: test_crf_decoding_op
[04:42:10] :	 [Step 1/1] 252/272 Test #253: test_conv3d_transpose_op ....................   Passed    5.69 sec
[04:42:10] :	 [Step 1/1] 253/272 Test #259: test_crf_decoding_op ........................   Passed    2.95 sec
[04:42:10] :	 [Step 1/1]         Start 260: test_huber_loss_op
[04:42:10] :	 [Step 1/1]         Start 261: test_image_classification_layer
[04:42:12] :	 [Step 1/1] 254/272 Test #257: test_conv2d_transpose_op ....................   Passed    5.58 sec
[04:42:12] :	 [Step 1/1] 255/272 Test #261: test_image_classification_layer .............   Passed    2.12 sec
[04:42:12] :	 [Step 1/1]         Start 262: test_linear_chain_crf_op
[04:42:12] :	 [Step 1/1]         Start 263: test_lod_tensor_array
[04:42:14] :	 [Step 1/1] 256/272 Test #260: test_huber_loss_op ..........................   Passed    3.62 sec
[04:42:14] :	 [Step 1/1]         Start 264: test_precision_recall_op
[04:42:15] :	 [Step 1/1] 257/272 Test #263: test_lod_tensor_array .......................   Passed    2.61 sec
[04:42:15] :	 [Step 1/1] 258/272 Test #264: test_precision_recall_op ....................   Passed    1.22 sec
[04:42:15] :	 [Step 1/1]         Start 265: test_rnn_memory_helper_op
[04:42:15] :	 [Step 1/1]         Start 266: test_evaluator
[04:42:16] :	 [Step 1/1] 259/272 Test #265: test_rnn_memory_helper_op ...................   Passed    1.38 sec
[04:42:16] :	 [Step 1/1] 260/272 Test #262: test_linear_chain_crf_op ....................   Passed    4.40 sec
[04:42:16] :	 [Step 1/1]         Start 267: test_recommender_system
[04:42:16] :	 [Step 1/1]         Start 268: test_conv3d_op
[04:42:18] :	 [Step 1/1] 261/272 Test #266: test_evaluator ..............................   Passed    2.49 sec
[04:42:18] :	 [Step 1/1]         Start 269: test_fill_constant_batch_size_like_op
[04:42:23] :	 [Step 1/1] 262/272 Test #269: test_fill_constant_batch_size_like_op .......   Passed    4.25 sec
[04:42:23] :	 [Step 1/1]         Start 270: test_clip_by_norm_op
[04:42:28] :	 [Step 1/1] 263/272 Test #270: test_clip_by_norm_op ........................   Passed    5.21 sec
[04:42:28] :	 [Step 1/1]         Start 271: test_lod_tensor_array_ops
[04:42:30] :	 [Step 1/1] 264/272 Test #268: test_conv3d_op ..............................   Passed   13.19 sec
[04:42:30] :	 [Step 1/1]         Start 272: test_shrink_rnn_memory
[04:42:31] :	 [Step 1/1] 265/272 Test #271: test_lod_tensor_array_ops ...................   Passed    2.82 sec
[04:42:33] :	 [Step 1/1] 266/272 Test #272: test_shrink_rnn_memory ......................   Passed    2.49 sec
[04:42:37] :	 [Step 1/1] 267/272 Test #256: test_recognize_digits_conv ..................   Passed   31.34 sec
[04:42:49] :	 [Step 1/1] 268/272 Test #233: test_lstm_op ................................   Passed   53.56 sec
[04:43:25] :	 [Step 1/1] 269/272 Test #252: test_understand_sentiment_conv ..............   Passed   81.26 sec
[04:43:25] :	 [Step 1/1] 270/272 Test #258: test_understand_sentiment_lstm ..............   Passed   79.02 sec
[04:43:28] :	 [Step 1/1] 271/272 Test #241: test_mul_op .................................   Passed   88.67 sec
[04:43:38] :	 [Step 1/1] 272/272 Test #267: test_recommender_system .....................   Passed   81.77 sec
[04:43:38] :	 [Step 1/1] 
[04:43:38] :	 [Step 1/1] 100% tests passed, 0 tests failed out of 272
[04:43:38] :	 [Step 1/1] 
[04:43:38] :	 [Step 1/1] Total Test time (real) = 235.37 sec

[chengduoZH]

@luotao1
1、可以将时间从大到小排序，命令：

cat ~/Downloads/Paddle_PR_CI_7559.log | awk '{split($0,a,"Passed");split(a[1],b,": "); if(match(b[2],".") && match(a[2]," "))  print b[2],a[2]} ' | sort -k 3 -r

2、数据是从teamcity上得到的，我是把log下载了下来，在命令里面有的Paddle_PR_CI_7559.log

[luotao1]

两个log的差异特别大。

[chengduoZH]

我运行代码不是最新的。下面这个应该是最新的：

test_mul_op .................................       88.67 sec
test_recommender_system .....................       81.77 sec
test_understand_sentiment_conv ..............       81.26 sec
test_understand_sentiment_lstm ..............       79.02 sec
test_CompareSparse ..........................       61.38 sec
test_CompareTwoNets .........................       56.80 sec
test_lstm_op ................................       53.56 sec
test_ParameterServer2 .......................       40.33 sec
test_layerHelpers ...........................       36.68 sec
master_test .................................       33.79 sec
test_image_classification_train .............       31.60 sec
test_recognize_digits_conv ..................       31.34 sec
test_TrainerOnePass .........................       29.48 sec
test_seq_conv ...............................       29.13 sec
test_RecurrentLayer .........................       26.72 sec
test_ProtoServer ............................       26.15 sec
test_CustomStackTrace .......................       22.69 sec
test_StringUtils ............................       21.38 sec
test_LayerGrad ..............................       20.45 sec
test_PyDataProvider2 ........................       20.42 sec
test_CustomStackTracePrint ..................       16.80 sec
test_RowBuffer ..............................       13.59 sec
test_conv3d_op ..............................       13.19 sec
test_MKLDNN .................................       11.33 sec
test_RecurrentGradientMachine ...............       10.46 sec
test_TrainingAlgorithm ......................        9.77 sec
math_function_gpu_test ......................        9.43 sec
test_matmul_op ..............................        8.47 sec
test_BaseMatrix .............................        8.38 sec
pserver_client_test .........................        8.24 sec
test_Matrix .................................        8.08 sec
Im2ColTest ..................................        7.97 sec
DepthwiseConvOpTest .........................        7.77 sec
testTrainer .................................        7.59 sec
test_ProtoDataProvider ......................        7.46 sec
test_matrixCompare ..........................        7.36 sec
test_seq_pool ...............................        6.34 sec
testTrain ...................................        5.86 sec
test_conv3d_transpose_op ....................        5.69 sec
test_conv2d_transpose_op ....................        5.58 sec
test_paramconf_order ........................        5.45 sec
test_clip_by_norm_op ........................        5.21 sec
test_dropout_op .............................        5.11 sec
test_minus_op ...............................        5.10 sec
test_Evaluator ..............................        4.98 sec
socket_test .................................        4.94 sec
test_conv2d_op ..............................        4.83 sec
test_pool3d_op ..............................        4.82 sec
test_prelu_op ...............................        4.66 sec
test_sum_op .................................        4.44 sec
test_adamax_op ..............................        4.41 sec
test_linear_chain_crf_op ....................        4.40 sec
test_activation_op ..........................        4.29 sec
test_fill_constant_batch_size_like_op .......        4.25 sec
test_pad_op .................................        4.18 sec
test_gather_op ..............................        4.16 sec
test_pool2d_op ..............................        4.10 sec
test_pool_max_op ............................        3.95 sec
test_rmsprop_op .............................        3.93 sec
test_smooth_l1_loss_op ......................        3.81 sec
test_gru_op .................................        3.74 sec
test_elementwise_mul_op .....................        3.71 sec
test_huber_loss_op ..........................        3.62 sec
test_elementwise_sub_op .....................        3.61 sec
test_NetworkCompare .........................        3.57 sec
test_gaussian_random_op .....................        3.56 sec
test_BatchNorm ..............................        3.52 sec
test_crop_op ................................        3.45 sec
test_elementwise_div_op .....................        3.41 sec
test_clip_op ................................        3.38 sec
test_cos_sim_op .............................        3.37 sec
test_l1_norm_op .............................        3.36 sec
test_adadelta_op ............................        3.36 sec
test_KmaxSeqScore ...........................        3.36 sec
test_common .................................        3.35 sec
GemmConvOpTest ..............................        3.34 sec
PadOpTest ...................................        3.31 sec
test_WarpCTCLayer ...........................        3.30 sec
test_mean_op ................................        3.22 sec
test_PyDataProvider .........................        3.19 sec
test_batch_norm_op ..........................        3.13 sec
test_sparseMatrixCompare ....................        3.04 sec
test_transpose_op ...........................        3.01 sec
test_sequence_softmax_op ....................        3.01 sec
decorator_test ..............................        3.01 sec
RowConvOpTest ...............................        3.01 sec
test_seq_expand .............................        2.96 sec
test_crf_decoding_op ........................        2.95 sec
test_conv_shift_op ..........................        2.88 sec
test_Expand .................................        2.87 sec
test_accuracy_op ............................        2.86 sec
test_lod_tensor_array_ops ...................        2.82 sec
test_image ..................................        2.82 sec
ContextProjectionOpTest .....................        2.82 sec
test_momentum_op ............................        2.79 sec
test_fill_zeros_like_op .....................        2.79 sec
test_reduce_op ..............................        2.77 sec
test_rank_loss_op ...........................        2.76 sec
test_proximal_adagrad_op ....................        2.76 sec
test_elementwise_add_op .....................        2.71 sec
test_recognize_digits_mlp ...................        2.70 sec
device_context_test .........................        2.69 sec
test_compare_op .............................        2.68 sec
test_cast_op ................................        2.68 sec
test_squared_l2_norm_op .....................        2.65 sec
test_ConvTrans ..............................        2.65 sec
test_squared_l2_distance_op .................        2.64 sec
test_lod_tensor_array .......................        2.61 sec
CropOpTest ..................................        2.61 sec
TensorShapeTest .............................        2.60 sec
test_data_feeder ............................        2.59 sec
test_adam_op ................................        2.59 sec
test_top_k_op ...............................        2.58 sec
test_split_op ...............................        2.57 sec
test_reshape_op .............................        2.55 sec
test_fit_a_line .............................        2.55 sec
test_op .....................................        2.52 sec
test_CRFLayerGrad ...........................        2.52 sec
test_ploter .................................        2.51 sec
test_shrink_rnn_memory ......................        2.49 sec
test_evaluator ..............................        2.49 sec
test_lookup_table_op ........................        2.48 sec
test_ThreadBarrier ..........................        2.44 sec
test_softmax_with_cross_entropy_op ..........        2.42 sec
test_sgd_op .................................        2.42 sec
test_concat_op ..............................        2.42 sec
test_CompareTwoOpts .........................        2.42 sec
test_recurrent_machine_generation ...........        2.41 sec
CrossMapNormalOpTest ........................        2.40 sec
test_ConvUnify ..............................        2.36 sec
test_cross_entropy_op .......................        2.35 sec
transform_test ..............................        2.34 sec
test_word2vec ...............................        2.34 sec
testGradientMachine .........................        2.32 sec
ScaleSubRegionOpTest ........................        2.31 sec
test_adagrad_op .............................        2.30 sec
BufferArgTest ...............................        2.30 sec
test_DetectionOutput ........................        2.27 sec
test_proximal_gd_op .........................        2.26 sec
test_SeqSliceLayerGrad ......................        2.25 sec
test_scale_op ...............................        2.24 sec
test_softmax_op .............................        2.21 sec
pserver_test ................................        2.21 sec
test_scatter_op .............................        2.20 sec
test_fill_constant_op .......................        2.19 sec
test_parameters .............................        2.18 sec
test_multiplex_op ...........................        2.18 sec
test_margin_rank_loss_op ....................        2.18 sec
test_PyDataProviderWrapper ..................        2.17 sec
selected_rows_functor_gpu_test ..............        2.16 sec
test_uniform_random_op ......................        2.13 sec
test_decayed_adagrad_op .....................        2.13 sec
test_argument ...............................        2.13 sec
tensor_test .................................        2.13 sec
test_image_classification_layer .............        2.12 sec
test_scope ..................................        2.10 sec
test_sign_op ................................        2.04 sec
test_SpinLock ...............................        2.03 sec
testMatrix ..................................        2.02 sec
test_operator_desc ..........................        2.01 sec
MulOpTest ...................................        2.01 sec
CosSimOpTest ................................        2.01 sec
test_selected_rows ..........................        2.00 sec
test_inference_model_io .....................        1.93 sec
creator_test ................................        1.93 sec
strided_memcpy_test .........................        1.91 sec
test_gru_unit_op ............................        1.85 sec
test_optimizer ..............................        1.84 sec
test_rnn_layer ..............................        1.83 sec
test_ActivationGrad .........................        1.83 sec
test_MultinomialSampler .....................        1.82 sec
test_GpuProfiler ............................        1.81 sec
test_CrossEntropyOverBeamGrad ...............        1.81 sec
test_Compare ................................        1.81 sec
BlockExpandOpTest ...........................        1.81 sec
test_ExecViaCpu .............................        1.80 sec
test_lrn_op .................................        1.78 sec
test_PriorBox ...............................        1.75 sec
test_positive_negative_pair_op ..............        1.74 sec
test_modified_huber_loss_op .................        1.74 sec
test_seq_concat_op ..........................        1.73 sec
test_layer ..................................        1.72 sec
test_Thread .................................        1.71 sec
network_helper_test .........................        1.71 sec
test_tensor .................................        1.64 sec
test_topology ...............................        1.63 sec
test_tensor_array ...........................        1.63 sec
test_lod_rank_table .........................        1.62 sec
test_recurrent_op ...........................        1.61 sec
test_Trainer ................................        1.61 sec
FunctionTest ................................        1.61 sec
test_feed_fetch_method ......................        1.59 sec
test_auc_op .................................        1.57 sec
test_default_scope_funcs ....................        1.52 sec
TensorTypeTest ..............................        1.50 sec
SwitchOpTest ................................        1.50 sec
testVector ..................................        1.49 sec
test_SIMDFlags ..............................        1.41 sec
test_perturbation ...........................        1.40 sec
test_CpuGpuVector ...........................        1.40 sec
test_rnn_memory_helper_op ...................        1.38 sec
test_net ....................................        1.37 sec
test_variable ...............................        1.34 sec
test_executor_and_mul .......................        1.33 sec
test_dynamic_recurrent_op ...................        1.33 sec
test_initializer ............................        1.32 sec
vol2col_test ................................        1.31 sec
save_load_op_test ...........................        1.31 sec
test_FPException ............................        1.30 sec
test_Allocator ..............................        1.30 sec
test_infer_shape ............................        1.29 sec
test_lstm_unit_op ...........................        1.27 sec
test_op_support_gpu .........................        1.24 sec
test_precision_recall_op ....................        1.22 sec
test_LinearChainCRF .........................        1.21 sec
test_SIMDFunctions ..........................        1.20 sec
test_Error ..................................        1.20 sec
im2col_test .................................        1.20 sec
test_cond_op ................................        1.19 sec
test_protobuf_descs .........................        1.16 sec
test_regularizer ............................        1.13 sec
test_lod_array_length_op ....................        1.12 sec
test_sigmoid_cross_entropy_with_logits_op ...        1.11 sec
test_operator ...............................        1.11 sec
test_SparseMatrix ...........................        1.11 sec
test_program ................................        1.08 sec
test_exception ..............................        1.04 sec
test_layers .................................        1.01 sec
test_framework_debug_str ....................        1.01 sec
test_protobuf ...............................        0.94 sec
lod_tensor_test .............................        0.92 sec
test_array_read_write_op ....................        0.91 sec
test_parameter ..............................        0.86 sec
test_nccl_init_op ...........................        0.86 sec
memory_test .................................        0.80 sec
testArguments ...............................        0.72 sec
lod_tensor_gpu_test .........................        0.71 sec
dim_test ....................................        0.71 sec
to_string_test ..............................        0.70 sec
selected_rows_functor_test ..................        0.66 sec
serialization_test ..........................        0.61 sec
layers_test .................................        0.61 sec
system_allocator_test .......................        0.60 sec
stringpiece_test ............................        0.60 sec
place_test ..................................        0.60 sec
math_function_test ..........................        0.60 sec
eigen_test ..................................        0.60 sec
net_op_test .................................        0.56 sec
gather_test .................................        0.56 sec
variable_test ...............................        0.50 sec
var_type_inference_test .....................        0.50 sec
test_reset_hook .............................        0.50 sec
tensor_array_test ...........................        0.50 sec
scope_test ..................................        0.50 sec
program_desc_test ...........................        0.50 sec
op_registry_test ............................        0.50 sec
dynamic_recurrent_op_test ...................        0.50 sec
backward_test ...............................        0.50 sec
enforce_test ................................        0.31 sec
stringprintf_test ...........................        0.30 sec
cpu_info_test ...............................        0.30 sec
selected_rows_test ..........................        0.26 sec
cudnn_helper_test ...........................        0.23 sec
parameter_optimizer_test ....................        0.21 sec
nccl_op_test ................................        0.21 sec
scatter_test ................................        0.20 sec
prune_test ..................................        0.20 sec
operator_test ...............................        0.20 sec
op_proto_maker_test .........................        0.20 sec
test_config_parser ..........................        0.17 sec
nccl_test ...................................        0.12 sec
ddim_test ...................................        0.10 sec

[chengduoZH]

该问题已暂时得以缓解，该issue可以先关闭了