10:59:59.09[7]DEBUG: 90674046   1  RIZ0   160090 .    250 .
10:59:59.09[7]INFO :INFO: 90674046   1  RIZ0   160090 .    250 .
10:59:59.09[7]DEBUG:Finishing operation with success = True
10:59:59.09[7]DEBUG:POM:PortfolioOrder created
10:59:59.09[7]INFO :RIZ0:RTS_FUT: Orders has been updated for . 1 active orders, 2 total
10:59:59.09[7]INFO :: ''     508. (2142 ,  15.11.2010 10:45:00)
10:59:59.50[MQ<SmcTradeData>]DEBUG:Processing 7 skipped trades
11:14:58.94[13]INFO :RIZ0:RTS_FUT: Orders has been updated for . 1 active orders, 2 total
11:14:58.97[13]INFO :RIZ0:RTS_FUT: Add command RtCloseByStop 'Exit by stop' entry=false
11:14:58.97[13]INFO :RIZ0:RTS_FUT: Orders has been updated for . 1 active orders, 2 total
11:14:58.97[13]INFO :RIZ0:RTS_FUT: Check moving order 90674046 to price 160090 from 160090 diff 0
11:14:58.97[13]INFO :RIZ0:RTS_FUT: Orders has been updated for . 1 active orders, 2 total
11:14:58.97[13]INFO :: ''     26. (2143 ,  15.11.2010 11:00:00)
11:14:59.44[MQ<SmcTradeData>]DEBUG:Processing 5 skipped trades
11:29:58.86[7]INFO :RIZ0:RTS_FUT: Orders has been updated for . 1 active orders, 2 total
11:29:58.90[7]INFO :RIZ0:RTS_FUT: Add command RtCloseByStop 'Exit by stop' entry=false
11:29:58.90[7]INFO :RIZ0:RTS_FUT: Orders has been updated for . 1 active orders, 2 total
11:29:58.90[7]INFO :RIZ0:RTS_FUT: Check moving order 90674046 to price 160090 from 160090 diff 0
11:29:58.90[7]INFO :RIZ0:RTS_FUT: Orders has been updated for . 1 active orders, 2 total
11:29:58.90[7]INFO :: ''     47. (2144 ,  15.11.2010 11:15:00)
11:30:03.73[1]DEBUG:SmartCOM. UpdateOrder 438378922 0 StOrder_State_Open StOrder_Type_StopLimit
11:30:03.74[MQ<OrderInfo>]DEBUG:New OrderInfo 438378922 comment=90674046#7#20#.Close_long$Close$90530208 parsed=True orderid=90674046
11:30:03.74[MQ<OrderInfo>]DEBUG:PO 90674046 IT :IT Invest:RIZ0 upd: price=160090 status=Wait->Active,active=True->True,executed=False->False, q=1, rest=1
11:30:03.74[MQ<OrderInfo>]DEBUG:Cmd: 353003485, "", error=False, last=True, orderId=438378922
11:30:04.16[1]DEBUG:SmartCOM. UpdateOrder 438378922 2669847020 StOrder_State_Open StOrder_Type_StopLimit
11:30:04.83[1]DEBUG:SmartCOM. UpdateOrder 438378922 2669847020 StOrder_State_Open StOrder_Type_StopLimit
11:44:58.35[4]INFO :RIZ0:RTS_FUT: Orders has been updated for . 1 active orders, 2 total
11:44:58.37[4]INFO :RIZ0:RTS_FUT: Add command for Close by market aproved: True
11:44:58.37[4]INFO :RIZ0:RTS_FUT: Add command RtOpenbyEq entry=true
11:44:58.37[4]INFO :RIZ0:RTS_FUT: Orders has been updated for . 1 active orders, 2 total
11:44:58.37[4]INFO :RIZ0:RTS_FUT: MoveByMarket Close_long: Close_long$Close$90530208
11:44:58.37[4]INFO :RIZ0:RTS_FUT: CancelOrder before change .Close_long
11:44:58.37[4]DEBUG:Cancel PO 90674046 oi=438378922:90674046#7#20#.Close_long$Close$90530208:A=True:S
11:44:58.37[4]DEBUG:SmartCOM. CancelOrder 438378922
11:44:58.43[4]DEBUG:Cmd: 353003485, "   ", error=False, last=True, orderId=438378922
11:44:58.60[1]DEBUG:SmartCOM. UpdateOrder 438378922 2669847020 StOrder_State_Open StOrder_Type_StopLimit
11:44:58.66[1]DEBUG:SmartCOM. UpdateOrder 438378922 2669847020 StOrder_State_Cancel StOrder_Type_StopLimit
11:44:58.67[MQ<OrderInfo>]DEBUG:New OrderInfo 438378922 comment=90674046#7#20#.Close_long$Close$90530208 parsed=True orderid=90674046
11:44:58.67[MQ<OrderInfo>]DEBUG:PO 90674046 IT :IT Invest:RIZ0 upd: price=160090 status=Active->Cancelled,active=True->False,executed=False->False, q=1, rest=1
11:44:58.67[MQ<OrderInfo>]DEBUG:Cmd: 353003485, "", error=False, last=True, orderId=438378922
11:44:58.68[4]DEBUG: 90674046   1  RIZ0   160090 .    304 .
11:44:58.68[4]INFO :INFO: 90674046   1  RIZ0   160090 .    304 .
11:44:58.68[4]DEBUG:Finishing operation with success = True
11:44:58.68[4]DEBUG:POM:Cancel:True
11:44:58.68[4]INFO :RIZ0:RTS_FUT: CancelOrder has been successefull .Close_long
11:44:58.68[4]INFO :RIZ0:RTS_FUT: NewOrder for change Close_long, B/S=False, Price=, Slip=20, Type=Market, Comment=.Close_long$Close$90530208
11:44:58.68[4]DEBUG:DBManager.NewOrder from:TSLab.LabView.Realtime.RealtimePositionList.ChangeOrder TSLab.LabView.Realtime.RealtimePositionList.MoveByMarket 
11:44:58.68[4]DEBUG:IT :7:A(BP9145-RF-01)=BP9145-RF-01:DS:IT Invest:A=True:B=False:RIZ0:RTS_FUT:New order starting for id=90676579 
11:44:58.68[4]DEBUG:NewOrder:New order for po id=90676579
11:44:58.69[4]DEBUG:SmartCOM. PlaceOrder 353003486 StOrder_Type_Market 0 0 1
11:44:59.28[10]DEBUG:Refresh is needed for RIZ0:RTS_FUT. Cur time 15.11.2010 11:45:00, last trade time 15.11.2010 11:45:00
11:44:59.28[10]DEBUG:GetMinuteBars for RTS-12.10_FT interval=15
11:44:59.28[10]DEBUG:Send request for RIZ0:RTS_FUT reset True interval 15M
11:44:59.29[10]DEBUG:GetMinuteBars end for RTS-12.10_FT interval=15
11:44:59.32[10]DEBUG:Adding 46074 trades from 15.11.2010 10:15:00 to RIZ0:RTS_FUT
11:44:59.32[10]DEBUG:CheckAndCopyData extends RIZ0:RTS_FUT
11:44:59.50[1]DEBUG:SmartCOM. UpdateOrder 438523711 0 StOrder_State_Pending StOrder_Type_Market
11:44:59.50[MQ<OrderInfo>]DEBUG:SmartCOM. place to lagged 438523711
11:44:59.51[1]DEBUG:SmartCOM. OrderSucceeded 353003486 438523711
11:44:59.52[1]DEBUG:SmartCOM. remove from lagged 438523711
11:44:59.53[1]DEBUG:New OrderInfo 438523711 comment=90676579#7#0#.Close_long$Close$90530208 parsed=True orderid=90676579
11:44:59.53[1]DEBUG:PO 90676579 IT :IT Invest:RIZ0 upd: price=0 status=None->Wait,active=False->True,executed=False->False, q=1, rest=1
11:44:59.53[1]DEBUG:Cmd: 353003486, "", error=False, last=True, orderId=438523711
11:44:59.53[4]DEBUG: 90676579   1  RIZ0    .    851 .
11:44:59.53[4]INFO :INFO: 90676579   1  RIZ0    .    851 .
11:44:59.53[4]DEBUG:Finishing operation with success = True
11:44:59.53[4]DEBUG:POM:PortfolioOrder created
11:44:59.53[4]INFO :RIZ0:RTS_FUT: FindActiveOrder for Long isn't found
11:44:59.53[4]INFO :RIZ0:RTS_FUT: NewStopOrder Long count=1
11:44:59.53[4]INFO :RIZ0:RTS_FUT: NewOpenOrder Long, B/S=True, Price=160890, Type=Growth, Comment=.Long
11:44:59.53[4]DEBUG:DBManager.NewOrder from:TSLab.LabView.Realtime.RealtimePositionList.NewOrder TSLab.LabView.Realtime.RealtimePositionList.NewOpenOrder 
11:44:59.53[4]DEBUG:IT :7:A(BP9145-RF-01)=BP9145-RF-01:DS:IT Invest:A=True:B=False:RIZ0:RTS_FUT:New order starting for id=90676438 
11:44:59.53[4]DEBUG:NewOrder:New order for po id=90676438
11:44:59.54[4]DEBUG:SmartCOM. PlaceOrder 353003487 StOrder_Type_StopLimit 160910 160890 1
11:44:59.69[1]DEBUG:SmartCOM. UpdateOrder 438523711 2670200979 StOrder_State_Open StOrder_Type_Market
11:44:59.70[MQ<OrderInfo>]DEBUG:New OrderInfo 438523711 comment=90676579#7#0#.Close_long$Close$90530208 parsed=True orderid=90676579
11:44:59.70[MQ<OrderInfo>]DEBUG:PO 90676579 IT :IT Invest:RIZ0 upd: price=0 status=Wait->Active,active=True->True,executed=False->False, q=1, rest=1
11:44:59.70[MQ<OrderInfo>]DEBUG:Cmd: 353003486, "", error=False, last=True, orderId=438523711
11:44:59.88[1]DEBUG:SmartCOM. OrderSucceeded 353003487 438523744
11:44:59.90[1]DEBUG:SmartCOM. UpdateOrder 438523744 0 StOrder_State_Pending StOrder_Type_StopLimit
11:44:59.91[MQ<OrderInfo>]DEBUG:New OrderInfo 438523744 comment=90676438#7#20#.Long parsed=True orderid=90676438
11:44:59.91[MQ<OrderInfo>]DEBUG:PO 90676438 IT :IT Invest:RIZ0 upd: price=160890 status=None->Wait,active=False->True,executed=False->False, q=1, rest=1
11:44:59.91[MQ<OrderInfo>]DEBUG:Cmd: 353003487, "", error=False, last=True, orderId=438523744
11:44:59.92[4]DEBUG: 90676438   1  RIZ0   160890 .    386 .
11:44:59.92[4]INFO :INFO: 90676438   1  RIZ0   160890 .    386 .
11:44:59.92[4]DEBUG:Finishing operation with success = True
11:44:59.92[4]DEBUG:POM:PortfolioOrder created
11:44:59.92[4]INFO :RIZ0:RTS_FUT: Orders has been updated for . 2 active orders, 3 total
11:44:59.92[4]INFO :: ''     1576. (2145 ,  15.11.2010 11:30:00)
11:44:59.92[7]DEBUG:Refresh is needed for RIZ0:RTS_FUT. Cur time 15.11.2010 11:45:01, last trade time 15.11.2010 11:45:01
11:44:59.92[7]DEBUG:GetMinuteBars for RTS-12.10_FT interval=15
11:45:00.09[1]DEBUG:SmartCOM. UpdateOrder 438378922 2669847020 StOrder_State_Cancel StOrder_Type_StopLimit
11:45:00.10[1]DEBUG:SmartCOM. UpdateOrder 438523711 2670200979 StOrder_State_Filled StOrder_Type_Market
11:45:00.11[MQ<OrderInfo>]DEBUG:New OrderInfo 438523711 comment=90676579#7#0#.Close_long$Close$90530208 parsed=True orderid=90676579
11:45:00.11[MQ<OrderInfo>]DEBUG:PO 90676579 IT :IT Invest:RIZ0 upd: price=154185 status=Active->Executed,active=True->False,executed=False->True, q=1, rest=0
11:45:00.11[MQ<OrderInfo>]DEBUG:  90676579 .  1 RIZ0.  0 ().
11:45:00.11[MQ<OrderInfo>]INFO :INFO:  90676579 .  1 RIZ0.  0 ().
11:45:00.11[MQ<OrderInfo>]DEBUG:Cmd: 353003486, "", error=False, last=True, orderId=438523711
11:45:00.16[1]DEBUG:SmartCOM. AddTrade 238317982 2670200979
11:45:00.17[MQ<TradeWithOwner>]DEBUG:Trade=238317982:RIZ0:159080:15.11.2010 11:45:01:1:BP9145-RF-01:90676579#7#0#.Close_long$Close$90530208
11:45:00.17[MQ<TradeWithOwner>]INFO :INFO: 238317982:  1  RIZ0   159080.
11:45:00.21[7]DEBUG:GetMinuteBars end for RTS-12.10_FT interval=15
11:45:00.31[MQ<SmcTradeData>]DEBUG:Processing 48 skipped trades
11:45:00.74[7]DEBUG:Refresh is needed for RIZ0:RTS_FUT. Cur time 15.11.2010 11:45:02, last trade time 15.11.2010 11:45:01
11:45:00.74[7]DEBUG:GetMinuteBars for RTS-12.10_FT interval=15
11:45:00.74[7]DEBUG:GetMinuteBars end for RTS-12.10_FT interval=15
11:45:00.75[13]INFO :RIZ0:RTS_FUT: Orders has been updated for . 1 active orders, 3 total
11:45:00.75[13]INFO :      '90676579'.   15.11.2010 11:30:01.
    .
