[2022-05-14 15:04:44.501299] INFO: moduleinvoker: input_features.v1 开始运行..
[2022-05-14 15:04:44.521285] INFO: moduleinvoker: 命中缓存
[2022-05-14 15:04:44.522911] INFO: moduleinvoker: input_features.v1 运行完成[0.021646s].
[2022-05-14 15:04:44.533060] INFO: moduleinvoker: instruments.v2 开始运行..
[2022-05-14 15:04:45.790386] INFO: moduleinvoker: instruments.v2 运行完成[1.257324s].
[2022-05-14 15:04:45.814198] INFO: moduleinvoker: general_feature_extractor.v7 开始运行..
[2022-05-14 15:04:46.755404] INFO: 基础特征抽取: 年份 2011, 特征行数=131825
[2022-05-14 15:04:48.001774] INFO: 基础特征抽取: 年份 2012, 特征行数=565675
[2022-05-14 15:04:50.989476] INFO: 基础特征抽取: 年份 2013, 特征行数=564168
[2022-05-14 15:04:51.928392] INFO: 基础特征抽取: 年份 2014, 特征行数=0
[2022-05-14 15:04:52.003617] INFO: 基础特征抽取: 总行数: 1261668
[2022-05-14 15:04:52.008813] INFO: moduleinvoker: general_feature_extractor.v7 运行完成[6.19461s].
[2022-05-14 15:04:52.025172] INFO: moduleinvoker: derived_feature_extractor.v3 开始运行..
[2022-05-14 15:04:54.053008] INFO: derived_feature_extractor: 提取完成 log(market_cap_float_0), 0.055s
[2022-05-14 15:04:54.416520] INFO: derived_feature_extractor: /y_2011, 131825
[2022-05-14 15:04:55.287495] INFO: derived_feature_extractor: /y_2012, 565675
[2022-05-14 15:04:56.212777] INFO: derived_feature_extractor: /y_2013, 564168
[2022-05-14 15:04:58.349564] INFO: moduleinvoker: derived_feature_extractor.v3 运行完成[6.324388s].
[2022-05-14 15:04:58.366869] INFO: moduleinvoker: advanced_auto_labeler.v2 开始运行..
[2022-05-14 15:05:13.451364] INFO: 自动标注(股票): 加载历史数据: 1129843 行
[2022-05-14 15:05:13.452909] INFO: 自动标注(股票): 开始标注 ..
[2022-05-14 15:05:18.067780] INFO: moduleinvoker: advanced_auto_labeler.v2 运行完成[19.700899s].
[2022-05-14 15:05:18.085058] INFO: moduleinvoker: join.v3 开始运行..
[2022-05-14 15:05:21.661431] INFO: join: /y_2011, 行数=0/131825, 耗时=0.542953s
[2022-05-14 15:05:23.047085] INFO: join: /y_2012, 行数=564578/565675, 耗时=1.38341s
[2022-05-14 15:05:24.364680] INFO: join: /y_2013, 行数=550888/564168, 耗时=1.313128s
[2022-05-14 15:05:24.458450] INFO: join: 最终行数: 1115466
[2022-05-14 15:05:24.470786] INFO: moduleinvoker: join.v3 运行完成[6.385726s].
[2022-05-14 15:05:24.488060] INFO: moduleinvoker: dropnan.v2 开始运行..
[2022-05-14 15:05:24.595182] INFO: dropnan: /y_2011, 0/0
[2022-05-14 15:05:25.094231] INFO: dropnan: /y_2012, 564578/564578
[2022-05-14 15:05:25.525792] INFO: dropnan: /y_2013, 550888/550888
[2022-05-14 15:05:25.617513] INFO: dropnan: 行数: 1115466/1115466
[2022-05-14 15:05:25.625561] INFO: moduleinvoker: dropnan.v2 运行完成[1.137492s].
[2022-05-14 15:05:25.633279] INFO: moduleinvoker: instruments.v2 开始运行..
[2022-05-14 15:05:25.640814] INFO: moduleinvoker: 命中缓存
[2022-05-14 15:05:25.642213] INFO: moduleinvoker: instruments.v2 运行完成[0.008947s].
[2022-05-14 15:05:25.656728] INFO: moduleinvoker: general_feature_extractor.v7 开始运行..
[2022-05-14 15:05:28.156099] INFO: 基础特征抽取: 年份 2014, 特征行数=141569
[2022-05-14 15:05:34.863731] INFO: 基础特征抽取: 年份 2015, 特征行数=569698
[2022-05-14 15:05:38.096928] INFO: 基础特征抽取: 年份 2016, 特征行数=641546
[2022-05-14 15:05:39.585206] INFO: 基础特征抽取: 年份 2017, 特征行数=0
[2022-05-14 15:05:39.648600] INFO: 基础特征抽取: 总行数: 1352813
[2022-05-14 15:05:39.652738] INFO: moduleinvoker: general_feature_extractor.v7 运行完成[13.996009s].
[2022-05-14 15:05:39.664644] INFO: moduleinvoker: derived_feature_extractor.v3 开始运行..
[2022-05-14 15:05:41.684208] INFO: derived_feature_extractor: 提取完成 log(market_cap_float_0), 0.017s
[2022-05-14 15:05:42.051198] INFO: derived_feature_extractor: /y_2014, 141569
[2022-05-14 15:05:43.005911] INFO: derived_feature_extractor: /y_2015, 569698
[2022-05-14 15:05:43.995312] INFO: derived_feature_extractor: /y_2016, 641546
[2022-05-14 15:05:44.215901] INFO: moduleinvoker: derived_feature_extractor.v3 运行完成[4.551253s].
[2022-05-14 15:05:44.226648] INFO: moduleinvoker: dropnan.v2 开始运行..
[2022-05-14 15:05:44.386385] INFO: dropnan: /y_2014, 141569/141569
[2022-05-14 15:05:44.699692] INFO: dropnan: /y_2015, 569698/569698
[2022-05-14 15:05:44.976499] INFO: dropnan: /y_2016, 641546/641546
[2022-05-14 15:05:45.050463] INFO: dropnan: 行数: 1352813/1352813
[2022-05-14 15:05:45.057987] INFO: moduleinvoker: dropnan.v2 运行完成[0.831339s].
[2022-05-14 15:05:45.083181] INFO: moduleinvoker: stock_ranker.v2 开始运行..
[2022-05-14 15:05:45.114224] INFO: moduleinvoker: stock_ranker_train.v6 开始运行..
[2022-05-14 15:05:45.831958] INFO: StockRanker: 特征预处理 ..
[2022-05-14 15:05:45.974516] INFO: StockRanker: prepare data: training ..
[2022-05-14 15:05:46.084292] INFO: StockRanker: sort ..
[2022-05-14 15:05:55.623651] INFO: StockRanker训练: 464541ea 准备训练: 1115466 行数
[2022-05-14 15:05:55.625333] INFO: StockRanker训练: AI模型训练,将在1115466*1=111.55万数据上对模型训练进行20轮迭代训练。预计将需要1~3分钟。请耐心等待。
[2022-05-14 15:05:55.853743] INFO: StockRanker训练: 正在训练 ..
[2022-05-14 15:05:55.907479] INFO: StockRanker训练: 任务状态: Pending
[2022-05-14 15:06:05.953966] INFO: StockRanker训练: 任务状态: Running
[2022-05-14 15:07:16.296102] INFO: StockRanker训练: 00:01:09.9482061, finished iteration 1
[2022-05-14 15:07:26.350006] INFO: StockRanker训练: 00:01:18.2522688, finished iteration 2
[2022-05-14 15:07:36.399730] INFO: StockRanker训练: 00:01:27.3237494, finished iteration 3
[2022-05-14 15:07:46.447442] INFO: StockRanker训练: 00:01:37.5513944, finished iteration 4
[2022-05-14 15:07:56.489950] INFO: StockRanker训练: 00:01:48.4770907, finished iteration 5
[2022-05-14 15:08:06.533370] INFO: StockRanker训练: 00:01:59.0212844, finished iteration 6
[2022-05-14 15:08:16.575583] INFO: StockRanker训练: 00:02:09.3270432, finished iteration 7
[2022-05-14 15:08:26.618639] INFO: StockRanker训练: 00:02:18.3284961, finished iteration 8
[2022-05-14 15:08:26.620159] INFO: StockRanker训练: 00:02:25.9822097, finished iteration 9
[2022-05-14 15:08:36.666130] INFO: StockRanker训练: 00:02:34.1849179, finished iteration 10
[2022-05-14 15:08:46.724827] INFO: StockRanker训练: 00:02:42.8613899, finished iteration 11
[2022-05-14 15:08:56.766360] INFO: StockRanker训练: 00:02:51.1432906, finished iteration 12
[2022-05-14 15:09:06.821161] INFO: StockRanker训练: 00:02:59.2499547, finished iteration 13
[2022-05-14 15:09:16.854277] INFO: StockRanker训练: 00:03:08.0369204, finished iteration 14
[2022-05-14 15:09:16.855968] INFO: StockRanker训练: 00:03:16.3615083, finished iteration 15
[2022-05-14 15:09:26.896451] INFO: StockRanker训练: 00:03:25.5626948, finished iteration 16
[2022-05-14 15:09:36.937696] INFO: StockRanker训练: 00:03:34.7732114, finished iteration 17
[2022-05-14 15:09:46.978881] INFO: StockRanker训练: 00:03:44.1682078, finished iteration 18
[2022-05-14 15:09:57.017970] INFO: StockRanker训练: 00:03:52.9111124, finished iteration 19
[2022-05-14 15:10:07.059653] INFO: StockRanker训练: 00:04:00.9965876, finished iteration 20
[2022-05-14 15:10:07.061139] INFO: StockRanker训练: 任务状态: Succeeded
[2022-05-14 15:10:07.226822] INFO: moduleinvoker: stock_ranker_train.v6 运行完成[262.112593s].
[2022-05-14 15:10:07.245768] INFO: moduleinvoker: stock_ranker_predict.v5 开始运行..
[2022-05-14 15:10:07.536102] INFO: StockRanker预测: /y_2014 ..
[2022-05-14 15:10:07.764449] INFO: StockRanker预测: /y_2015 ..
[2022-05-14 15:10:08.309790] INFO: StockRanker预测: /y_2016 ..
[2022-05-14 15:10:10.023551] INFO: moduleinvoker: stock_ranker_predict.v5 运行完成[2.777788s].
[2022-05-14 15:10:10.093841] INFO: moduleinvoker: stock_ranker.v2 运行完成[265.010657s].
[2022-05-14 15:10:11.914968] INFO: moduleinvoker: backtest.v8 开始运行..
[2022-05-14 15:10:11.919987] INFO: backtest: biglearning backtest:V8.6.2
[2022-05-14 15:10:11.921127] INFO: backtest: product_type:stock by specified
[2022-05-14 15:10:12.085946] INFO: moduleinvoker: cached.v2 开始运行..
[2022-05-14 15:10:18.315878] INFO: backtest: 读取股票行情完成:2212017
[2022-05-14 15:10:20.026023] INFO: moduleinvoker: cached.v2 运行完成[7.940087s].
[2022-05-14 15:10:21.920364] INFO: algo: TradingAlgorithm V1.8.7
[2022-05-14 15:10:22.881548] INFO: algo: trading transform...
[2022-05-14 15:10:24.848676] INFO: algo: handle_splits get splits [dt:2015-04-27 00:00:00+00:00] [asset:Equity(1479 [603006.SHA]), ratio:0.9959732890129089]
[2022-05-14 15:10:24.850176] INFO: Position: position stock handle split[sid:1479, orig_amount:1500, new_amount:1506.0, orig_cost:48.00000068618622, new_cost:47.8067, ratio:0.9959732890129089, last_sale_price:49.46998977661133]
[2022-05-14 15:10:24.851243] INFO: Position: after split: PositionStock(asset:Equity(1479 [603006.SHA]), amount:1506.0, cost_basis:47.8067, last_sale_price:49.66999816894531)
[2022-05-14 15:10:24.852207] INFO: Position: returning cash: 3.1901
[2022-05-14 15:10:25.305132] INFO: algo: handle_splits get splits [dt:2015-05-27 00:00:00+00:00] [asset:Equity(764 [002735.SZA]), ratio:0.9986258745193481]
[2022-05-14 15:10:25.355969] INFO: algo: handle_splits get splits [dt:2015-05-29 00:00:00+00:00] [asset:Equity(2754 [002732.SZA]), ratio:0.9956985712051392]
[2022-05-14 15:10:25.357361] INFO: Position: position stock handle split[sid:2754, orig_amount:2300, new_amount:2309.0, orig_cost:30.10000062755383, new_cost:29.9705, ratio:0.9956985712051392, last_sale_price:46.299983978271484]
[2022-05-14 15:10:25.358396] INFO: Position: after split: PositionStock(asset:Equity(2754 [002732.SZA]), amount:2309.0, cost_basis:29.9705, last_sale_price:46.5)
[2022-05-14 15:10:25.359274] INFO: Position: returning cash: 43.338
[2022-05-14 15:10:25.598098] INFO: algo: handle_splits get splits [dt:2015-06-16 00:00:00+00:00] [asset:Equity(1634 [603010.SHA]), ratio:0.9974614381790161]
[2022-05-14 15:10:25.599550] INFO: Position: position stock handle split[sid:1634, orig_amount:2400, new_amount:2406.0, orig_cost:49.25000379121263, new_cost:49.125, ratio:0.9974614381790161, last_sale_price:51.07999801635742]
[2022-05-14 15:10:25.600574] INFO: Position: after split: PositionStock(asset:Equity(1634 [603010.SHA]), amount:2406.0, cost_basis:49.125, last_sale_price:51.209999084472656)
[2022-05-14 15:10:25.601468] INFO: Position: returning cash: 5.5194
[2022-05-14 15:10:25.654115] INFO: algo: handle_splits get splits [dt:2015-06-18 00:00:00+00:00] [asset:Equity(1916 [300381.SZA]), ratio:0.9951326847076416]
[2022-05-14 15:10:25.655529] INFO: Position: position stock handle split[sid:1916, orig_amount:2500, new_amount:2512.0, orig_cost:35.51000511899547, new_cost:35.3372, ratio:0.9951326847076416, last_sale_price:40.8900032043457]
[2022-05-14 15:10:25.656551] INFO: Position: after split: PositionStock(asset:Equity(1916 [300381.SZA]), amount:2512.0, cost_basis:35.3372, last_sale_price:41.09000015258789)
[2022-05-14 15:10:25.657437] INFO: Position: returning cash: 9.3149
[2022-05-14 15:10:25.765031] INFO: algo: handle_splits get splits [dt:2015-06-26 00:00:00+00:00] [asset:Equity(187 [002743.SZA]), ratio:0.9988213181495667]
[2022-05-14 15:10:25.766458] INFO: Position: position stock handle split[sid:187, orig_amount:2000, new_amount:2002.0, orig_cost:39.65999998235258, new_cost:39.6133, ratio:0.9988213181495667, last_sale_price:50.84000778198242]
[2022-05-14 15:10:25.767466] INFO: Position: after split: PositionStock(asset:Equity(187 [002743.SZA]), amount:2002.0, cost_basis:39.6133, last_sale_price:50.900001525878906)
[2022-05-14 15:10:25.768351] INFO: Position: returning cash: 18.3098
[2022-05-14 15:10:26.377342] INFO: algo: handle_splits get splits [dt:2015-07-31 00:00:00+00:00] [asset:Equity(411 [002703.SZA]), ratio:0.9980777502059937]
[2022-05-14 15:10:26.378766] INFO: Position: position stock handle split[sid:411, orig_amount:2500, new_amount:2504.0, orig_cost:40.05000711959119, new_cost:39.973, ratio:0.9980777502059937, last_sale_price:51.91001892089844]
[2022-05-14 15:10:26.379792] INFO: Position: after split: PositionStock(asset:Equity(411 [002703.SZA]), amount:2504.0, cost_basis:39.973, last_sale_price:52.00999450683594)
[2022-05-14 15:10:26.380681] INFO: Position: returning cash: 42.3004
[2022-05-14 15:10:27.781531] INFO: algo: handle_splits get splits [dt:2015-11-05 00:00:00+00:00] [asset:Equity(991 [603066.SHA]), ratio:0.9974276423454285]
[2022-05-14 15:10:30.292979] INFO: algo: handle_splits get splits [dt:2016-04-19 00:00:00+00:00] [asset:Equity(1210 [603889.SHA]), ratio:0.4944533109664917]
[2022-05-14 15:10:30.294415] INFO: Position: position stock handle split[sid:1210, orig_amount:3900, new_amount:7887.0, orig_cost:28.590000328754368, new_cost:14.1364, ratio:0.4944533109664917, last_sale_price:15.60000228881836]
[2022-05-14 15:10:30.295442] INFO: Position: after split: PositionStock(asset:Equity(1210 [603889.SHA]), amount:7887.0, cost_basis:14.1364, last_sale_price:31.55000114440918)
[2022-05-14 15:10:30.296325] INFO: Position: returning cash: 7.7845
[2022-05-14 15:10:30.567402] INFO: algo: handle_splits get splits [dt:2016-05-06 00:00:00+00:00] [asset:Equity(1228 [002718.SZA]), ratio:0.9910967946052551]
[2022-05-14 15:10:30.568958] INFO: Position: position stock handle split[sid:1228, orig_amount:2500, new_amount:2522.0, orig_cost:56.15001491439162, new_cost:55.6501, ratio:0.9910967946052551, last_sale_price:55.65999984741211]
[2022-05-14 15:10:30.570077] INFO: Position: after split: PositionStock(asset:Equity(1228 [002718.SZA]), amount:2522.0, cost_basis:55.6501, last_sale_price:56.160003662109375)
[2022-05-14 15:10:30.571010] INFO: Position: returning cash: 25.4901
[2022-05-14 15:10:30.759647] INFO: algo: handle_splits get splits [dt:2016-05-17 00:00:00+00:00] [asset:Equity(1808 [300472.SZA]), ratio:0.6654706001281738]
[2022-05-14 15:10:30.761078] INFO: Position: position stock handle split[sid:1808, orig_amount:13900, new_amount:20887.0, orig_cost:45.92585429138522, new_cost:30.5623, ratio:0.6654706001281738, last_sale_price:31.529996871948242]
[2022-05-14 15:10:30.762089] INFO: Position: after split: PositionStock(asset:Equity(1808 [300472.SZA]), amount:20887.0, cost_basis:30.5623, last_sale_price:47.380001068115234)
[2022-05-14 15:10:30.762976] INFO: Position: returning cash: 14.9519
[2022-05-14 15:10:30.903051] INFO: algo: handle_splits get splits [dt:2016-05-25 00:00:00+00:00] [asset:Equity(1807 [002761.SZA]), ratio:0.9989590644836426]
[2022-05-14 15:10:30.904516] INFO: Position: position stock handle split[sid:1807, orig_amount:3600, new_amount:3603.0, orig_cost:38.800002335712776, new_cost:38.7596, ratio:0.9989590644836426, last_sale_price:38.3800048828125]
[2022-05-14 15:10:30.905642] INFO: Position: after split: PositionStock(asset:Equity(1807 [002761.SZA]), amount:3603.0, cost_basis:38.7596, last_sale_price:38.41999816894531)
[2022-05-14 15:10:30.906533] INFO: Position: returning cash: 28.8338
[2022-05-14 15:10:30.961645] INFO: algo: handle_splits get splits [dt:2016-05-27 00:00:00+00:00] [asset:Equity(2502 [300421.SZA]), ratio:0.9871792197227478]
[2022-05-14 15:10:30.963129] INFO: Position: position stock handle split[sid:2502, orig_amount:26000, new_amount:26337.0, orig_cost:28.107081918444006, new_cost:27.7467, ratio:0.9871792197227478, last_sale_price:26.949993133544922]
[2022-05-14 15:10:30.964361] INFO: Position: after split: PositionStock(asset:Equity(2502 [300421.SZA]), amount:26337.0, cost_basis:27.7467, last_sale_price:27.30000114440918)
[2022-05-14 15:10:30.965431] INFO: Position: returning cash: 18.0423
[2022-05-14 15:10:31.033432] INFO: algo: handle_splits get splits [dt:2016-06-01 00:00:00+00:00] [asset:Equity(2621 [002724.SZA]), ratio:0.9984560608863831]
[2022-05-14 15:10:31.048317] INFO: algo: handle_splits get splits [dt:2016-06-01 00:00:00+00:00] [asset:Equity(2320 [300445.SZA]), ratio:0.49911510944366455]
[2022-05-14 15:10:31.049618] INFO: Position: position stock handle split[sid:2320, orig_amount:4100, new_amount:8214.0, orig_cost:45.71000251848638, new_cost:22.8146, ratio:0.49911510944366455, last_sale_price:25.380001068115234]
[2022-05-14 15:10:31.051025] INFO: Position: after split: PositionStock(asset:Equity(2320 [300445.SZA]), amount:8214.0, cost_basis:22.8146, last_sale_price:50.84999465942383)
[2022-05-14 15:10:31.052366] INFO: Position: returning cash: 13.6528
[2022-05-14 15:10:31.184084] INFO: algo: handle_splits get splits [dt:2016-06-08 00:00:00+00:00] [asset:Equity(825 [603988.SHA]), ratio:0.996868371963501]
[2022-05-14 15:10:31.185648] INFO: Position: position stock handle split[sid:825, orig_amount:5000, new_amount:5015.0, orig_cost:51.75000881585474, new_cost:51.5879, ratio:0.996868371963501, last_sale_price:54.1199836730957]
[2022-05-14 15:10:31.186913] INFO: Position: after split: PositionStock(asset:Equity(825 [603988.SHA]), amount:5015.0, cost_basis:51.5879, last_sale_price:54.290000915527344)
[2022-05-14 15:10:31.188311] INFO: Position: returning cash: 38.2807
[2022-05-14 15:10:31.232297] INFO: algo: handle_splits get splits [dt:2016-06-14 00:00:00+00:00] [asset:Equity(2372 [603696.SHA]), ratio:0.9956093430519104]
[2022-05-14 15:10:31.233718] INFO: Position: position stock handle split[sid:2372, orig_amount:9400, new_amount:9441.0, orig_cost:38.81776950300233, new_cost:38.6473, ratio:0.9956093430519104, last_sale_price:34.010013580322266]
[2022-05-14 15:10:31.248293] INFO: Position: after split: PositionStock(asset:Equity(2372 [603696.SHA]), amount:9441.0, cost_basis:38.6473, last_sale_price:34.15999984741211)
[2022-05-14 15:10:31.249767] INFO: Position: returning cash: 15.4469
[2022-05-14 15:10:31.405093] INFO: algo: handle_splits get splits [dt:2016-06-23 00:00:00+00:00] [asset:Equity(2750 [603223.SHA]), ratio:0.9977739453315735]
[2022-05-14 15:10:31.406539] INFO: Position: position stock handle split[sid:2750, orig_amount:5500, new_amount:5512.0, orig_cost:31.05001180500629, new_cost:30.9809, ratio:0.9977739453315735, last_sale_price:31.370014190673828]
[2022-05-14 15:10:31.407677] INFO: Position: after split: PositionStock(asset:Equity(2750 [603223.SHA]), amount:5512.0, cost_basis:30.9809, last_sale_price:31.440000534057617)
[2022-05-14 15:10:31.408999] INFO: Position: returning cash: 8.4892
[2022-05-14 15:10:34.007872] INFO: Performance: Simulated 488 trading days out of 488.
[2022-05-14 15:10:34.009409] INFO: Performance: first open: 2015-01-05 09:30:00+00:00
[2022-05-14 15:10:34.010450] INFO: Performance: last close: 2016-12-30 15:00:00+00:00
[2022-05-14 15:10:37.329933] INFO: moduleinvoker: backtest.v8 运行完成[25.414966s].
[2022-05-14 15:10:37.331618] INFO: moduleinvoker: trade.v4 运行完成[27.224279s].