Error code 500| internet error

xuewei.zhu · December 2020

I have the following error when pull data using Python API:

Error code 500 | Server Error: {"code":500,"message":"connect ETIMEDOUT 159.220.1.19:443","statusMessage":"Internal Server Error"}

I tried:

ek.set_app_key(APP_KEY)
state = ek.get_desktop_session().get_open_state();

Error on handshake port 9060 : TimeoutException("TimeoutException on HTTP request: ReadTimeout('')")

http://localhost:9060/api/   
{"code":500,"message":"Cannot find module \".\"","statusMessage":"Internal Server Error"}

http://localhost:9000/ping?all
{"port":9000,"mode":"eikon4","pid":29100,"hasSecure":true,"startedTime":"Mon Dec 14 2020 09:52:30 GMT+0000 (GMT Standard Time)","subApps":[{"path":"/heap"},{"path":"/ping"},{"path":"/sxs","data":{"hasSecure":true,"sxsApps":{}}},{"path":"/api"},{"path":"/sxs/v1/services/messenger"}]}

http://localhost:9060/api/status 
"statusCode":"ST_PROXY_READY","version":"2.6.4"

Name: eikon
Version: 1.1.8
Summary: Python package for retrieving Eikon data.
Home-page: https://developers.refinitiv.com/eikon-apis/eikon-data-api
Author: REFINITIV
Author-email: UNKNOWN
License: Apache 2.0
Location: c:\users\xuewe614\appdata\roaming\python\python37\site-packages
Requires: nest-asyncio, numpy, appdirs, python-dateutil, httpx, datetime, websocket-client, pandas, deprecation
Required-by: 
Note: you may need to restart the kernel to use updated packages.

I am able to use Excel to pull out the data but not python anymore.

Please let me know how to fix this issue.

pf · December 2020

Hi,

Your issue is similar to :

To complete :