11:45:00.99[13]INFO :RIZ0:RTS_FUT: Add command RtOpenbyEq entry=true
11:45:00.99[13]INFO :RIZ0:RTS_FUT: Orders has been updated for . 1 active orders, 3 total
11:45:00.99[13]INFO :RIZ0:RTS_FUT: Orders has been updated for . 1 active orders, 3 total
11:45:00.99[13]INFO :: ''     242. (2145 ,  15.11.2010 11:30:00)
11:45:14.95[1]INFO :: '' 
11:45:14.95[4]DEBUG:Detach update to 'RIZ0:RTS_FUT'
11:45:15.13[4]DEBUG:Initialization is started.
11:45:15.13[4]DEBUG:GetMinuteBars for RTS-12.10_FT interval=15
11:45:15.13[4]DEBUG:Make new task for RIZ0:RTS_FUT interval 15M
11:45:15.13[4]DEBUG:GetMinuteBars end for RTS-12.10_FT interval=15
11:45:15.13[4]DEBUG:Shift time from 15.11.2010 12:00:00 to 15.11.2010 11:45:17 for RIZ0:RTS_FUT.
11:45:15.13[4]DEBUG:Adding 0 trades to RIZ0:RTS_FUT. startTime=15.11.2010 11:45:16
11:45:15.53[4]INFO :RIZ0:RTS_FUT: Orders has been updated for . 1 active orders, 3 total
11:45:15.53[4]INFO :      '90676579'.   15.11.2010 11:30:01.
    .
