16.03 13:36:35 TRACE [CardPluginEmulator] entering getCardInfo(CardSearchRequest). The argument is: ru.crystals.pos.cards.service.PluginCardSearchRequest@169412e
16.03 13:36:35 TRACE [CardPluginEmulator] entering readProperties(IntegrationProperties, Logger)
16.03 13:36:35 TRACE [CardPluginEmulator] leaving readProperties(IntegrationProperties, Logger). The result is: CardPluginEmulatorProperties{getInfoUrl=http://127.0.0.1:10071/card-plugin-processing/emulator/get-card, writeOffUrl=http://127.0.0.1:10071/card-plugin-processing/emulator/write-off, rollbackUrl=http://127.0.0.1:10071/card-plugin-processing/emulator/rollback, connectionTimeout=10000, readTimeout=10000, cardNoPrefixes=[152 , 151], cardNoLength=7}; it took 0 [ms]
16.03 13:36:35 TRACE [CardPluginProcssingServiceEmulatorSingleton] entering getCardInfo(String). The argument is: "1510011"
16.03 13:36:36 DEBUG [CardsFilesReader2] entering run(); server is online: true
16.03 13:36:36 TRACE [CardsFilesReader2] the lock was acquired
16.03 13:36:36 DEBUG [CardsFilesReader2] entering importCardCatalogs()
16.03 13:36:36 TRACE [CardsFilesReader2] entering sendRetainedReports()
16.03 13:36:36 TRACE [CardsFilesReader2] no missed reports were detected
16.03 13:36:36 TRACE [CardsFilesReader2] leaving sendRetainedReports(). The result is: 0; it took 1 [ms]
16.03 13:36:36 TRACE [CardsFilesReader2] the last log entry is: log [last-card-id [id: 1; file-id: 102392; processed-at: 2018-03-14 13:28:27.561]]
16.03 13:36:36 TRACE [CardsFilesReader2] entering getNewFiles(Long). The arguments are: id [102392]
16.03 13:36:36 TRACE [CardsFilesReader2] testConnect() was done in 0 [ms]
16.03 13:36:36 TRACE [CardsFilesReader2] leaving getNewFiles(Long). The result size is: 0; it took 4 [ms]
16.03 13:36:36 TRACE [CardsFilesReader2] no new card catalogs to import this time - so, nothig else to do
16.03 13:36:36 DEBUG [CardsFilesReader2] leaving importCardCatalogs(); it took 10 [ms]
16.03 13:36:36 DEBUG [CardsFilesReader2] leaving run(); it took 13 [ms]
16.03 13:36:40 ERROR [ConnectionEmulatorUtils] toString(XmlEmulatorException): the argument is null
16.03 13:36:40 TRACE [CardPluginProcessingEmulatorCardsManagerImpl] entering getCardByNumber(String). The argument is: "1510011"
16.03 13:36:40 ERROR [CardEmulatorUtils] toString(XmlEmulatorCard): the argument is null
16.03 13:36:40 TRACE [CardPluginProcessingEmulatorCardsManagerImpl] leaving getCardByNumber(String). The result is: null
16.03 13:36:40 TRACE [CardPluginProcssingServiceEmulatorSingleton] leaving getCardInfo(String). The result is: null
16.03 13:36:40 ERROR [CardPluginEmulator] getCardInfo: status code is: 204
16.03 13:36:40 INFO  [CardPluginEmulator] leaving getCardInfo(CardSearchRequest). Card [number: "1510011"] not found
16.03 13:36:40 DEBUG [CardPluginMatcher] Card "1510011" has status "CARD_NOT_FOUND" in card plugin "card.plugin.emulator"
16.03 13:36:40 TRACE [CardsServiceImpl] entering getCardTypeServer(String). The argument is: number [1510011]
16.03 13:36:40 WARN  [CardsServiceImpl] getCardTypeServer(String): card type (through card-range) for card [number: 1510011] was not detected in the local db, so, the card considered to be NOT FOUND
16.03 13:36:40 TRACE [CardsServiceImpl] leaving getCardTypeServer(String). The result is: null; it took 16 [ms]
16.03 13:36:40 ERROR [CardsServiceImpl] ServerCardsApplication failed: no local card found: 1510011
16.03 13:36:40 TRACE [CardsServiceImpl] leaving getCardTypeEntity(String). Find with ServerCardsApplication. The result is : null
16.03 13:36:40 TRACE [CardsServiceImpl] no one suitable card range was found for card "1510011". BUT maybe it is a coupon listed in a conditions collection of an action...
16.03 13:36:40 TRACE [ActionIntrospectorImpl] entering getActionTriggeringCoupons(Date). The argument is: date [(NULL)]
16.03 13:36:40 TRACE [ActionIntrospectorImpl] getActionTriggeringCoupons: the date argument was string: "2018-03-16 13:36:40.810"
16.03 13:36:40 TRACE [ActionIntrospectorImpl] getActionTriggeringCoupons: query to execute: "SELECT DISTINCT a."value" FROM discounts_action_plugin_property AS a INNER JOIN discounts_action_plugin AS b ON a.plugin_id = b.id INNER JOIN discounts_advertisingactions AS c ON b.action_id = c.id WHERE a."name" = 'couponNumber'      AND b.class_name = 'ru.crystalservice.setv6.discounts.plugins.CouponsCondition'      AND (c.periodstart IS NULL OR c.periodstart <= '2018-03-16 13:36:40.810')      AND (c.periodfinish IS NULL OR c.periodfinish >= '2018-03-16 13:36:40.810');"
16.03 13:36:40 TRACE [ActionIntrospectorImpl] leaving getActionTriggeringCoupons(Date). the result is: [46126832, 25072017, 46126842, 46126853, 19911234, 111020172, 111020171]; it took 7 [ms]
16.03 13:36:40 TRACE [CardsServiceImpl] getCardType(String): the card/coupon #1510011 was not detected even in the collection of action-triggering coupons!
16.03 13:36:40 TRACE [CardsServiceImpl] leaving getCardType(String). The result is: null; it took 5048 [ms]
16.03 13:36:40 INFO  [TechProcessImpl] TP error
16.03 13:36:40 DEBUG [CheckContainer] /CheckContainer/ Trying to set state: SEARCH_PRODUCT
16.03 13:36:40 TRACE [Runner] Runner begin work ru.crystals.pos.visualization.Factory$31
16.03 13:36:40 TRACE [Runner] Runner end work ru.crystals.pos.visualization.Factory$31
16.03 13:36:40 ERROR [TechProcessImpl] TP error: Товар не найден
16.03 13:36:40 INFO  [TechProcessImpl] Message  "Barcode not found" was send, code = 1510011, cashNum = 1
16.03 13:36:40 TRACE [Runner] Runner begin work ru.crystals.pos.visualization.Factory$31
16.03 13:36:40 TRACE [Runner] Runner end work ru.crystals.pos.visualization.Factory$31
16.03 13:36:43 INFO  [TransferManager] Nothing yet not processed on server to resend
16.03 13:36:45 TRACE [HibernateBackedActionsTransportAuxiliariesDao] entering getLastDiscountId()
16.03 13:36:45 TRACE [HibernateBackedActionsTransportAuxiliariesDao] leaving getLastDiscountId(). The result is: last-discount-id [disc-id: 105621; sent-to-server: true; saved: true]; It took 4 [ms]
16.03 13:36:45 TRACE [ActionsFilesReader] no new files; last id = 105621