For a deeper look into our Eikon Data API, look into:

Overview |  Quickstart |  Documentation |  Downloads |  Tutorials |  Articles

question

Upvotes
Accepted
xuewei.zhu avatar image
5 ●2 ●3 ●6

Error code 500| internet error

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.




eikoneikon-data-apipythonrefinitiv-dataplatform-eikonworkspaceworkspace-data-apierror-500
icon clock
10 |1500

Up to 2 attachments (including images) can be used with a maximum of 512.0 KiB each and 1.0 MiB total.

5 Answers

Upvotes
Accepted
pierre.faurel avatar image
4.3k ●2 ●4 ●5

Hi,

Your issue is similar to :

To complete :

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


icon clock
10 |1500

Up to 2 attachments (including images) can be used with a maximum of 512.0 KiB each and 1.0 MiB total.

Upvotes
xuewei.zhu avatar image
5 ●2 ●3 ●6

@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"}
icon clock
10 |1500

Up to 2 attachments (including images) can be used with a maximum of 512.0 KiB each and 1.0 MiB total.

Upvotes
pierre.faurel avatar image
4.3k ●2 ●4 ●5

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 :

  1. 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

  2. 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>
icon clock
10 |1500

Up to 2 attachments (including images) can be used with a maximum of 512.0 KiB each and 1.0 MiB total.

Upvotes
xuewei.zhu avatar image
5 ●2 ●3 ●6

@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":"[]"}

icon clock
10 |1500

Up to 2 attachments (including images) can be used with a maximum of 512.0 KiB each and 1.0 MiB total.

pierre.faurel avatar image pierre.faurel

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 avatar image xuewei.zhu pierre.faurel
Still nothing about TRACE shows up. I am using spyder to complie the python, not sure if it matters.
pierre.faurel avatar image pierre.faurel xuewei.zhu

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).

1608067915686.png (240.0 KiB)
1608067996814.jpeg (22.9 KiB)
Show more comments
Upvotes
Jirapongse avatar image
62.2k ●174 ●45 ●65

@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.



1608117168604.png (37.5 KiB)
1608117444222.png (10.6 KiB)
icon clock
10 |1500

Up to 2 attachments (including images) can be used with a maximum of 512.0 KiB each and 1.0 MiB total.

xuewei.zhu avatar image xuewei.zhu

@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.


1608120047075.png (9.2 KiB)
Jirapongse avatar image Jirapongse xuewei.zhu

@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"}
1608176262336.png (4.7 KiB)
xuewei.zhu avatar image xuewei.zhu Jirapongse

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 avatar image Jirapongse xuewei.zhu

@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).

1608263325711.png (23.1 KiB)
wasin.w avatar image wasin.w Jirapongse

Hello @xuewei.zhu

Did you have a chance to test the ping command that suggested by @jirapongse.phuriphanvichai?

Show more comments