платеж через терминал проходит, поступает к abhinterinvest, далее от них отправляется к нам в биллинг, а биллинг в ответ выдает ошибку: "другая ошибка провайдера". Может кто сталкивался с данной проблемой, как ее решить? Из изменений в биллинге вчера было только добавление лицензионного ключа и рестарт ядра. Лог из файла netup-payment-system прикрепляю.
Код: Выделить всё
15/07/2017 13:41:15:817073 [29391] INFO SecureSocketImpl {} accept from 172.21.200.7 succeded, connection has system file descriptor 12 and sid [35106]
15/07/2017 13:41:15:921870 [29391] DEBUG SecureSocketImpl {} accepted new SSL connection using AES256-SHA cipher (no certificate)
15/07/2017 13:41:15:921886 [29391] DEBUG HTTPSocketConnectionImpl {} New connection accepted [35106]
15/07/2017 13:41:15:921916 [29391] INFO HTTPParserWithURLParams {} HTTPParserWithURLParamsImpl->init()
15/07/2017 13:41:15:921940 [29391] INFO ConnectionInfoStorage {} system got connection with system socket #12 and service socket id [35106]
15/07/2017 13:41:15:921950 [29391] DEBUG HTTPSocketConnectionImpl {} initialized: ssl
15/07/2017 13:41:15:921964 [29391] INFO ConnectionManagerImpl {} connection of type 'http' with sid 35106 was created.
15/07/2017 13:41:15:985934 [7065] DEBUG HTTPSocketConnectionImpl {} 278 bytes received from socket: [35106]
15/07/2017 13:41:15:985958 [7065] INFO HTTPParser {}
GET /abhinterinvest?command=check&txn_id=1126447061&account=7754300&txn_date=20170715134115&sum=1000.00 HTTP/1.1
Authorization: Basic Nzc3Nzc3Nzo=
Host: 195.191.5.7:9000
Connection: Keep-Alive
User-Agent: Apache-HttpClient/4.3.4 (java 1.5)
Accept-Encoding: gzip,deflate
15/07/2017 13:41:15:985971 [7065] INFO HTTPParser {}
encoded request: GET /abhinterinvest?command=check&txn_id=1126447061&account=7754300&txn_date=20170715134115&sum=1000.00 HTTP/1.1
Authorization: Basic Nzc3Nzc3Nzo=
Host: 195.191.5.7:9000
Connection: Keep-Alive
User-Agent: Apache-HttpClient/4.3.4 (java 1.5)
Accept-Encoding: gzip,deflate
15/07/2017 13:41:15:985984 [7065] INFO HTTPParser {} method: HTTP_GET
15/07/2017 13:41:15:985989 [7065] INFO HTTPParser {} URI:/abhinterinvest?command=check&txn_id=1126447061&account=7754300&txn_date=20170715134115&sum=1000.00
15/07/2017 13:41:15:986019 [7065] INFO HTTPParser {} message body empty
15/07/2017 13:41:15:986113 [7065] INFO HTTPSocketConnectionImpl {} [35106] received transaction [0x7fb09435f3e0]
15/07/2017 13:41:15:986129 [7065] WARNING HTTPSocketConnectionImpl {} [35106] Got transaction without route.
15/07/2017 13:41:15:986312 [29371] INFO TransactionHandler {} Execution of the transaction [0x7fb09435f3e0] of priority 0 has been started. Transaction consists of the following event:
#0 event [netup:http][http_get_request][1.1] ([131436]131437[131444])
15/07/2017 13:41:15:986347 [29371] DEBUG HttpRequest {} starting event [netup:http][http_get_request][1.1] ([131436]131437[131444]) {
uri='string, value: "/abhinterinvest?command=check&txn_id=1126447061&account=7754300&txn_date=20170715134115&sum=1000.00"'
authorization='string, value: " Basic Nzc3Nzc3Nzo="'
host='string, value: " 195.191.5.7:9000"'
connection='string, value: " Keep-Alive"'
user-agent='string, value: " Apache-HttpClient/4.3.4 (java 1.5)"'
accept-encoding='string, value: " gzip,deflate"'
path='word, value: 4294901760'
command='string, value: "check"'
txn_id='string, value: "1126447061"'
account='string, value: "7754300"'
txn_date='string, value: "20170715134115"'
sum='string, value: "1000.00"'
client_host='string, value: "172.21.200.7"'
connection_ip='int, value: 130553260'
connection_socket_identifier='int, value: 12'
}
15/07/2017 13:41:15:986603 [29371] SQL SQLDriverImplV2 {} [OK] mysql> [BEGIN TRANSACTION] time spent 0.000173755 seconds.
15/07/2017 13:41:15:987965 [29371] SQL SQLDriverImplV2 {} [OK] mysql> SELECT payment_systems.id AS "id", payment_systems.event_name AS "name", payment_systems.event_family AS "family", payment_systems.event_version AS "version", payment_systems.login AS "login", payment_systems.password AS "password", payment_systems.scheme_type AS "scheme_type", payment_systems.comission AS "comission", payment_systems.check_type AS "check_type" FROM payment_systems WHERE payment_systems.url = '/abhinterinvest' AND payment_systems.deleted = 0 (1 row) time spent 0.00153719 seconds.
15/07/2017 13:41:15:987985 [29371] DEBUG HttpRequest {} searching handler for the event [netup:http]http_request[abhinterinvest] ([131436]132346[134669])
15/07/2017 13:41:15:988024 [29371] DEBUG PaymentSystemsCommon {} started handling of the event [netup:http]http_request[abhinterinvest] ([131436]132346[134669])
15/07/2017 13:41:15:988083 [29371] DEBUG PaymentSystemsCommon {} performing precheck
15/07/2017 13:41:15:988109 [29371] DEBUG ExternalPaymentHandler {} starting event [netup:business]payment_precheck[external] ([131239]133894[132020])
15/07/2017 13:41:15:989219 [29371] SQL SQLDriverImplV2 {} [OK] mysql> SELECT payment_systems.event_name AS "event_name", payment_systems.event_version AS "event_version", payment_systems.event_family AS "event_family" FROM payment_systems WHERE payment_systems.type = 6554010 AND payment_systems.deleted = 0 (1 row) time spent 0.00109498 seconds.
15/07/2017 13:41:15:989263 [29371] INFO ConnectionManagerImpl {} processing system transaction [0x7fb10925e950] of type 'connections_state'
15/07/2017 13:41:15:989300 [29371] ERROR ExternalHandler {} Not handling because we aren't connected to external side.
15/07/2017 13:41:15:989306 [29371] DEBUG ExternalPaymentHandler {} exiting
15/07/2017 13:41:15:990262 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_events ( processed_events.family, processed_events.name, processed_events.version, processed_events.processing_state, processed_events.processing_instant, created, created_by) VALUES (131436, 132346, 134669, 262213, 1500115275, 1500115275, 0) (1 row) time spent 0.000873306 seconds.
15/07/2017 13:41:15:991178 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.string_value, created, created_by) VALUES (33601, 655408, 6553618, '/abhinterinvest?command=check&txn_id=1126447061&account=7754300&txn_date=20170715134115&sum=1000.00', 1500115275, 0) (1 row) time spent 0.000860497 seconds.
15/07/2017 13:41:15:991996 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.string_value, created, created_by) VALUES (33601, 655372, 6553618, ' Basic Nzc3Nzc3Nzo=', 1500115275, 0) (1 row) time spent 0.000760276 seconds.
15/07/2017 13:41:15:992759 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.string_value, created, created_by) VALUES (33601, 655375, 6553618, ' 195.191.5.7:9000', 1500115275, 0) (1 row) time spent 0.000719117 seconds.
15/07/2017 13:41:15:993539 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.string_value, created, created_by) VALUES (33601, 655389, 6553618, ' Keep-Alive', 1500115275, 0) (1 row) time spent 0.000737247 seconds.
15/07/2017 13:41:15:994191 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.string_value, created, created_by) VALUES (33601, 655386, 6553618, ' Apache-HttpClient/4.3.4 (java 1.5)', 1500115275, 0) (1 row) time spent 0.000608536 seconds.
15/07/2017 13:41:15:994940 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.string_value, created, created_by) VALUES (33601, 655370, 6553618, ' gzip,deflate', 1500115275, 0) (1 row) time spent 0.00069805 seconds.
15/07/2017 13:41:15:995646 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, created, created_by) VALUES (33601, 655425, 6553611, 1500115275, 0) (1 row) time spent 0.000665671 seconds.
15/07/2017 13:41:15:996389 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.string_value, created, created_by) VALUES (33601, 131999, 6553618, 'check', 1500115275, 0) (1 row) time spent 0.000694378 seconds.
15/07/2017 13:41:15:997111 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.string_value, created, created_by) VALUES (33601, 131997, 6553618, '1126447061', 1500115275, 0) (1 row) time spent 0.000674045 seconds.
15/07/2017 13:41:15:997769 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.string_value, created, created_by) VALUES (33601, 131247, 6553618, '7754300', 1500115275, 0) (1 row) time spent 0.000609772 seconds.
15/07/2017 13:41:15:998437 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.string_value, created, created_by) VALUES (33601, 131998, 6553618, '20170715134115', 1500115275, 0) (1 row) time spent 0.000620861 seconds.
15/07/2017 13:41:15:998934 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.string_value, created, created_by) VALUES (33601, 131950, 6553618, '1000.00', 1500115275, 0) (1 row) time spent 0.000451136 seconds.
15/07/2017 13:41:15:999425 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.string_value, created, created_by) VALUES (33601, 131092, 6553618, '172.21.200.7', 1500115275, 0) (1 row) time spent 0.000448044 seconds.
15/07/2017 13:41:15:999909 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.integer_value, created, created_by) VALUES (33601, 134425, 6553619, 12, 1500115275, 0) (1 row) time spent 0.000444281 seconds.
15/07/2017 13:41:16:000406 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.string_value, created, created_by) VALUES (33601, 132313, 6553618, 'user', 1500115275, 0) (1 row) time spent 0.000454711 seconds.
15/07/2017 13:41:16:001048 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.string_value, created, created_by) VALUES (33601, 132314, 6553618, 'password', 1500115276, 0) (1 row) time spent 0.000599581 seconds.
15/07/2017 13:41:16:001546 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.integer_value, created, created_by) VALUES (33601, 133886, 6553619, 131171, 1500115276, 0) (1 row) time spent 0.000458014 seconds.
15/07/2017 13:41:16:002033 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.double_value, created, created_by) VALUES (33601, 133887, 6553620, 0, 1500115276, 0) (1 row) time spent 0.000442063 seconds.
15/07/2017 13:41:16:002524 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.integer_value, created, created_by) VALUES (33601, 133940, 6553622, 2, 1500115276, 0) (1 row) time spent 0.000450647 seconds.
15/07/2017 13:41:16:003002 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO processed_event_parameters ( processed_event_parameters.processed_event_id, processed_event_parameters.name, processed_event_parameters.type, processed_event_parameters.integer_value, created, created_by) VALUES (33601, 133999, 6553619, 6553772, 1500115276, 0) (1 row) time spent 0.000439709 seconds.
15/07/2017 13:41:16:003032 [29371] DEBUG NewPaymentRequest {} starting
15/07/2017 13:41:16:003650 [29371] SQL SQLDriverImplV2 {} [OK] mysql> INSERT INTO payment_requests ( payment_requests.system_type, payment_requests.type, payment_requests.processed_event_id, payment_requests.state, payment_requests.error_code, payment_requests.identifier, payment_requests.iso_currency_code, payment_requests.payment_number, payment_requests.payment_instant, payment_requests.amount, created, created_by) VALUES (6554010, 133894, 33601, 262213, 262213, '7754300', 643, '1126447061', 1500115275, 1000, 1500115276, 0) (1 row) time spent 0.000561065 seconds.
15/07/2017 13:41:16:003665 [29371] DEBUG NewPaymentRequest {} Sending confirmations
15/07/2017 13:41:16:003673 [29371] DEBUG NewPaymentRequest {} Confirmations were sent
15/07/2017 13:41:16:003678 [29371] DEBUG NewPaymentRequest {} exiting
15/07/2017 13:41:16:003719 [29371] DEBUG PaymentSystemsCommon {} going to finish, sending reply for status 'internal server error':
sid: 0
type: 0
staff_id: 0
priority: 4
address:
port: 0
local_dictionary_size: 0
role_mask: 0
aggregation: 0
urgent: 0
route: Route length: 0
size: 1
Event #0:
event [netup:html][header][1.0] ([131445]131446[131246])
{
body='string, value: "<?xml version="1.0" encoding="UTF-8"?>
<response>
<osmp_txn_id>1126447061</osmp_txn_id>
<result>300</result>
<comment>Другая ошибка провайдера.</comment>
<pay_id>provider</pay_id>
</response>
"'
Content-Type='string, value: "text/xml; charset=UTF-8"'
}
15/07/2017 13:41:16:003726 [29371] DEBUG PaymentSystemsCommon {} finished handling of the event [netup:http]http_request[abhinterinvest] ([131436]132346[134669])
15/07/2017 13:41:16:003740 [29371] DEBUG HttpRequest {} exiting
15/07/2017 13:41:16:003758 [29371] INFO TransactionHandler {} Transaction [0x7fb09435f3e0] has created 1 outgoing transaction[s]
[0x7fb10400c0a0] with 1 events
15/07/2017 13:41:16:003769 [29371] INFO TransactionHandler {} Finished execution of the transaction [0x7fb09435f3e0]
15/07/2017 13:41:16:036404 [7065] INFO ConnectionManagerImpl {} Connection [35106] is closed
15/07/2017 13:41:16:036427 [7065] INFO ConnectionInfoStorage {} Removed info about connection with system socket #12
15/07/2017 13:41:16:036474 [7065] INFO ConnectionManagerImpl {} Connection [35106] is closed
15/07/2017 13:41:16:068980 [29371] SQL SQLDriverImplV2 {} [OK] mysql> [COMMIT TRANSACTION] time spent 0.0652126 seconds.
15/07/2017 13:41:16:069001 [29371] INFO SQLManagerImpl {} Finished commit
15/07/2017 13:41:16:069007 [29371] INFO TransactionHandler {} [0x7fb09435f3e0] has pushed to queue 0 transactions.