check python dependency versions (pip list command)
activate debug log in your scipt to retrieve more detail
(add ek.set_log_level(1) before ek.set_app_key(APP_KEY)

xuewei.zhu · December 2020

@pierre.faurel I tried the pip list which shows the Eikon version is 1.1.8 and nest-asyncio is 1.3.3. My desktop version is 4.0.52(4.052055) and eikon proxy version is 9.52.0.51.

This is the full log after set log level(1)

ipdb> 2020-12-15 08:58:09,428 P[41668] [MainThread 21032] Reset a Desktop session with new app_key
2020-12-15 08:58:09,430 P[41668] [MainThread 21032] Unlock login_event for streaming session 0 due to stop streaming call
2020-12-15 08:58:09,434 P[41668] [MainThread 21032] Send GET request to http://127.0.0.1:9060/api/status to detect API Proxy...
2020-12-15 08:58:09,437 P[41668] [MainThread 21032] Request to http://127.0.0.1:9060/api/status
        headers = {'x-tr-applicationid': '43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e'}
        params = None
2020-12-15 08:58:09,523 P[41668] [MainThread 21032] HTTP request response 200: {"statusCode":"ST_PROXY_READY","version":"2.6.4"}
2020-12-15 08:58:09,524 P[41668] [MainThread 21032] Checking port 9060 response : 200 - {"statusCode":"ST_PROXY_READY","version":"2.6.4"}
2020-12-15 08:58:09,526 P[41668] [MainThread 21032] Port 9060 was retrieved from .portInUse file
2020-12-15 08:58:09,527 P[41668] [MainThread 21032] Try to handshake on url http://127.0.0.1:9060/api/handshake...
2020-12-15 08:58:09,529 P[41668] [MainThread 21032] Request to http://127.0.0.1:9060/api/handshake
        headers = {'Content-Type': 'application/json', 'x-tr-applicationid': '43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e'}
        params = None
2020-12-15 08:58:24,532 P[41668] [MainThread 21032] TimeoutException on HTTP request: ReadTimeout('')
2020-12-15 08:58:24,533 P[41668] [MainThread 21032] Error on handshake port 9060 : TimeoutException("TimeoutException on HTTP request: ReadTimeout('')")
2020-12-15 08:58:24,535 P[41668] [MainThread 21032] Application ID: 43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e
2020-12-15 08:58:24,536 P[41668] [MainThread 21032] Init a Desktop session with new app_key
2020-12-15 08:58:24,537 P[41668] [MainThread 21032] Port 9060 on local proxy was detected
2020-12-15 08:58:24,816 P[41668] [MainThread 21032] entity: DataGrid_StandardAsync
2020-12-15 08:58:24,818 P[41668] [MainThread 21032] payload: {'requests': [{'instruments': ['EURAB6E1Y=', 'EURAB6E2Y=', 'EURAB6E3Y=', 'EURAB6E4Y=', 'EURAB6E5Y=', 'EURAB6E6Y=', 'EURAB6E7Y=', 'EURAB6E8Y=', 'EURAB6E9Y=', 'EURAB6E10Y=', 'EURAB6E11Y=', 'EURAB6E12Y=', 'EURAB6E13Y=', 'EURAB6E14Y=', 'EURAB6E15Y=', 'EURAB6E16Y=', 'EURAB6E17Y=', 'EURAB6E18Y=', 'EURAB6E19Y=', 'EURAB6E20Y=', 'EURAB6E21Y=', 'EURAB6E22Y=', 'EURAB6E23Y=', 'EURAB6E24Y=', 'EURAB6E25Y=', 'EURAB6E26Y=', 'EURAB6E27Y=', 'EURAB6E28Y=', 'EURAB6E29Y=', 'EURAB6E30Y=', 'EURAB6E40Y=', 'EURAB6E50Y=', 'GBPSB6L1Y=', 'GBPSB6L2Y=', 'GBPSB6L3Y=', 'GBPSB6L4Y=', 'GBPSB6L5Y=', 'GBPSB6L6Y=', 'GBPSB6L7Y=', 'GBPSB6L8Y=', 'GBPSB6L9Y=', 'GBPSB6L10Y=', 'GBPSB6L12Y=', 'GBPSB6L15Y=', 'GBPSB6L20Y=', 'GBPSB6L25Y=', 'GBPSB6L30Y=', 'GBPSB6L40Y=', 'GBPSB6L50Y=', 'USDSB3L1Y=', 'USDSB3L2Y=', 'USDSB3L3Y=', 'USDSB3L4Y=', 'USDSB3L5Y=', 'USDSB3L6Y=', 'USDSB3L7Y=', 'USDSB3L8Y=', 'USDSB3L9Y=', 'USDSB3L10Y=', 'USDSB3L11Y=', 'USDSB3L12Y=', 'USDSB3L13Y=', 'USDSB3L14Y=', 'USDSB3L15Y=', 'USDSB3L20Y=', 'USDSB3L25Y=', 'USDSB3L30Y=', 'USDSB3L40Y=', 'USDSB3L50Y=', 'SEKAB3S1Y=', 'SEKAB3S2Y=', 'SEKAB3S3Y=', 'SEKAB3S4Y=', 'SEKAB3S5Y=', 'SEKAB3S6Y=', 'SEKAB3S7Y=', 'SEKAB3S8Y=', 'SEKAB3S9Y=', 'SEKAB3S10Y=', 'SEKAB3S12Y=', 'SEKAB3S15Y=', 'SEKAB3S20Y=', 'SEKAB3S30Y=', 'CHFAB6L1Y=', 'CHFAB6L2Y=', 'CHFAB6L3Y=', 'CHFAB6L4Y=', 'CHFAB6L5Y=', 'CHFAB6L6Y=', 'CHFAB6L7Y=', 'CHFAB6L8Y=', 'CHFAB6L9Y=', 'CHFAB6L10Y=', 'CHFAB6L11Y=', 'CHFAB6L12Y=', 'CHFAB6L15Y=', 'CHFAB6L20Y=', 'CHFAB6L25Y=', 'CHFAB6L30Y=', 'AUDSM6AB1Y='], 'fields': [{'name': 'TR.MidPrice'}], 'parameters': {'sdate': '2020-03-31', 'edate': '2020-03-31', 'frq': 'D'}}]}
2020-12-15 08:58:24,824 P[41668] [MainThread 21032] Request:{'Entity': {'E': 'DataGrid_StandardAsync', 'W': {'requests': [{'instruments': ['EURAB6E1Y=', 'EURAB6E2Y=', 'EURAB6E3Y=', 'EURAB6E4Y=', 'EURAB6E5Y=', 'EURAB6E6Y=', 'EURAB6E7Y=', 'EURAB6E8Y=', 'EURAB6E9Y=', 'EURAB6E10Y=', 'EURAB6E11Y=', 'EURAB6E12Y=', 'EURAB6E13Y=', 'EURAB6E14Y=', 'EURAB6E15Y=', 'EURAB6E16Y=', 'EURAB6E17Y=', 'EURAB6E18Y=', 'EURAB6E19Y=', 'EURAB6E20Y=', 'EURAB6E21Y=', 'EURAB6E22Y=', 'EURAB6E23Y=', 'EURAB6E24Y=', 'EURAB6E25Y=', 'EURAB6E26Y=', 'EURAB6E27Y=', 'EURAB6E28Y=', 'EURAB6E29Y=', 'EURAB6E30Y=', 'EURAB6E40Y=', 'EURAB6E50Y=', 'GBPSB6L1Y=', 'GBPSB6L2Y=', 'GBPSB6L3Y=', 'GBPSB6L4Y=', 'GBPSB6L5Y=', 'GBPSB6L6Y=', 'GBPSB6L7Y=', 'GBPSB6L8Y=', 'GBPSB6L9Y=', 'GBPSB6L10Y=', 'GBPSB6L12Y=', 'GBPSB6L15Y=', 'GBPSB6L20Y=', 'GBPSB6L25Y=', 'GBPSB6L30Y=', 'GBPSB6L40Y=', 'GBPSB6L50Y=', 'USDSB3L1Y=', 'USDSB3L2Y=', 'USDSB3L3Y=', 'USDSB3L4Y=', 'USDSB3L5Y=', 'USDSB3L6Y=', 'USDSB3L7Y=', 'USDSB3L8Y=', 'USDSB3L9Y=', 'USDSB3L10Y=', 'USDSB3L11Y=', 'USDSB3L12Y=', 'USDSB3L13Y=', 'USDSB3L14Y=', 'USDSB3L15Y=', 'USDSB3L20Y=', 'USDSB3L25Y=', 'USDSB3L30Y=', 'USDSB3L40Y=', 'USDSB3L50Y=', 'SEKAB3S1Y=', 'SEKAB3S2Y=', 'SEKAB3S3Y=', 'SEKAB3S4Y=', 'SEKAB3S5Y=', 'SEKAB3S6Y=', 'SEKAB3S7Y=', 'SEKAB3S8Y=', 'SEKAB3S9Y=', 'SEKAB3S10Y=', 'SEKAB3S12Y=', 'SEKAB3S15Y=', 'SEKAB3S20Y=', 'SEKAB3S30Y=', 'CHFAB6L1Y=', 'CHFAB6L2Y=', 'CHFAB6L3Y=', 'CHFAB6L4Y=', 'CHFAB6L5Y=', 'CHFAB6L6Y=', 'CHFAB6L7Y=', 'CHFAB6L8Y=', 'CHFAB6L9Y=', 'CHFAB6L10Y=', 'CHFAB6L11Y=', 'CHFAB6L12Y=', 'CHFAB6L15Y=', 'CHFAB6L20Y=', 'CHFAB6L25Y=', 'CHFAB6L30Y=', 'AUDSM6AB1Y='], 'fields': [{'name': 'TR.MidPrice'}], 'parameters': {'sdate': '2020-03-31', 'edate': '2020-03-31', 'frq': 'D'}}]}}}
2020-12-15 08:58:24,826 P[41668] [MainThread 21032] Request to http://127.0.0.1:9060/api/v1/data
        headers = {'Content-Type': 'application/json', 'x-tr-applicationid': '43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e'}
        params = None
2020-12-15 08:58:46,021 P[41668] [MainThread 21032] HTTP request response 500: {"code":500,"message":"connect ETIMEDOUT 159.220.1.19:443","statusMessage":"Internal Server Error"}
2020-12-15 08:58:46,022 P[41668] [MainThread 21032] HTTP Response code: 500
2020-12-15 08:58:46,023 P[41668] [MainThread 21032] HTTP Response: {"code":500,"message":"connect ETIMEDOUT 159.220.1.19:443","statusMessage":"Internal Server Error"}
2020-12-15 08:58:46,025 P[41668] [MainThread 21032] Headers: x_ratelimit_limit=None / x_ratelimit_remaining=None 
2020-12-15 08:58:46,027 P[41668] [MainThread 21032]          x_volumelimit_limit=None / x_volumelimit_remaining=None
2020-12-15 08:58:46,029 P[41668] [MainThread 21032]          retry_after 0
2020-12-15 08:58:46,031 P[41668] [MainThread 21032] Error code 500 | Server Error: {"code":500,"message":"connect ETIMEDOUT 159.220.1.19:443","statusMessage":"Internal Server Error"}
2020-12-15 08:58:46,032 P[41668] [MainThread 21032] HTTP request failed: EikonError-Server Error: {"code":500,"message":"connect ETIMEDOUT 159.220.1.19:443","statusMessage":"Internal Server Error"}

pf · December 2020

Hi @xuewei.zhu,

The log shows that python script identified the port to use to connect to Eikon API Proxy, but the next steps failed with Timeout:

2020-12-15 08:58:09,529 P[41668] [MainThread 21032] Request to http://127.0.0.1:9060/api/handshake         headers = {'Content-Type': 'application/json', 'x-tr-applicationid': '43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e'}         params = None 2020-12-15 08:58:24,532 P[41668] [MainThread 21032] TimeoutException on HTTP request: ReadTimeout('')

To understand why, could you :

set HTTPX_LOG_LEVEL to trace low level errors.
You can add 2 following lines in your script:
import os
os.environ['HTTPX_LOG_LEVEL']='trace'

=> You should have additional messages in output
activate debug log in Eikon (with Eikon Tools Configuration Manager), then restart Eikon + your script, an dlastly check APIProxy log file in C:\ProgramData\Thomson Reuters\Eikon Data\Logs\TRD\Eikon.20201215.<hhmmss>.p<pid>

xuewei.zhu · December 2020

@pierre.faurel I dont see any addtional message in output after

ek.set_app_key(APP_KEY)
os.environ['HTTPX_LOG_LEVEL']='trace'
ek.set_log_level(1)
....
dataSwap = ek.get_data(t, ["TR.MidPrice"], {'sdate':valuationDate, 'edate':valuationDate,'frq':'D'})

I am not sure if I did the right thing to active the debug log file, please let me know if this file is one you need.

Eikon: -eikonpid=42036 -EikonProcessId=18396 -HwndComInterop=0x1 "-manifest=C:\Users\xuewe614\AppData\Local\Thomson Reuters\Eikon User\Cache\LibraryCache\9.0.52055_GEDTC-327782__EikonContainer.dll.manifest" "-Context=C:\Users\xuewe614\AppData\Local\Thomson Reuters\Eikon User\Cache\LibraryCache\Apps\REFINITIVHOLDINGS.EIKON.DATAAPIPROXY\1.1.0.15" /prefetch:1

start node

Arg: C:\Program Files (x86)\Thomson Reuters\Eikon\X\Bin\Eikonbox.exe C:\Users\xuewe614\AppData\Local\Thomson Reuters\Eikon User\Cache\LibraryCache\Apps\REFINITIVHOLDINGS.EIKON.DATAAPIPROXY\1.1.0.15\index.js -TraceLevel=2

[2020-12-15 17:29:18.315|1|api-svc|1|INFO] PA: false
[2020-12-15 17:29:18.326|1|api-svc|2|DEBUG] Waiting for FT
[2020-12-15 17:29:25.934|1|api-svc|1|INFO] FT is ready
[2020-12-15 17:29:25.944|1|api-svc|3|DEBUG] set User Agent
[2020-12-15 17:29:25.955|1|api-svc|1|INFO] Initial port: 9060
[2020-12-15 17:29:25.965|1|api-svc|1|INFO] Port range: 20
[2020-12-15 17:29:25.994|1|api-svc|1|INFO] Listening to port=9060
[2020-12-15 17:29:26.014|1|data-api|1|INFO] [ConfigurationManager]Getting configuration ...
[2020-12-15 17:29:26.089|1|data-api|1|INFO] [ConfigurationManager]Configuration successfully retrieved.
[2020-12-15 17:29:26.097|1|data-api|3|DEBUG] [ConfigurationManager]{"backwardCompatibilityBetaEnabled":true,"currentVersion":"2.6.4","currentWebApiVersion":"1.1.0","dataCenter":"","endPointURL":"https://emea1.apps.cp.thomsonreuters.com/Apps/UDF/MSF","handshake":{"enabled":true,"isMandatory":false},"minimalVersion":"0.0.0","minimalVersionRequirementMet":true,"proxyEnabled":true,"rdpAccessEnabled":true,"statusEnabled":true,"streaming":{"enabled":true,"maxConnection":10},"throttling":{"dailyRequestCount":{"enabled":true,"max":10000},"dailyVolume":{"enabled":true,"max":5000000},"queueSize":100,"queueTimeout":120,"requestCount":{"enabled":true,"max":5,"period":1},"volume":{"enabled":true,"max":50000,"period":60}},"udfAccessEnabled":true,"usageTrackingEnabled":true}
[2020-12-15 17:29:26.114|1|data-api|3|DEBUG] [getWsClientOptions]HTTP_PROXY and HTTPS_PROXY environment variables are no set, skipping proxy configuration.
[2020-12-15 17:29:26.144|1|data-api|3|DEBUG] [UdfController][UdfController::create] Creating UDF controller.
[2020-12-15 17:29:26.161|1|data-api|3|DEBUG] [StatusController][StatusController::create] Creating status controller.
[2020-12-15 17:29:26.173|1|data-api|3|DEBUG] [RdpStreamingDiscoveryController][RdpStreamingDiscoveryController::create] Creating RDP Streaming Discovery controller.
[2020-12-15 17:29:26.183|1|data-api|3|DEBUG] [RdpController][RdpController::create] Creating RDP controller.
[2020-12-15 17:29:26.198|1|data-api|3|DEBUG] [HomeController][HomeController::create] Creating index controller.
[2020-12-15 17:29:26.210|1|data-api|3|DEBUG] [HandshakeController][Handshake::create] Creating handshake controller.
[2020-12-15 17:29:26.225|1|data-api|3|DEBUG] [ApiV1StreamingDiscoveryController][ApiV1StreamingDiscoveryController::create] Creating ApiV1StreamingDiscoveryController controller.
[2020-12-15 17:29:26.234|1|data-api|3|DEBUG] [ApiController][ApiController::create] Creating API controller.
[2020-12-15 17:29:26.252|1|data-api|3|DEBUG] [TracingMiddleware]Creating new request tracing middleware.
[2020-12-15 17:29:26.266|1|data-api|3|DEBUG] [SettingsAggregatorService]Getting value for APIPROXY.THROTTLING.STORE from platform setting service
[2020-12-15 17:29:26.275|1|data-api|3|DEBUG] [TracingMiddleware]Creating new request tracing middleware.
[2020-12-15 17:29:26.290|1|data-api|3|DEBUG] [TracingMiddleware]Creating new request tracing middleware.
[2020-12-15 17:29:26.302|1|data-api|3|DEBUG] [TracingMiddleware]Creating new request tracing middleware.
[2020-12-15 17:29:26.322|1|data-api|3|DEBUG] [TracingMiddleware]Creating new request tracing middleware.
[2020-12-15 17:29:26.352|1|data-api|3|DEBUG] [ABFT][ABFT] Applying anti-bruteforce throttling
[2020-12-15 17:29:26.383|1|data-api|1|INFO] [Throttling][RBT] Request-count based throttling has been enabled by configuration.
[2020-12-15 17:29:26.393|1|data-api|3|DEBUG] [Throttling][RBT] Applying request-count limits - 5 request(s) per 1 second(s)
[2020-12-15 17:29:26.403|1|data-api|1|INFO] [Throttling][VBT] Volume-based throttling has been enabled by configuration.
[2020-12-15 17:29:26.413|1|data-api|3|DEBUG] [Throttling][VBT] Applying volume limits - 50000 KB per 60 second(s)
[2020-12-15 17:29:26.437|1|data-api|1|INFO] [WebSocketServer]Creating new WebSocket server instance
[2020-12-15 17:29:26.453|1|data-api|3|DEBUG] [WebSocketServer]WebSocket path is /api/rdp/streaming/pricing/v1/WebSocket. Max simultaneous connections: 10
[2020-12-15 17:29:26.469|1|data-api|1|INFO] [ApiV1WebSocketServerProvider]Creating new WebSocket server instance
[2020-12-15 17:29:26.481|1|data-api|3|DEBUG] [ApiV1WebSocketServerProvider]WebSocket path is /api/v1/data/streaming/pricing/WebSocket. Max simultaneous connections: 10
[2020-12-15 17:29:26.516|1|data-api|3|DEBUG] [SettingsAggregatorService]Got value '{"dailyRequest":"[[\"127.0.0.1\",[[1607990400000,0],[1607904000000,0]]]]","dailyVolume":"[]"}', trying to parse ...
[2020-12-15 17:29:26.526|1|data-api|3|DEBUG] [SettingsAggregatorService]Parsing succeeded !
[2020-12-15 17:29:26.535|1|data-api|1|INFO] [Throttling][DRBT] Daily request-count based throttling has been enabled by configuration.
[2020-12-15 17:29:26.547|1|data-api|3|DEBUG] [Throttling][DRBT] Applying daily request-count limits - 10000 request(s) per day
[2020-12-15 17:29:26.562|1|data-api|1|INFO] [Throttling][DVBT] Daily volume-based throttling has been enabled by configuration.
[2020-12-15 17:29:26.572|1|data-api|3|DEBUG] [Throttling][DVBT] Applying daily volume-based limits - 5000000 KB per day
[2020-12-15 17:29:26.595|1|api-svc|1|INFO] Write port file
[2020-12-15 17:31:06.649|1|data-api|3|DEBUG] [TracingMiddleware]Creating new request tracing middleware.
[2020-12-15 17:31:06.657|1|data-api|3|DEBUG] [TracingMiddleware]Got new incoming request, request id is #43259f1e-751c-47d9-8fd8-9e82e897dc4d.
[2020-12-15 17:31:06.664|1|data-api|1|INFO] [LogRequestDetailMiddleware][Request #43259f1e-751c-47d9-8fd8-9e82e897dc4d] New incoming GET request
[2020-12-15 17:31:06.670|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #43259f1e-751c-47d9-8fd8-9e82e897dc4d] URL: http://127.0.0.1:9060/api/status
[2020-12-15 17:31:06.677|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #43259f1e-751c-47d9-8fd8-9e82e897dc4d] Headers: {"host":"127.0.0.1:9060","user-agent":"python-httpx/0.14.3","accept":"*/*","accept-encoding":"gzip, deflate","connection":"keep-alive","x-tr-applicationid":"43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e"}
[2020-12-15 17:31:06.683|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #43259f1e-751c-47d9-8fd8-9e82e897dc4d] Body: {}
[2020-12-15 17:31:06.690|1|data-api|3|DEBUG] [RefinitivAppsCorsHandlerMiddleware]Generating dynamic CORS module options ...
[2020-12-15 17:31:06.698|1|data-api|3|DEBUG] [StatusController][StatusController::create] Creating status controller.
[2020-12-15 17:31:06.717|1|data-api|3|DEBUG] [TracingMiddleware]Creating new request tracing middleware.
[2020-12-15 17:31:06.725|1|data-api|3|DEBUG] [TracingMiddleware]Got new incoming request, request id is #2a4027a6-4144-45ed-9418-b3d39379f243.
[2020-12-15 17:31:06.731|1|data-api|1|INFO] [LogRequestDetailMiddleware][Request #2a4027a6-4144-45ed-9418-b3d39379f243] New incoming POST request
[2020-12-15 17:31:06.737|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #2a4027a6-4144-45ed-9418-b3d39379f243] URL: http://127.0.0.1:9060/api/handshake
[2020-12-15 17:31:06.742|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #2a4027a6-4144-45ed-9418-b3d39379f243] Headers: {"host":"127.0.0.1:9060","user-agent":"python-httpx/0.14.3","accept":"*/*","accept-encoding":"gzip, deflate","connection":"keep-alive","content-type":"application/json","x-tr-applicationid":"43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e","content-length":"159"}
[2020-12-15 17:31:06.748|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #2a4027a6-4144-45ed-9418-b3d39379f243] Body: {"AppKey":"43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e","AppScope":"rapi","ApiVersion":"1","LibraryName":"Eikon Python Library","LibraryVersion":"1.1.8"}
[2020-12-15 17:31:06.757|1|data-api|3|DEBUG] [UsageTrackingService]Sending event RequestReceived to usage tracking service
[2020-12-15 17:31:06.765|1|data-api|3|DEBUG] [ProductInsight]Token: ****************************9e5a - Event: RequestReceived - Attributes: {"AppKey":"************************************f26e","EndPoint":"SymbologySearch","API":"Eikon Data APIs"}
[2020-12-15 17:31:06.771|1|data-api|3|DEBUG] Received new usage tracking request for event RequestReceived
[2020-12-15 17:31:06.778|1|data-api|3|DEBUG] Sending usage tracking payload {"Target":"/UsageTracking","RequestType":"send","Method":"EventRawData","MethodParam":{"EventName":"RequestReceived","EventProperties":"{\"AppKey\":\"43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e\",\"EndPoint\":\"SymbologySearch\",\"API\":\"Eikon Data APIs\"}","ProjectToken":"881d83de3bf9490988d03e2250149e5a"}} on EikonBus channel /UsageTracking
[2020-12-15 17:31:21.842|1|data-api|3|DEBUG] [ApiController][ApiController::create] Creating API controller.
[2020-12-15 17:31:21.852|1|data-api|0|WARN] [SecurityTokenHandlerMiddleware]An error occured while checking security token but security token is not mandatory, calling next middleware anyway ...
[2020-12-15 17:31:21.859|1|data-api|3|DEBUG] [TracingMiddleware]Creating new request tracing middleware.
[2020-12-15 17:31:21.867|1|data-api|3|DEBUG] [TracingMiddleware]Got new incoming request, request id is #e563f666-f672-4eb8-b32a-abd3ef904545.
[2020-12-15 17:31:21.872|1|data-api|1|INFO] [LogRequestDetailMiddleware][Request #e563f666-f672-4eb8-b32a-abd3ef904545] New incoming POST request
[2020-12-15 17:31:21.879|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #e563f666-f672-4eb8-b32a-abd3ef904545] URL: http://127.0.0.1:9060/api/udf
[2020-12-15 17:31:21.884|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #e563f666-f672-4eb8-b32a-abd3ef904545] Headers: {"host":"127.0.0.1:9060","user-agent":"python-httpx/0.14.3","accept":"*/*","accept-encoding":"gzip, deflate","connection":"keep-alive","content-type":"application/json","x-tr-applicationid":"43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e","content-length":"1649"}
[2020-12-15 17:31:21.892|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #e563f666-f672-4eb8-b32a-abd3ef904545] Body: {"Entity":{"E":"DataGrid_StandardAsync","W":{"requests":[{"instruments":["EURAB6E1Y=","EURAB6E2Y=","EURAB6E3Y=","EURAB6E4Y=","EURAB6E5Y=","EURAB6E6Y=","EURAB6E7Y=","EURAB6E8Y=","EURAB6E9Y=","EURAB6E10Y=","EURAB6E11Y=","EURAB6E12Y=","EURAB6E13Y=","EURAB6E14Y=","EURAB6E15Y=","EURAB6E16Y=","EURAB6E17Y=","EURAB6E18Y=","EURAB6E19Y=","EURAB6E20Y=","EURAB6E21Y=","EURAB6E22Y=","EURAB6E23Y=","EURAB6E24Y=","EURAB6E25Y=","EURAB6E26Y=","EURAB6E27Y=","EURAB6E28Y=","EURAB6E29Y=","EURAB6E30Y=","EURAB6E40Y=","EURAB6E50Y=","GBPSB6L1Y=","GBPSB6L2Y=","GBPSB6L3Y=","GBPSB6L4Y=","GBPSB6L5Y=","GBPSB6L6Y=","GBPSB6L7Y=","GBPSB6L8Y=","GBPSB6L9Y=","GBPSB6L10Y=","GBPSB6L12Y=","GBPSB6L15Y=","GBPSB6L20Y=","GBPSB6L25Y=","GBPSB6L30Y=","GBPSB6L40Y=","GBPSB6L50Y=","USDSB3L1Y=","USDSB3L2Y=","USDSB3L3Y=","USDSB3L4Y=","USDSB3L5Y=","USDSB3L6Y=","USDSB3L7Y=","USDSB3L8Y=","USDSB3L9Y=","USDSB3L10Y=","USDSB3L11Y=","USDSB3L12Y=","USDSB3L13Y=","USDSB3L14Y=","USDSB3L15Y=","USDSB3L20Y=","USDSB3L25Y=","USDSB3L30Y=","USDSB3L40Y=","USDSB3L50Y=","SEKAB3S1Y=","SEKAB3S2Y=","SEKAB3S3Y=","SEKAB3S4Y=","SEKAB3S5Y=","SEKAB3S6Y=","SEKAB3S7Y=","SEKAB3S8Y=","SEKAB3S9Y=","SEKAB3S10Y=","SEKAB3S12Y=","SEKAB3S15Y=","SEKAB3S20Y=","SEKAB3S30Y=","CHFAB6L1Y=","CHFAB6L2Y=","CHFAB6L3Y=","CHFAB6L4Y=","CHFAB6L5Y=","CHFAB6L6Y=","CHFAB6L7Y=","CHFAB6L8Y=","CHFAB6L9Y=","CHFAB6L10Y=","CHFAB6L11Y=","CHFAB6L12Y=","CHFAB6L15Y=","CHFAB6L20Y=","CHFAB6L25Y=","CHFAB6L30Y=","AUDSM6AB1Y="],"fields":[{"name":"TR.MidPrice"}],"parameters":{"sdate":"2020-03-31","edate":"2020-03-31","frq":"D"}}]}}}
[2020-12-15 17:31:21.899|1|data-api|3|DEBUG] [Throttling][Request #e563f666-f672-4eb8-b32a-abd3ef904545] [LBBT] Status: received - Step: 1 - Sum: 0 - Remaining: 100
[2020-12-15 17:31:21.906|1|data-api|3|DEBUG] [Throttling][Request #e563f666-f672-4eb8-b32a-abd3ef904545] [RBT] Limit: 5 - Step: 1 - Sum: 1 - Remaining: 4 - Request rejected: false
[2020-12-15 17:31:21.914|1|data-api|3|DEBUG] [Throttling][Request #e563f666-f672-4eb8-b32a-abd3ef904545] [LBBT] Status: accepted - Step: 1 - Sum: 0 - Remaining: 100
[2020-12-15 17:31:21.921|1|data-api|3|DEBUG] [Throttling][Request #e563f666-f672-4eb8-b32a-abd3ef904545] [DRBT] Limit: 10000 - Step: 1 - Sum: 1 - Remaining: 9999 - Request rejected: false
[2020-12-15 17:31:21.927|1|data-api|3|DEBUG] [UdfController][UdfController::create] Creating UDF controller.
[2020-12-15 17:31:21.934|1|data-api|3|DEBUG] [UsageTrackingService]Sending event RequestReceived to usage tracking service
[2020-12-15 17:31:21.941|1|data-api|3|DEBUG] [ProductInsight]Token: ****************************9e5a - Event: RequestReceived - Attributes: {"AppKey":"************************************f26e","EndPoint":"DataGrid_StandardAsync","API":"Eikon Data APIs"}
[2020-12-15 17:31:21.946|1|data-api|3|DEBUG] Received new usage tracking request for event RequestReceived
[2020-12-15 17:31:21.954|1|data-api|3|DEBUG] Sending usage tracking payload {"Target":"/UsageTracking","RequestType":"send","Method":"EventRawData","MethodParam":{"EventName":"RequestReceived","EventProperties":"{\"AppKey\":\"43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e\",\"EndPoint\":\"DataGrid_StandardAsync\",\"API\":\"Eikon Data APIs\"}","ProjectToken":"881d83de3bf9490988d03e2250149e5a"}} on EikonBus channel /UsageTracking
[2020-12-15 17:31:21.966|1|data-api|3|DEBUG] [SettingsAggregatorService]Saving setting value - Name: APIPROXY.THROTTLING.STORE, Value: {"dailyRequest":"[[\"127.0.0.1\",[[1607990400000,1],[1607904000000,0]]]]","dailyVolume":"[]"}
[2020-12-15 17:31:21.975|1|data-api|3|DEBUG] [SettingsAggregatorService]Saving setting value - Name: APIPROXY.THROTTLING.STORE, Value: {"dailyRequest":"[[\"127.0.0.1\",[[1607990400000,1],[1607904000000,0]]]]","dailyVolume":"[]"}
[2020-12-15 17:31:27.843|1|data-api|0|ERROR] [UdfClientExceptionLoggerDecorator]An error occured while processing the request: connect ETIMEDOUT 159.220.1.19:443
[2020-12-15 17:31:27.849|1|data-api|0|ERROR] connect ETIMEDOUT 159.220.1.19:443
[2020-12-15 17:31:27.856|1|data-api|0|ERROR] Error: connect ETIMEDOUT 159.220.1.19:443
at Object.exports._errnoException (util.js:1022:11)
at exports._exceptionWithHostPort (util.js:1045:20)
at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1087:14)
[2020-12-15 17:31:27.862|1|data-api|0|ERROR] [HandshakeApplicationKeyCheckMiddleware]An error occured while checking application key during handshake
[2020-12-15 17:31:27.868|1|data-api|0|ERROR] connect ETIMEDOUT 159.220.1.19:443
[2020-12-15 17:31:27.874|1|data-api|0|ERROR] Error: connect ETIMEDOUT 159.220.1.19:443
at Object.exports._errnoException (util.js:1022:11)
at exports._exceptionWithHostPort (util.js:1045:20)
at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1087:14)
[2020-12-15 17:31:42.983|1|data-api|0|ERROR] [UdfClientExceptionLoggerDecorator]An error occured while processing the request: connect ETIMEDOUT 159.220.1.19:443
[2020-12-15 17:31:43.001|1|data-api|0|ERROR] connect ETIMEDOUT 159.220.1.19:443
[2020-12-15 17:31:43.009|1|data-api|0|ERROR] Error: connect ETIMEDOUT 159.220.1.19:443
at Object.exports._errnoException (util.js:1022:11)
at exports._exceptionWithHostPort (util.js:1045:20)
at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1087:14)
[2020-12-15 17:31:43.017|1|data-api|3|DEBUG] [Throttling][Request #e563f666-f672-4eb8-b32a-abd3ef904545] [DVBT] Limit: 5000000.00 KB - Step: 0.00 KB - Sum: 0.00 KB - Remaining: 5000000.00 KB - Request rejected: false
[2020-12-15 17:31:43.023|1|data-api|3|DEBUG] [Throttling][Request #e563f666-f672-4eb8-b32a-abd3ef904545] [VBT] Limit: 50000.00 KB - Step: 0.00 KB - Sum: 0.00 KB - Remaining: 50000.00 KB - Request rejected: false
[2020-12-15 17:31:43.031|1|data-api|3|DEBUG] [SettingsAggregatorService]Saving setting value - Name: APIPROXY.THROTTLING.STORE, Value: {"dailyRequest":"[[\"127.0.0.1\",[[1607990400000,1],[1607904000000,0]]]]","dailyVolume":"[]"}
[2020-12-15 17:31:43.040|1|data-api|3|DEBUG] [SettingsAggregatorService]Saving setting value - Name: APIPROXY.THROTTLING.STORE, Value: {"dailyRequest":"[[\"127.0.0.1\",[[1607990400000,0],[1607904000000,0]]]]","dailyVolume":"[]"}
[2020-12-15 17:32:19.042|1|data-api|3|DEBUG] [TracingMiddleware]Creating new request tracing middleware.
[2020-12-15 17:32:19.049|1|data-api|3|DEBUG] [TracingMiddleware]Got new incoming request, request id is #7c19d9c9-4ab1-4f9d-a25a-a776c21cefcd.
[2020-12-15 17:32:19.055|1|data-api|1|INFO] [LogRequestDetailMiddleware][Request #7c19d9c9-4ab1-4f9d-a25a-a776c21cefcd] New incoming GET request
[2020-12-15 17:32:19.061|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #7c19d9c9-4ab1-4f9d-a25a-a776c21cefcd] URL: http://127.0.0.1:9060/api/status
[2020-12-15 17:32:19.068|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #7c19d9c9-4ab1-4f9d-a25a-a776c21cefcd] Headers: {"host":"127.0.0.1:9060","user-agent":"python-httpx/0.14.3","accept":"*/*","accept-encoding":"gzip, deflate","connection":"keep-alive","x-tr-applicationid":"43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e"}
[2020-12-15 17:32:19.075|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #7c19d9c9-4ab1-4f9d-a25a-a776c21cefcd] Body: {}
[2020-12-15 17:32:19.082|1|data-api|3|DEBUG] [RefinitivAppsCorsHandlerMiddleware]Generating dynamic CORS module options ...
[2020-12-15 17:32:19.088|1|data-api|3|DEBUG] [StatusController][StatusController::create] Creating status controller.
[2020-12-15 17:32:19.113|1|data-api|3|DEBUG] [TracingMiddleware]Creating new request tracing middleware.
[2020-12-15 17:32:19.120|1|data-api|3|DEBUG] [TracingMiddleware]Got new incoming request, request id is #bef94132-f965-4e2e-8050-a1d54e0e1913.
[2020-12-15 17:32:19.129|1|data-api|1|INFO] [LogRequestDetailMiddleware][Request #bef94132-f965-4e2e-8050-a1d54e0e1913] New incoming POST request
[2020-12-15 17:32:19.135|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #bef94132-f965-4e2e-8050-a1d54e0e1913] URL: http://127.0.0.1:9060/api/handshake
[2020-12-15 17:32:19.142|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #bef94132-f965-4e2e-8050-a1d54e0e1913] Headers: {"host":"127.0.0.1:9060","user-agent":"python-httpx/0.14.3","accept":"*/*","accept-encoding":"gzip, deflate","connection":"keep-alive","content-type":"application/json","x-tr-applicationid":"43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e","content-length":"159"}
[2020-12-15 17:32:19.149|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #bef94132-f965-4e2e-8050-a1d54e0e1913] Body: {"AppKey":"43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e","AppScope":"rapi","ApiVersion":"1","LibraryName":"Eikon Python Library","LibraryVersion":"1.1.8"}
[2020-12-15 17:32:19.162|1|data-api|3|DEBUG] [UsageTrackingService]Sending event RequestReceived to usage tracking service
[2020-12-15 17:32:19.169|1|data-api|3|DEBUG] [ProductInsight]Token: ****************************9e5a - Event: RequestReceived - Attributes: {"AppKey":"************************************f26e","EndPoint":"SymbologySearch","API":"Eikon Data APIs"}
[2020-12-15 17:32:19.175|1|data-api|3|DEBUG] Received new usage tracking request for event RequestReceived
[2020-12-15 17:32:19.182|1|data-api|3|DEBUG] Sending usage tracking payload {"Target":"/UsageTracking","RequestType":"send","Method":"EventRawData","MethodParam":{"EventName":"RequestReceived","EventProperties":"{\"AppKey\":\"43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e\",\"EndPoint\":\"SymbologySearch\",\"API\":\"Eikon Data APIs\"}","ProjectToken":"881d83de3bf9490988d03e2250149e5a"}} on EikonBus channel /UsageTracking
[2020-12-15 17:32:34.384|1|data-api|3|DEBUG] [ApiController][ApiController::create] Creating API controller.
[2020-12-15 17:32:34.400|1|data-api|0|WARN] [SecurityTokenHandlerMiddleware]An error occured while checking security token but security token is not mandatory, calling next middleware anyway ...
[2020-12-15 17:32:34.407|1|data-api|3|DEBUG] [TracingMiddleware]Creating new request tracing middleware.
[2020-12-15 17:32:34.414|1|data-api|3|DEBUG] [TracingMiddleware]Got new incoming request, request id is #10db02aa-6a81-42ec-99b2-d4aaefbf04d5.
[2020-12-15 17:32:34.420|1|data-api|1|INFO] [LogRequestDetailMiddleware][Request #10db02aa-6a81-42ec-99b2-d4aaefbf04d5] New incoming POST request
[2020-12-15 17:32:34.426|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #10db02aa-6a81-42ec-99b2-d4aaefbf04d5] URL: http://127.0.0.1:9060/api/udf
[2020-12-15 17:32:34.433|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #10db02aa-6a81-42ec-99b2-d4aaefbf04d5] Headers: {"host":"127.0.0.1:9060","user-agent":"python-httpx/0.14.3","accept":"*/*","accept-encoding":"gzip, deflate","connection":"keep-alive","content-type":"application/json","x-tr-applicationid":"43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e","content-length":"1649"}
[2020-12-15 17:32:34.439|1|data-api|3|DEBUG] [LogRequestDetailMiddleware][Request #10db02aa-6a81-42ec-99b2-d4aaefbf04d5] Body: {"Entity":{"E":"DataGrid_StandardAsync","W":{"requests":[{"instruments":["EURAB6E1Y=","EURAB6E2Y=","EURAB6E3Y=","EURAB6E4Y=","EURAB6E5Y=","EURAB6E6Y=","EURAB6E7Y=","EURAB6E8Y=","EURAB6E9Y=","EURAB6E10Y=","EURAB6E11Y=","EURAB6E12Y=","EURAB6E13Y=","EURAB6E14Y=","EURAB6E15Y=","EURAB6E16Y=","EURAB6E17Y=","EURAB6E18Y=","EURAB6E19Y=","EURAB6E20Y=","EURAB6E21Y=","EURAB6E22Y=","EURAB6E23Y=","EURAB6E24Y=","EURAB6E25Y=","EURAB6E26Y=","EURAB6E27Y=","EURAB6E28Y=","EURAB6E29Y=","EURAB6E30Y=","EURAB6E40Y=","EURAB6E50Y=","GBPSB6L1Y=","GBPSB6L2Y=","GBPSB6L3Y=","GBPSB6L4Y=","GBPSB6L5Y=","GBPSB6L6Y=","GBPSB6L7Y=","GBPSB6L8Y=","GBPSB6L9Y=","GBPSB6L10Y=","GBPSB6L12Y=","GBPSB6L15Y=","GBPSB6L20Y=","GBPSB6L25Y=","GBPSB6L30Y=","GBPSB6L40Y=","GBPSB6L50Y=","USDSB3L1Y=","USDSB3L2Y=","USDSB3L3Y=","USDSB3L4Y=","USDSB3L5Y=","USDSB3L6Y=","USDSB3L7Y=","USDSB3L8Y=","USDSB3L9Y=","USDSB3L10Y=","USDSB3L11Y=","USDSB3L12Y=","USDSB3L13Y=","USDSB3L14Y=","USDSB3L15Y=","USDSB3L20Y=","USDSB3L25Y=","USDSB3L30Y=","USDSB3L40Y=","USDSB3L50Y=","SEKAB3S1Y=","SEKAB3S2Y=","SEKAB3S3Y=","SEKAB3S4Y=","SEKAB3S5Y=","SEKAB3S6Y=","SEKAB3S7Y=","SEKAB3S8Y=","SEKAB3S9Y=","SEKAB3S10Y=","SEKAB3S12Y=","SEKAB3S15Y=","SEKAB3S20Y=","SEKAB3S30Y=","CHFAB6L1Y=","CHFAB6L2Y=","CHFAB6L3Y=","CHFAB6L4Y=","CHFAB6L5Y=","CHFAB6L6Y=","CHFAB6L7Y=","CHFAB6L8Y=","CHFAB6L9Y=","CHFAB6L10Y=","CHFAB6L11Y=","CHFAB6L12Y=","CHFAB6L15Y=","CHFAB6L20Y=","CHFAB6L25Y=","CHFAB6L30Y=","AUDSM6AB1Y="],"fields":[{"name":"TR.MidPrice"}],"parameters":{"sdate":"2020-03-31","edate":"2020-03-31","frq":"D"}}]}}}
[2020-12-15 17:32:34.446|1|data-api|3|DEBUG] [Throttling][Request #10db02aa-6a81-42ec-99b2-d4aaefbf04d5] [LBBT] Status: received - Step: 1 - Sum: 0 - Remaining: 100
[2020-12-15 17:32:34.452|1|data-api|3|DEBUG] [Throttling][Request #10db02aa-6a81-42ec-99b2-d4aaefbf04d5] [RBT] Limit: 5 - Step: 1 - Sum: 1 - Remaining: 4 - Request rejected: false
[2020-12-15 17:32:34.458|1|data-api|3|DEBUG] [Throttling][Request #10db02aa-6a81-42ec-99b2-d4aaefbf04d5] [LBBT] Status: accepted - Step: 1 - Sum: 0 - Remaining: 100
[2020-12-15 17:32:34.465|1|data-api|3|DEBUG] [Throttling][Request #10db02aa-6a81-42ec-99b2-d4aaefbf04d5] [DRBT] Limit: 10000 - Step: 1 - Sum: 1 - Remaining: 9999 - Request rejected: false
[2020-12-15 17:32:34.471|1|data-api|3|DEBUG] [UdfController][UdfController::create] Creating UDF controller.
[2020-12-15 17:32:34.477|1|data-api|3|DEBUG] [UsageTrackingService]Sending event RequestReceived to usage tracking service
[2020-12-15 17:32:34.482|1|data-api|3|DEBUG] [ProductInsight]Token: ****************************9e5a - Event: RequestReceived - Attributes: {"AppKey":"************************************f26e","EndPoint":"DataGrid_StandardAsync","API":"Eikon Data APIs"}
[2020-12-15 17:32:34.489|1|data-api|3|DEBUG] Received new usage tracking request for event RequestReceived
[2020-12-15 17:32:34.495|1|data-api|3|DEBUG] Sending usage tracking payload {"Target":"/UsageTracking","RequestType":"send","Method":"EventRawData","MethodParam":{"EventName":"RequestReceived","EventProperties":"{\"AppKey\":\"43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e\",\"EndPoint\":\"DataGrid_StandardAsync\",\"API\":\"Eikon Data APIs\"}","ProjectToken":"881d83de3bf9490988d03e2250149e5a"}} on EikonBus channel /UsageTracking
[2020-12-15 17:32:34.502|1|data-api|3|DEBUG] [SettingsAggregatorService]Saving setting value - Name: APIPROXY.THROTTLING.STORE, Value: {"dailyRequest":"[[\"127.0.0.1\",[[1607990400000,1],[1607904000000,0]]]]","dailyVolume":"[]"}
[2020-12-15 17:32:34.510|1|data-api|3|DEBUG] [SettingsAggregatorService]Saving setting value - Name: APIPROXY.THROTTLING.STORE, Value: {"dailyRequest":"[[\"127.0.0.1\",[[1607990400000,1],[1607904000000,0]]]]","dailyVolume":"[]"}
[2020-12-15 17:32:40.206|1|data-api|0|ERROR] [UdfClientExceptionLoggerDecorator]An error occured while processing the request: connect ETIMEDOUT 159.220.1.19:443
[2020-12-15 17:32:40.218|1|data-api|0|ERROR] connect ETIMEDOUT 159.220.1.19:443
[2020-12-15 17:32:40.226|1|data-api|0|ERROR] Error: connect ETIMEDOUT 159.220.1.19:443
at Object.exports._errnoException (util.js:1022:11)
at exports._exceptionWithHostPort (util.js:1045:20)
at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1087:14)
[2020-12-15 17:32:40.238|1|data-api|0|ERROR] [HandshakeApplicationKeyCheckMiddleware]An error occured while checking application key during handshake
[2020-12-15 17:32:40.246|1|data-api|0|ERROR] connect ETIMEDOUT 159.220.1.19:443
[2020-12-15 17:32:40.253|1|data-api|0|ERROR] Error: connect ETIMEDOUT 159.220.1.19:443
at Object.exports._errnoException (util.js:1022:11)
at exports._exceptionWithHostPort (util.js:1045:20)
at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1087:14)
[2020-12-15 17:32:55.520|1|data-api|0|ERROR] [UdfClientExceptionLoggerDecorator]An error occured while processing the request: connect ETIMEDOUT 159.220.1.19:443
[2020-12-15 17:32:55.526|1|data-api|0|ERROR] connect ETIMEDOUT 159.220.1.19:443
[2020-12-15 17:32:55.536|1|data-api|0|ERROR] Error: connect ETIMEDOUT 159.220.1.19:443
at Object.exports._errnoException (util.js:1022:11)
at exports._exceptionWithHostPort (util.js:1045:20)
at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1087:14)
[2020-12-15 17:32:55.545|1|data-api|3|DEBUG] [Throttling][Request #10db02aa-6a81-42ec-99b2-d4aaefbf04d5] [DVBT] Limit: 5000000.00 KB - Step: 0.00 KB - Sum: 0.00 KB - Remaining: 5000000.00 KB - Request rejected: false
[2020-12-15 17:32:55.551|1|data-api|3|DEBUG] [Throttling][Request #10db02aa-6a81-42ec-99b2-d4aaefbf04d5] [VBT] Limit: 50000.00 KB - Step: 0.00 KB - Sum: 0.00 KB - Remaining: 50000.00 KB - Request rejected: false
[2020-12-15 17:32:55.558|1|data-api|3|DEBUG] [SettingsAggregatorService]Saving setting value - Name: APIPROXY.THROTTLING.STORE, Value: {"dailyRequest":"[[\"127.0.0.1\",[[1607990400000,1],[1607904000000,0]]]]","dailyVolume":"[]"}
[2020-12-15 17:32:55.566|1|data-api|3|DEBUG] [SettingsAggregatorService]Saving setting value - Name: APIPROXY.THROTTLING.STORE, Value: {"dailyRequest":"[[\"127.0.0.1\",[[1607990400000,0],[1607904000000,0]]]]","dailyVolume":"[]"}

pf · December 2020

https://community.developers.refinitiv.com/discussion/comment/70632#Comment_70632

That's the APIProxy log file we need to investigate in Eikon API Proxy.

About http log in python, you have to place following code before import eikon:

import os
os.environ['HTTPCORE_LOG_LEVEL']='trace'
os.environ['HTTPX_LOG_LEVEL']='trace'
...
import eikon as ek

Then, you should have additional output (but these traces arn't inlog file):

[2020-12-15 20:24:06,259] - [INFO] - [session.desktop.1] - [59088] | MainThread
Try to handshake on url http://127.0.0.1:9060/api/handshake...
DEBUG [2020-12-15 20:24:06] httpx._client - HTTP Request: GET http://127.0.0.1:9060/api/status "HTTP/1.1 200 OK"
TRACE [2020-12-15 20:24:06] httpcore._async.http11 - receive_event=Data(<49 bytes>)
TRACE [2020-12-15 20:24:06] httpcore._async.http11 - receive_event=EndOfMessage(headers=[])
...

xuewei.zhu · December 2020

https://community.developers.refinitiv.com/discussion/comment/70634#Comment_70634

Still nothing about TRACE shows up. I am using spyder to complie the python, not sure if it matters.

pf · December 2020

https://community.developers.refinitiv.com/discussion/comment/70645#Comment_70645

If you're using Spyder, the simplest way is to add HTTPCORE_LOG_LEVEL and HTTPX_LOG_LEVEL in Tools\Current user environment variables...

Example for HTTPX_LOG_LEVEL :

Once tests will be done , don’t forget to remove these keys (reopen Current user environment variables... then remove it).

xuewei.zhu · December 2020

https://community.developers.refinitiv.com/discussion/comment/70637#Comment_70637

the output in console remain the same as before.

2020-12-15 22:02:05,378 P[18464] [MainThread 14864] Reset a Desktop session with new app_key
2020-12-15 22:02:05,382 P[18464] [MainThread 14864] Unlock login_event for streaming session 0 due to stop streaming call
2020-12-15 22:02:05,387 P[18464] [MainThread 14864] Send GET request to http://127.0.0.1:9060/api/status to detect API Proxy...
2020-12-15 22:02:05,391 P[18464] [MainThread 14864] Request to http://127.0.0.1:9060/api/status
        headers = {'x-tr-applicationid': '43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e'}
        params = None
2020-12-15 22:02:05,468 P[18464] [MainThread 14864] HTTP request response 200: {"statusCode":"ST_PROXY_READY","version":"2.6.4"}
2020-12-15 22:02:05,471 P[18464] [MainThread 14864] Checking port 9060 response : 200 - {"statusCode":"ST_PROXY_READY","version":"2.6.4"}
2020-12-15 22:02:05,478 P[18464] [MainThread 14864] Port 9060 was retrieved from .portInUse file
2020-12-15 22:02:05,482 P[18464] [MainThread 14864] Try to handshake on url http://127.0.0.1:9060/api/handshake...
2020-12-15 22:02:05,487 P[18464] [MainThread 14864] Request to http://127.0.0.1:9060/api/handshake
        headers = {'Content-Type': 'application/json', 'x-tr-applicationid': '43fcf8daae5440c891dbcdcda91b5f8c7c8bf26e'}
        params = None

Should the TRACE appear in the Log file or console?

pf · December 2020

https://community.developers.refinitiv.com/discussion/comment/70649#Comment_70649

Strange...

To progress and check basic info, could you add following lines to verify if env variables are taken in account, check all lib versions and test if default internal http request timeout is too short:

import os
print(os.environ['HTTPX_LOG_LEVEL'])
print(os.environ['HTTPCORE_LOG_LEVEL'])

import eikon as ek 
import httpx 
import nest_asyncio

print(ek.__version__) 
print(httpx.__version__)
print(nest_asyncio.__version__)
ek.set_timeout(60)
ek.set_log_level(1)
ek.set_app_key(APP_KEY)

xuewei.zhu · December 2020

https://community.developers.refinitiv.com/discussion/comment/70660#Comment_70660

trace
trace
1.1.8
0.14.3
AttributeError: module 'nest_asyncio' has no attribute '__version__'

but if I do pip show nest_asyncio

Name: nest-asyncio
Version: 1.3.3
Summary: Patch asyncio to allow nested event loops
Home-page: https://github.com/erdewit/nest_asyncio
Author: Ewald R. de Wit
Author-email: ewald.de.wit@gmail.com
License: BSD
Location: c:\users\xuewe614\appdata\roaming\python\python37\site-packages
Requires: 
Required-by: eikon
Note: you may need to restart the kernel to use updated packages.

The output in console is still the same after setting the time out

Jirapongse · December 2020

@xuewei.zhu

I suspect that it may relate to this error.

{"code":500,"message":"connect ETIMEDOUT 159.220.1.19:443","statusMessage":"Internal Server Error"}

159.220.1.19 is emea1.apps.cp.thomsonreuters.com.

You may check that your machine can connect to that server by using telnet command

You can use https://www.putty.org/ to connect to that server.

If it can connect to that server, you should see a blank screen.

xuewei.zhu · December 2020

https://community.developers.refinitiv.com/discussion/comment/70683#Comment_70683

jirapongse.phuriphanvichai Hi, I am able to see a blank screen with a green bar at the top left corner. However, it pops an error window in a minutes.

Jirapongse · December 2020

https://community.developers.refinitiv.com/discussion/comment/70686#Comment_70686

@xuewei.zhu

If you can type something on that widows, it means that your machine can connect to the server and it is not a firewall issue.

Do you still see this error?

Error code 500 | Server Error: {"code":500,"message":"connect ETIMEDOUT 159.220.1.19:443","statusMessage":"Internal Server Error"}

xuewei.zhu · December 2020

https://community.developers.refinitiv.com/discussion/comment/70713#Comment_70713

I can't type in anything. I assume it's a connection error.

I am using the company network that I used a proxy to install eikon python api. Could this be an issue?

Jirapongse · December 2020

https://community.developers.refinitiv.com/discussion/comment/70686#Comment_70686

@xuewei.zhu

My understanding is wrong. Typing anything in the putty doesn't mean that it can connect to the server. However, the error "Connection timed out" shows that the machine is unable to connect to the server (159.220.1.19). You may test with the ping command.

If you still see this error (ETIMEDOUT 159.220.1.19:443) in the log, it could relate to this issue. You may contact your local IT team to verify if the firewall allows your machine to connect to 159.220.1.19:443 (emea1.apps.cp.thomsonreuters.com).

wasin.w · December 2020

https://community.developers.refinitiv.com/discussion/comment/70794#Comment_70794

Hello @xuewei.zhu

Did you have a chance to test the ping command that suggested by @Jirapongse?

xuewei.zhu · December 2020

https://community.developers.refinitiv.com/discussion/comment/71204#Comment_71204

I am not able to get a reply from 159.220.1.19 using Ping, however, I am able to access the website emea1.apps.cp.thomsonreuters.com. I have contact my local IT team to see if the firewall doesn't allow me to connect (I was able to connect though)

Error code 500| internet error

Best Answer

Answers

Categories

EXPLORE OUR SITES

Error code 500| internet error

Best Answer

Answers

Categories