13:54:40.40 100 Агент: 'q1' SignalName Order1 Реальная цена открытия: 133600
13:54:40.40 100 Агент: 'q1' На текущем тике время:13:54:38:706 Bid:133590 Ask:133600 StepPrice:6,32764
13:54:40.39 131 Агент: 'q1' Нет свечи для привязки заявки '167687021'. Дата заявки 24.04.2013 13:54:38.
Автоматическое исполнение заявок временно отключено.
13:54:40.05 204 167687021:Рыночная заявка 167687021 исполнена. Куплено 1 лот RIM3. Остаток 0 лота(ов).
13:54:40.05 200 167687021:Заявка 167687021 на покупку 1 лотов RIM3 по рыночной цене принята. Команда выполнена за 95 мс.
13:54:40.05 250 Сделка 729097515: Куплено 1 лот RIM3 по цене 133600.
13:54:39.94 202 167686437:Заявка 167686437 на покупку 1 лотов RIM3 по цене 133570 снята. Команда выполнена за 397 мс.
13:54:39.51 127 Агент: 'q1' Скрипт выполнен успешно за 160мс. (632276 баров, время 24.04.2013 13:54:35)
13:54:39.49 100 Агент: 'q1' SignalName Order1 Реальная цена открытия: 133570
13:54:39.49 100 Агент: 'q1' На текущем тике время:13:54:38:54 Bid:133590 Ask:133600 StepPrice:6,32764
13:54:39.47 200 167686437:Заявка 167686437 на покупку 1 лотов RIM3 по цене 133570 принята. Команда выполнена за 89 мс.
13:54:39.35 127 Агент: 'q1' Скрипт выполнен успешно за 257мс. (632276 баров, время 24.04.2013 13:54:35)
13:54:39.20 100 Агент: 'q1' Сейчас скрипт будет открывать ордер Order1! Основная информация: 13:54:35:854 Bid:133560 Ask:133570 StepPrice:6,32764
13:54:39.20 100 Агент: 'q1' На текущем тике время:13:54:35:854 Bid:133560 Ask:133570 StepPrice:6,32764

Появились вопросы...
1) Если сравнить последнюю строчку с четвертой снизу, то видим, что время на моём компьютере изменилось на 29мс а на сервере на 3 секунды! Как такое возможно?
Время вывожу в программе вот так:
ctx.Log("На текущем тике время:"+rt.FinInfo.LastUpdate.Hour.ToString()+":"+rt.FinInfo.LastUpdate.Minute.ToString()+":"+rt.FinInfo.LastUpdate.Second.ToString()+":"+rt.FinInfo.LastUpdate.Millisecond.ToString()+" Bid:"+rt.FinInfo.Bid.Value.ToString()+" Ask:"+rt.FinInfo.Ask.Value.ToString()+" StepPrice:"+rt.FinInfo.StepPrice.Value.ToString(), 255);


Алор, Плаза2

FinInfo.LastUpdate - это не серверное время, а время последнего изменения котировок. И это время фиксируется (в котировке) на момент старта потока пересчета. Нужно смотреть полный лог, чтобы понять ситуацию. Судя по обрывку лога, поток 13:54:35 задержался на 3 секунды

Спасибо, понял! Интересный нюанс.