11:45:15.58[4]INFO :RIZ0:RTS_FUT: Add command cancel for: Long
11:45:15.58[4]INFO :RIZ0:RTS_FUT: Orders has been updated for . 1 active orders, 3 total
11:45:15.58[4]INFO :RIZ0:RTS_FUT: Orders has been updated for . 1 active orders, 3 total
11:45:15.58[4]DEBUG:Initialization is stopped.
11:45:15.58[4]INFO :: ''     56. (2145 ,  15.11.2010 11:30:00)
11:45:36.84[7]DEBUG:Cancel PO 90676438 oi=438523744:90676438#7#20#.Long:A=True:B
11:45:36.84[7]DEBUG:SmartCOM. CancelOrder 438523744
11:45:36.84[7]DEBUG:Cmd: 353003487, "   ", error=False, last=True, orderId=438523744
11:45:37.06[1]DEBUG:SmartCOM. UpdateOrder 438523744 0 StOrder_State_Cancel StOrder_Type_StopLimit
11:45:37.08[MQ<OrderInfo>]DEBUG:New OrderInfo 438523744 comment=90676438#7#20#.Long parsed=True orderid=90676438
11:45:37.08[MQ<OrderInfo>]DEBUG:PO 90676438 IT :IT Invest:RIZ0 upd: price=160890 status=Wait->Cancelled,active=True->False,executed=False->False, q=1, rest=1
11:45:37.08[7]DEBUG: 90676438   1  RIZ0   160890 .    240 .
11:45:37.08[7]INFO :INFO: 90676438   1  RIZ0   160890 .    240 .
11:45:37.08[MQ<OrderInfo>]DEBUG:Cmd: 353003487, "", error=False, last=True, orderId=438523744
11:45:37.08[7]DEBUG:Finishing operation with success = True
11:45:37.08[7]DEBUG:POM:Cancel:True
11:59:58.75[MQ<SmcTradeData>]DEBUG:Processing 17 skipped trades