dimOn писал(а):
Оооочень интересно что было ДО начала лога, который с 80й ошибкой, выложенного
Вот там действительно интересная ситуация, с thread#57 и thread#56, интересно откуда они взялись вообще. И как так получилось, что два потока начали так пересекаться. Интересует начало потока 56. Ну, или если лог потеряли, то понаблюдайте.
Помимо 56-ого потока захватил еще и 55-й, там тоже интересно. Он пересекается с 56-м.
Код:
DEBUG 24.12.2009 14:27:34,411 : server: created thread #55
DEBUG 24.12.2009 14:27:34,412 : server: wait accepting #56...
DEBUG 24.12.2009 14:27:34,412 : thread#55: try command...
TRACE 24.12.2009 14:27:34,413 : thread#55: <- COMMAND
TRACE 24.12.2009 14:27:34,602 : thread#55: <- 69960179913961201236050797779787
TRACE 24.12.2009 14:27:34,602 : thread#55: <- name
TRACE 24.12.2009 14:27:34,602 : thread#55: <- 30
TRACE 24.12.2009 14:27:34,602 : thread#55: -> ECHO
TRACE 24.12.2009 14:27:34,602 : thread#55: -> 69960179913961201236050797779787
TRACE 24.12.2009 14:27:34,817 : shtrih-driver: _reset: send ENQ (0)
TRACE 24.12.2009 14:27:34,829 : shtrih-driver: _reset: getted NAK (0)
TRACE 24.12.2009 14:27:35,029 : shtrih-driver: _reset: send ENQ (0)
TRACE 24.12.2009 14:27:35,043 : shtrih-driver: _reset: getted NAK (0)
TRACE 24.12.2009 14:27:35,043 : shtrih-driver: SEND: 02 01 FC FD
TRACE 24.12.2009 14:27:35,103 : shtrih-driver: RECV: 02 14 FC 00 00 00 01 05 FA 00 D8 D2 D0 C8 D5 2D CC 2D D4 D0 2D CA FE
TRACE 24.12.2009 14:27:35,138 : thread#55: -> OK
TRACE 24.12.2009 14:27:35,139 : thread#55: -> ШТРИХ-М-ФР-К (тип:ККМ/ФР, модель:-6, протокол:1.5, язык:0(RU))
TRACE 24.12.2009 14:27:35,139 : thread#55: -> ENDECHO
DEBUG 24.12.2009 14:27:35,139 : thread#55: command 'name' completed sucessfull
DEBUG 24.12.2009 14:27:35,139 : thread#55: try command...
TRACE 24.12.2009 14:27:35,140 : thread#55: <- COMMAND
TRACE 24.12.2009 14:27:35,339 : thread#55: <- 60941857955402505292487806932511
TRACE 24.12.2009 14:27:35,339 : thread#55: <- status
TRACE 24.12.2009 14:27:35,339 : thread#55: <- 30
TRACE 24.12.2009 14:27:35,339 : thread#55: -> ECHO
TRACE 24.12.2009 14:27:35,339 : thread#55: -> 60941857955402505292487806932511
DEBUG 24.12.2009 14:27:35,496 : server: connection accepted #56: Socket[addr=/10.0.2.251,port=53131,localport=9876]
DEBUG 24.12.2009 14:27:35,496 : server: created thread #56
DEBUG 24.12.2009 14:27:35,497 : server: wait accepting #57...
DEBUG 24.12.2009 14:27:35,502 : thread#56: try command...
TRACE 24.12.2009 14:27:35,507 : thread#56: <- COMMAND
TRACE 24.12.2009 14:27:35,623 : shtrih-driver: _reset: send ENQ (0)
TRACE 24.12.2009 14:27:35,636 : shtrih-driver: _reset: getted NAK (0)
TRACE 24.12.2009 14:27:35,708 : thread#56: <- 15518440967664104782161244840588
TRACE 24.12.2009 14:27:35,708 : thread#56: <- check
TRACE 24.12.2009 14:27:35,708 : thread#56: <- 1
TRACE 24.12.2009 14:27:35,709 : thread#56: <- 1000
TRACE 24.12.2009 14:27:35,712 : thread#56: <- 700.0
TRACE 24.12.2009 14:27:35,713 : thread#56: <- 304896
TRACE 24.12.2009 14:27:35,714 : thread#56: <- 0
TRACE 24.12.2009 14:27:35,714 : thread#56: <- STRING
TRACE 24.12.2009 14:27:35,714 : thread#56: <- Оператор №68
TRACE 24.12.2009 14:27:35,714 : thread#56: <-
TRACE 24.12.2009 14:27:35,714 : thread#56: <- STRING
TRACE 24.12.2009 14:27:35,714 : thread#56: <- Тел. абон. отд. 581-581
TRACE 24.12.2009 14:27:35,714 : thread#56: <-
TRACE 24.12.2009 14:27:35,714 : thread#56: -> ECHO
TRACE 24.12.2009 14:27:35,715 : thread#56: -> 15518440967664104782161244840588
TRACE 24.12.2009 14:27:35,838 : shtrih-driver: _reset: send ENQ (0)
TRACE 24.12.2009 14:27:35,849 : shtrih-driver: _reset: getted NAK (0)
TRACE 24.12.2009 14:27:35,849 : shtrih-driver: SEND: 02 05 10 1E 00 00 00 0B
TRACE 24.12.2009 14:27:35,917 : shtrih-driver: RECV: 02 10 10 00 1E B2 02 02 00 00 9F DF 00 00 00 97 08 00 73
TRACE 24.12.2009 14:27:35,953 : thread#55: -> OK
TRACE 24.12.2009 14:27:35,953 : thread#55: -> Порядковый номер оператора: 30
Рулон операционного журнала: нет
Рулон чековой ленты: есть
ЭКЛЗ: есть
Оптический датчик операционного журнала: бумаги нет
Оптический датчик чековой ленты: бумага есть
Рычаг термоголовки контрольной ленты: поднят
Рычаг термоголовки чековой ленты: опущен
Крышка корпуса ФР: опущена
Денежный ящик: закрыт
ЭКЛЗ почти заполнена: нет
Режим ФР: #2 : Открытая смена, 24 часа не кончились.
Подрежим ФР: #0 : Бумага есть (ФР не в фазе печати операции)
Количество операций в чеке: 0
Напряжение резервной батареи: 3.1176472
Напряжение источника питания: 24.777779
Код ошибки ФП: 0
Код ошибки ЭКЛЗ: 0
TRACE 24.12.2009 14:27:35,953 : thread#55: -> ENDECHO
DEBUG 24.12.2009 14:27:35,954 : thread#55: command 'status' completed sucessfull
DEBUG 24.12.2009 14:27:35,954 : thread#55: try command...
TRACE 24.12.2009 14:27:35,956 : thread#55: <- !STATUSSERVER%)
DEBUG 24.12.2009 14:27:35,957 : thread#55: recv statusserver command...
DEBUG 24.12.2009 14:27:35,959 : thread#55: end all commands (good work)
DEBUG 24.12.2009 14:27:35,959 : thread#55: close socket
TRACE 24.12.2009 14:27:36,167 : shtrih-driver: _reset: send ENQ (0)
TRACE 24.12.2009 14:27:36,186 : shtrih-driver: _reset: getted NAK (0)
TRACE 24.12.2009 14:27:36,187 : shtrih-driver: SEND: 02 3C 80 01 00 00 00 E8 03 00 00 00 70 11 01 00 00 00 00 00 00 00 33 30 34 38 39 36 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 36
TRACE 24.12.2009 14:27:36,758 : shtrih-driver: RECV: 02 03 80 00 01 82
TRACE 24.12.2009 14:27:36,758 : shtrih-driver: SEND: 02 2E 17 01 00 00 00 03 CE EF E5 F0 E0 F2 EE F0 20 B9 36 38 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 94
TRACE 24.12.2009 14:27:36,905 : shtrih-driver: RECV: 02 03 17 00 01 15
TRACE 24.12.2009 14:27:36,906 : shtrih-driver: SEND: 02 2E 17 01 00 00 00 03 D2 E5 EB 2E 20 E0 E1 EE ED 2E 20 EE F2 E4 2E 20 35 38 31 2D 35 38 31 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 3E
TRACE 24.12.2009 14:27:37,035 : shtrih-driver: RECV: 02 03 17 00 01 15
TRACE 24.12.2009 14:27:37,035 : shtrih-driver: SEND: 02 47 85 01 00 00 00 A0 86 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 E4
DEBUG 24.12.2009 14:27:37,717 : server: connection accepted #57: Socket[addr=/10.0.2.251,port=53132,localport=9876]
DEBUG 24.12.2009 14:27:37,718 : server: created thread #57
DEBUG 24.12.2009 14:27:37,718 : server: wait accepting #58...
DEBUG 24.12.2009 14:27:37,718 : thread#57: try command...
TRACE 24.12.2009 14:27:37,727 : thread#57: <- COMMAND
TRACE 24.12.2009 14:27:37,822 : shtrih-driver: RECV: 02 08 85 00 01 30 75 00 00 00 C9
TRACE 24.12.2009 14:27:37,823 : shtrih-driver: SEND: 02 05 13 01 00 00 00 17
TRACE 24.12.2009 14:27:37,910 : shtrih-driver: RECV: 02 03 13 00 01 11
TRACE 24.12.2009 14:27:37,921 : thread#57: <- 58530026855700889061742240152563
TRACE 24.12.2009 14:27:37,922 : thread#57: <- check
TRACE 24.12.2009 14:27:37,922 : thread#57: <- 1
TRACE 24.12.2009 14:27:37,922 : thread#57: <- 250.0
TRACE 24.12.2009 14:27:37,922 : thread#57: <- 250.0
TRACE 24.12.2009 14:27:37,922 : thread#57: <- 13993
TRACE 24.12.2009 14:27:37,922 : thread#57: <- 0
TRACE 24.12.2009 14:27:37,922 : thread#57: <- STRING
TRACE 24.12.2009 14:27:37,922 : thread#57: <- Оператор №69
TRACE 24.12.2009 14:27:37,922 : thread#57: <-
TRACE 24.12.2009 14:27:37,922 : thread#57: <- STRING
TRACE 24.12.2009 14:27:37,922 : thread#57: <- Тел. абон. отд. 581-581
TRACE 24.12.2009 14:27:37,922 : thread#57: <-
TRACE 24.12.2009 14:27:37,922 : thread#57: -> ECHO
TRACE 24.12.2009 14:27:37,922 : thread#57: -> 58530026855700889061742240152563
TRACE 24.12.2009 14:27:37,937 : thread#56: -> OK
TRACE 24.12.2009 14:27:37,937 : thread#56: -> 300.0
TRACE 24.12.2009 14:27:37,937 : thread#56: -> ENDECHO
DEBUG 24.12.2009 14:27:37,937 : thread#56: command 'check' completed sucessfull
DEBUG 24.12.2009 14:27:37,937 : thread#56: try command...
DEBUG 24.12.2009 14:27:37,939 : thread#56: end all commands (good work)
DEBUG 24.12.2009 14:27:37,939 : thread#56: close socket
TRACE 24.12.2009 14:27:38,161 : shtrih-driver: _reset: send ENQ (0)
TRACE 24.12.2009 14:27:38,178 : shtrih-driver: _reset: getted NAK (0)
TRACE 24.12.2009 14:27:38,178 : shtrih-driver: SEND: 02 3C 80 01 00 00 00 E8 03 00 00 00 A8 61 00 00 00 00 00 00 00 00 31 33 39 39 33 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 AE
TRACE 24.12.2009 14:27:38,389 : shtrih-driver: RECV: 02 02 80 50 D2
TRACE 24.12.2009 14:27:38,415 : thread#57: -> ERROR
WARN 24.12.2009 14:27:38,415 : thread#57: send error driver: ru.bitel.frk.driver.shtrih.PrinterShtrihErrorException: #80: Идет печать предыдущей команды (ФР)
TRACE 24.12.2009 14:27:38,415 : thread#57: -> ru.bitel.frk.driver.shtrih.PrinterShtrihErrorException: #80: Идет печать предыдущей команды (ФР)
TRACE 24.12.2009 14:27:38,415 : thread#57: -> ENDECHO
DEBUG 24.12.2009 14:27:38,415 : thread#57: command 'check' completed sucessfull
DEBUG 24.12.2009 14:27:38,415 : thread#57: try command...
DEBUG 24.12.2009 14:27:38,417 : thread#57: end all commands (good work)
DEBUG 24.12.2009 14:27:38,417 : thread#57: close socket
DEBUG 24.12.2009 14:27:41,933 : server: connection accepted #58: Socket[addr=/10.0.2.251,port=53133,localport=9876]
DEBUG 24.12.2009 14:27:41,933 : server: created thread #58
DEBUG 24.12.2009 14:27:41,934 : server: wait accepting #59...
DEBUG 24.12.2009 14:27:41,934 : thread#58: try command...
TRACE 24.12.2009 14:27:41,934 : thread#58: <- COMMAND
TRACE 24.12.2009 14:27:42,121 : thread#58: <- 86773127435163271213160995699229
TRACE 24.12.2009 14:27:42,121 : thread#58: <- returnscheck
TRACE 24.12.2009 14:27:42,122 : thread#58: <- 30
TRACE 24.12.2009 14:27:42,122 : thread#58: <- 0
TRACE 24.12.2009 14:27:42,122 : thread#58: <- 1000
TRACE 24.12.2009 14:27:42,122 : thread#58: <- [возврат]
TRACE 24.12.2009 14:27:42,122 : thread#58: -> ECHO
TRACE 24.12.2009 14:27:42,122 : thread#58: -> 86773127435163271213160995699229
TRACE 24.12.2009 14:27:42,386 : shtrih-driver: _reset: send ENQ (0)
TRACE 24.12.2009 14:27:42,399 : shtrih-driver: _reset: getted NAK (0)
TRACE 24.12.2009 14:27:42,400 : shtrih-driver: SEND: 02 3C 82 1E 00 00 00 E8 03 00 00 00 A0 86 01 00 00 00 00 00 00 00 5B E2 EE E7 E2 F0 E0 F2 5D 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 81
TRACE 24.12.2009 14:27:42,986 : shtrih-driver: RECV: 02 03 82 00 1E 9F
TRACE 24.12.2009 14:27:42,986 : shtrih-driver: SEND: 02 47 85 1E 00 00 00 A0 86 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 FB
TRACE 24.12.2009 14:27:43,776 : shtrih-driver: RECV: 02 08 85 00 1E 00 00 00 00 00 93
TRACE 24.12.2009 14:27:43,776 : shtrih-driver: SEND: 02 05 13 1E 00 00 00 08
TRACE 24.12.2009 14:27:43,868 : shtrih-driver: RECV: 02 03 13 00 1E 0E
TRACE 24.12.2009 14:27:43,905 : thread#58: -> OK
TRACE 24.12.2009 14:27:43,905 : thread#58: -> 0.0
TRACE 24.12.2009 14:27:43,905 : thread#58: -> ENDECHO
DEBUG 24.12.2009 14:27:43,905 : thread#58: command 'returnscheck' completed sucessfull
DEBUG 24.12.2009 14:27:43,914 : thread#58: try command...
DEBUG 24.12.2009 14:27:43,914 : thread#58: end all commands (good work)
DEBUG 24.12.2009 14:27:43,914 : thread#58: close socket
DEBUG 24.12.2009 14:27:54,452 : server: connection accepted #59: Socket[addr=/10.0.2.251,port=53134,localport=9876]
DEBUG 24.12.2009 14:27:54,452 : server: created thread #59
DEBUG 24.12.2009 14:27:54,453 : server: wait accepting #60...
DEBUG 24.12.2009 14:27:54,454 : thread#59: try command...
TRACE 24.12.2009 14:27:54,454 : thread#59: <- COMMAND
TRACE 24.12.2009 14:27:54,645 : thread#59: <- 80829117149109891258835403283084
TRACE 24.12.2009 14:27:54,645 : thread#59: <- name
TRACE 24.12.2009 14:27:54,645 : thread#59: <- 30
TRACE 24.12.2009 14:27:54,645 : thread#59: -> ECHO
TRACE 24.12.2009 14:27:54,645 : thread#59: -> 80829117149109891258835403283084
TRACE 24.12.2009 14:27:54,928 : shtrih-driver: _reset: send ENQ (0)
TRACE 24.12.2009 14:27:54,940 : shtrih-driver: _reset: getted NAK (0)
TRACE 24.12.2009 14:27:55,140 : shtrih-driver: _reset: send ENQ (0)
TRACE 24.12.2009 14:27:55,157 : shtrih-driver: _reset: getted NAK (0)
TRACE 24.12.2009 14:27:55,157 : shtrih-driver: SEND: 02 01 FC FD
TRACE 24.12.2009 14:27:55,221 : shtrih-driver: RECV: 02 14 FC 00 00 00 01 05 FA 00 D8 D2 D0 C8 D5 2D CC 2D D4 D0 2D CA FE
TRACE 24.12.2009 14:27:55,266 : thread#59: -> OK
TRACE 24.12.2009 14:27:55,266 : thread#59: -> ШТРИХ-М-ФР-К (тип:ККМ/ФР, модель:-6, протокол:1.5, язык:0(RU))
TRACE 24.12.2009 14:27:55,266 : thread#59: -> ENDECHO
DEBUG 24.12.2009 14:27:55,267 : thread#59: command 'name' completed sucessfull
DEBUG 24.12.2009 14:27:55,267 : thread#59: try command...
TRACE 24.12.2009 14:27:55,268 : thread#59: <- COMMAND
TRACE 24.12.2009 14:27:55,478 : thread#59: <- 08177512781626789143641802003688
TRACE 24.12.2009 14:27:55,478 : thread#59: <- status
TRACE 24.12.2009 14:27:55,478 : thread#59: <- 30
TRACE 24.12.2009 14:27:55,478 : thread#59: -> ECHO
TRACE 24.12.2009 14:27:55,478 : thread#59: -> 08177512781626789143641802003688
TRACE 24.12.2009 14:27:55,689 : shtrih-driver: _reset: send ENQ (0)
TRACE 24.12.2009 14:27:55,702 : shtrih-driver: _reset: getted NAK (0)
TRACE 24.12.2009 14:27:55,905 : shtrih-driver: _reset: send ENQ (0)
TRACE 24.12.2009 14:27:55,918 : shtrih-driver: _reset: getted NAK (0)
TRACE 24.12.2009 14:27:55,918 : shtrih-driver: SEND: 02 05 10 1E 00 00 00 0B
TRACE 24.12.2009 14:27:55,986 : shtrih-driver: RECV: 02 10 10 00 1E B2 02 02 00 00 9F DF 00 00 00 97 08 00 73
TRACE 24.12.2009 14:27:56,014 : thread#59: -> OK
TRACE 24.12.2009 14:27:56,015 : thread#59: -> Порядковый номер оператора: 30
Рулон операционного журнала: нет
Рулон чековой ленты: есть
ЭКЛЗ: есть
Оптический датчик операционного журнала: бумаги нет
Оптический датчик чековой ленты: бумага есть
Рычаг термоголовки контрольной ленты: поднят
Рычаг термоголовки чековой ленты: опущен
Крышка корпуса ФР: опущена
Денежный ящик: закрыт
ЭКЛЗ почти заполнена: нет
Режим ФР: #2 : Открытая смена, 24 часа не кончились.
Подрежим ФР: #0 : Бумага есть (ФР не в фазе печати операции)
Количество операций в чеке: 0
Напряжение резервной батареи: 3.1176472
Напряжение источника питания: 24.777779
Код ошибки ФП: 0
Код ошибки ЭКЛЗ: 0
TRACE 24.12.2009 14:27:56,015 : thread#59: -> ENDECHO
DEBUG 24.12.2009 14:27:56,015 : thread#59: command 'status' completed sucessfull
DEBUG 24.12.2009 14:27:56,015 : thread#59: try command...
TRACE 24.12.2009 14:27:56,016 : thread#59: <- !STATUSSERVER%)
DEBUG 24.12.2009 14:27:56,016 : thread#59: recv statusserver command...
DEBUG 24.12.2009 14:27:56,032 : thread#59: end all commands (good work)
DEBUG 24.12.2009 14:27:56,032 : thread#59: close socket
dimOn писал(а):
А у вас, скажите ка, ТОЧНО один сервер печати стоит? Как у вас архитектура построена принтеров, раз гвоорите, что несколько. Они каждый на своём компе подключены или как? Один сервер печати на один регистратор? Укажите подробнее.
Я вот сейчас подумал, что если запустить два сервера печати, то только тогда они могут биться за один принтер таким вот образом, больше вариатнов у меня нету, каждый сервер поднимает единственный экземпляр драйвера и допускает до выполнения каждой команды из этого драйвера только один поток в один момент времени. То есть если принтер единолично захвачен сервером/драйвером, то команды таким вот образом конфликтовать не могут.... если конечно не тот вариант, что я описал - может быть ситуация, что после распечатки чека регитратор некоторое время не готов. Это обязательно уточните.
У нас имеется только один регистратор и следовательно только один сервер печати. С ним работают одновременно 4 оператора.
Регистратор во время писка отказывается принимать команды.
Сервер печати и регистратор был подключен к одному из компов оператора, на компе стоит windows vista.
Решил попробовать все поставить на другой комп и на другую OS, что бы исключить проблемы с com портом и с глюками vistы.
Вчера взял отдельный комп под сервер печати и регистратор, поставил на него Slackware.
Посмотрим как все это теперь будет работать.