[2022-08-10 19:36:33.726646] INFO: moduleinvoker: input_features.v1 开始运行..
[2022-08-10 19:36:34.495082] INFO: moduleinvoker: input_features.v1 运行完成[0.772408s].
[2022-08-10 19:36:34.542875] INFO: moduleinvoker: factorlens.v2 开始运行..
[2022-08-10 19:36:34.906089] INFO: 因子分析: batch_process start
[2022-08-10 19:36:34.916110] INFO: 因子分析: load_instruments 2021-01-01, 2021-03-31
[2022-08-10 19:36:37.515014] INFO: 因子分析: load_instruments, 4240 rows.
[2022-08-10 19:36:37.517204] INFO: 因子分析: load_benchmark_data 2021-01-01, 2021-03-31
[2022-08-10 19:36:38.966078] INFO: 因子分析: load_benchmark_data, 174 rows.
[2022-08-10 19:36:38.979040] INFO: 因子分析: StockPool.before_load_general_feature_data
[2022-08-10 19:36:38.982363] INFO: 因子分析: UserDataMerge.before_load_general_feature_data
[2022-08-10 19:36:38.984827] INFO: 因子分析: DropSTStocks.before_load_general_feature_data
[2022-08-10 19:36:38.988973] INFO: 因子分析: DropNewStocks.before_load_general_feature_data
[2022-08-10 19:36:38.991636] INFO: 因子分析: Neutralization.before_load_general_feature_data
[2022-08-10 19:36:39.018477] INFO: 因子分析: DelayRebalanceDays.before_load_general_feature_data
[2022-08-10 19:36:39.021172] INFO: 因子分析: RebalancePeriod.before_load_general_feature_data
[2022-08-10 19:36:39.027357] INFO: 因子分析: RebalancePrice.before_load_general_feature_data
[2022-08-10 19:36:39.030394] INFO: 因子分析: FactorCoverage.before_load_general_feature_data
[2022-08-10 19:36:39.034699] INFO: 因子分析: PBRatio.before_load_general_feature_data
[2022-08-10 19:36:39.039374] INFO: 因子分析: Turnover.before_load_general_feature_data
[2022-08-10 19:36:39.046330] INFO: 因子分析: load_general_feature_data, load data
[2022-08-10 19:39:44.445477] INFO: 因子分析: RebalancePeriod.after_load_general_feature_data
[2022-08-10 19:39:44.516311] INFO: 因子分析: RebalancePeriodsReturns.after_load_general_feature_data
[2022-08-10 19:40:17.956965] INFO: 因子分析: RebalancePrice.after_load_general_feature_data
[2022-08-10 19:40:21.070809] INFO: 因子分析: load_general_feature_data, 1186306 rows.
[2022-08-10 19:40:21.077330] INFO: 因子分析: load_derived_feature_data, 1186306 rows, 22 columns.
[2022-08-10 19:40:21.081477] INFO: 因子分析: process, correlation(turn_0, return_0, 5)
[2022-08-10 19:40:21.085054] INFO: 因子分析: calculate_factor, correlation(turn_0, return_0, 5)
[2022-08-10 19:41:02.414625] INFO: 因子分析: calculate_factor, done
[2022-08-10 19:41:02.474587] INFO: 因子分析: QuantileReturns.before_process
[2022-08-10 19:41:02.481149] INFO: 因子分析: IC.before_process
[2022-08-10 19:41:02.485426] INFO: 因子分析: BasicDescription.before_process
[2022-08-10 19:41:02.488160] INFO: 因子分析: RebalanceOverlap.before_process
[2022-08-10 19:41:02.490692] INFO: 因子分析: PBRatio.before_process
[2022-08-10 19:41:02.493316] INFO: 因子分析: Turnover.before_process
[2022-08-10 19:41:02.495885] INFO: 因子分析: FactorValue.before_process
[2022-08-10 19:41:02.498714] INFO: 因子分析: FactorPairwiseCorrelationMerged.before_process
[2022-08-10 19:41:02.540208] INFO: 因子分析: process metrics, start ..
[2022-08-10 19:41:02.587731] INFO: 因子分析: process, 217810/241536 rows ..
[2022-08-10 19:41:02.592802] INFO: 因子分析: BacktestInterval.process, 0.000s
[2022-08-10 19:41:02.596000] INFO: 因子分析: Benchmark.process, 0.000s
[2022-08-10 19:41:02.600040] INFO: 因子分析: StockPool.process, 0.000s
[2022-08-10 19:41:02.602409] INFO: 因子分析: UserDataMerge.process, 0.000s
[2022-08-10 19:41:02.604489] INFO: 因子分析: DropSTStocks.process, 0.000s
[2022-08-10 19:41:02.606587] INFO: 因子分析: DropPriceLimitStocks.process, 0.000s
[2022-08-10 19:41:02.608814] INFO: 因子分析: DropNewStocks.process, 0.000s
[2022-08-10 19:41:02.610948] INFO: 因子分析: DropSuspendedStocks.process, 0.000s
[2022-08-10 19:41:02.613012] INFO: 因子分析: QuantileCount.process, 0.000s
[2022-08-10 19:41:02.615032] INFO: 因子分析: CommissionRates.process, 0.000s
[2022-08-10 19:41:02.749659] INFO: 因子分析: Cutoutliers.process, 0.133s
[2022-08-10 19:41:02.872932] INFO: 因子分析: Normalization.process, 0.121s
[2022-08-10 19:41:05.064411] INFO: 因子分析: Neutralization.process, 2.184s
[2022-08-10 19:41:05.069229] INFO: 因子分析: DelayRebalanceDays.process, 0.000s
[2022-08-10 19:41:33.413163] INFO: 因子分析: RebalancePeriod.process, 28.341s
[2022-08-10 19:41:33.415853] INFO: 因子分析: RebalancePeriodsReturns.process, 0.000s
[2022-08-10 19:41:33.419313] INFO: 因子分析: RebalancePrice.process, 0.000s
[2022-08-10 19:41:33.422028] INFO: 因子分析: ReturnsCalculationMethod.process, 0.000s
[2022-08-10 19:41:33.431241] INFO: 因子分析: FactorCoverage.process, 0.000s
[2022-08-10 19:41:33.514448] INFO: 因子分析: QuantileReturns.process, 0.080s
[2022-08-10 19:41:33.742159] INFO: 因子分析: IC.process, 0.225s
[2022-08-10 19:41:33.771199] INFO: 因子分析: BasicDescription.process, 0.027s
[2022-08-10 19:41:33.903741] INFO: 因子分析: RebalanceOverlap.process, 0.130s
[2022-08-10 19:41:33.933826] INFO: 因子分析: PBRatio.process, 0.028s
[2022-08-10 19:41:33.954258] INFO: 因子分析: Turnover.process, 0.019s
[2022-08-10 19:41:33.993534] INFO: 因子分析: FactorValue.process, 0.037s
[2022-08-10 19:41:34.013928] INFO: 因子分析: process metrics, 31.474s
[2022-08-10 19:41:34.018712] INFO: 因子分析: QuantileReturns.after_process
[2022-08-10 19:41:34.020714] INFO: 因子分析: IC.after_process
[2022-08-10 19:41:34.022619] INFO: 因子分析: BasicDescription.after_process
[2022-08-10 19:41:34.024293] INFO: 因子分析: RebalanceOverlap.after_process
[2022-08-10 19:41:34.026051] INFO: 因子分析: PBRatio.after_process
[2022-08-10 19:41:34.028096] INFO: 因子分析: Turnover.after_process
[2022-08-10 19:41:34.030536] INFO: 因子分析: FactorValue.after_process
[2022-08-10 19:41:34.032404] INFO: 因子分析: FactorPairwiseCorrelationMerged.after_process
[2022-08-10 19:41:34.034621] INFO: 因子分析: process, correlation(turn_0, return_0, 10)
[2022-08-10 19:41:34.036703] INFO: 因子分析: calculate_factor, correlation(turn_0, return_0, 10)
[2022-08-10 19:42:13.775467] INFO: 因子分析: calculate_factor, done
[2022-08-10 19:42:13.819745] INFO: 因子分析: QuantileReturns.before_process
[2022-08-10 19:42:13.825151] INFO: 因子分析: IC.before_process
[2022-08-10 19:42:13.828740] INFO: 因子分析: BasicDescription.before_process
[2022-08-10 19:42:13.831487] INFO: 因子分析: RebalanceOverlap.before_process
[2022-08-10 19:42:13.834093] INFO: 因子分析: PBRatio.before_process
[2022-08-10 19:42:13.836198] INFO: 因子分析: Turnover.before_process
[2022-08-10 19:42:13.837954] INFO: 因子分析: FactorValue.before_process
[2022-08-10 19:42:13.839767] INFO: 因子分析: FactorPairwiseCorrelationMerged.before_process
[2022-08-10 19:42:13.879329] INFO: 因子分析: process metrics, start ..
[2022-08-10 19:42:13.927745] INFO: 因子分析: process, 183798/241536 rows ..
[2022-08-10 19:42:13.930420] INFO: 因子分析: BacktestInterval.process, 0.000s
[2022-08-10 19:42:13.933623] INFO: 因子分析: Benchmark.process, 0.000s
[2022-08-10 19:42:13.935878] INFO: 因子分析: StockPool.process, 0.000s
[2022-08-10 19:42:13.938218] INFO: 因子分析: UserDataMerge.process, 0.000s
[2022-08-10 19:42:13.941689] INFO: 因子分析: DropSTStocks.process, 0.000s
[2022-08-10 19:42:13.944027] INFO: 因子分析: DropPriceLimitStocks.process, 0.000s
[2022-08-10 19:42:13.948888] INFO: 因子分析: DropNewStocks.process, 0.000s
[2022-08-10 19:42:13.951551] INFO: 因子分析: DropSuspendedStocks.process, 0.000s
[2022-08-10 19:42:13.954115] INFO: 因子分析: QuantileCount.process, 0.000s
[2022-08-10 19:42:13.956351] INFO: 因子分析: CommissionRates.process, 0.000s
[2022-08-10 19:42:14.071433] INFO: 因子分析: Cutoutliers.process, 0.113s
[2022-08-10 19:42:14.151377] INFO: 因子分析: Normalization.process, 0.077s
[2022-08-10 19:42:16.113660] INFO: 因子分析: Neutralization.process, 1.960s
[2022-08-10 19:42:16.116117] INFO: 因子分析: DelayRebalanceDays.process, 0.000s
[2022-08-10 19:42:48.649674] INFO: 因子分析: RebalancePeriod.process, 32.532s
[2022-08-10 19:42:48.652026] INFO: 因子分析: RebalancePeriodsReturns.process, 0.000s
[2022-08-10 19:42:48.653752] INFO: 因子分析: RebalancePrice.process, 0.000s
[2022-08-10 19:42:48.655617] INFO: 因子分析: ReturnsCalculationMethod.process, 0.000s
[2022-08-10 19:42:48.657073] INFO: 因子分析: FactorCoverage.process, 0.000s
[2022-08-10 19:42:48.676755] ERROR: moduleinvoker: module name: factorlens, module version: v2, trackeback: KeyError: "['ADJUSTED_BENCHMARK_RETURN_0'] not in index"