[2023/05/18-15:46:25.701] [qtp1589931229-387] [DEBUG] [dku.tracing] - [ct: 1] Start call: /public/api/v1/ping [POST] user=none [2023/05/18-15:46:25.702] [qtp1589931229-387] [DEBUG] [dku.tracing] - [ct: 2] Done call: /public/api/v1/ping [POST] time=2ms user=none [2023/05/18-15:46:25.759] [qtp1589931229-406] [DEBUG] [dku.tracing] - [ct: 0] Start call: /public/api/v1/ping [POST] user=none [2023/05/18-15:46:25.759] [qtp1589931229-406] [DEBUG] [dku.tracing] - [ct: 1] Done call: /public/api/v1/ping [POST] time=1ms user=none [2023/05/18-15:46:25.763] [qtp1589931229-390] [DEBUG] [dku.tracing] - [ct: 0] Start call: /public/api/v1/ping [POST] user=none [2023/05/18-15:46:25.763] [qtp1589931229-390] [DEBUG] [dku.tracing] - [ct: 0] Done call: /public/api/v1/ping [POST] time=0ms user=none [2023/05/18-15:46:25.766] [qtp1589931229-395] [DEBUG] [dku.tracing] - [ct: 0] Start call: /public/api/v1/ping [POST] user=none [2023/05/18-15:46:25.766] [qtp1589931229-395] [DEBUG] [dku.tracing] - [ct: 0] Done call: /public/api/v1/ping [POST] time=0ms user=none [2023/05/18-15:46:30.452] [qtp1589931229-372] [DEBUG] [dku.tracing] - [ct: 0] Start call: /public/api/v1/ping [POST] user=none [2023/05/18-15:46:30.452] [qtp1589931229-372] [DEBUG] [dku.tracing] - [ct: 0] Done call: /public/api/v1/ping [POST] time=0ms user=none [2023/05/18-15:46:30.463] [qtp1589931229-405] [DEBUG] [dku.tracing] - [ct: 1] Start call: /admin/api/services/{serviceId:.+} [DELETE] user=none [serviceId=myservice-qna] [2023/05/18-15:46:30.465] [qtp1589931229-405] [DEBUG] [dku.apinode.auth.service] - [ct: 3] Verify the admin API key [2023/05/18-15:46:30.471] [qtp1589931229-405] [DEBUG] [dku.apinode.auth.service] - [ct: 9] API apiKey configured, verifying the api apiKey [2023/05/18-15:46:30.474] [qtp1589931229-405] [INFO] [dku.lambda.admin.api] - [ct: 12] Disabling the service [2023/05/18-15:46:30.475] [qtp1589931229-405] [INFO] [dku.lambda.dispatcher] - [ct: 13] Disabling service myservice-qna [2023/05/18-15:46:30.489] [qtp1589931229-405] [INFO] [dku.lambda.service.manager] - [ct: 27] Service shutting down (unloading 0 generations) [2023/05/18-15:46:30.489] [qtp1589931229-405] [INFO] [dku.lambda.service.manager] - [ct: 27] Service shutdown complete [2023/05/18-15:46:30.489] [qtp1589931229-405] [INFO] [dku.lambda.admin.api] - [ct: 27] Destroying the service [2023/05/18-15:46:30.498] [qtp1589931229-405] [INFO] [dku.lambda.service.manager] - [ct: 36] Unloading generation myservice-qna343590514464132241 [2023/05/18-15:46:30.803] [qtp1589931229-405] [INFO] [dku.lambda.service.manager] - [ct: 341] Service shutting down (unloading 0 generations) [2023/05/18-15:46:30.803] [qtp1589931229-405] [INFO] [dku.lambda.service.manager] - [ct: 341] Service shutdown complete [2023/05/18-15:46:30.806] [qtp1589931229-405] [DEBUG] [dku.tracing] - [ct: 344] Done call: /admin/api/services/{serviceId:.+} [DELETE] time=344ms user=none [serviceId=myservice-qna] [2023/05/18-15:46:30.815] [qtp1589931229-372] [DEBUG] [dku.tracing] - [ct: 0] Start call: /admin/api/services [POST] user=none [2023/05/18-15:46:30.815] [qtp1589931229-372] [DEBUG] [dku.apinode.auth.service] - [ct: 0] Verify the admin API key [2023/05/18-15:46:30.816] [qtp1589931229-372] [DEBUG] [dku.apinode.auth.service] - [ct: 1] API apiKey configured, verifying the api apiKey [2023/05/18-15:46:30.820] [qtp1589931229-372] [INFO] [dku.lambda.service.manager] - [ct: 5] Creating ServiceManager for myservice-qna [2023/05/18-15:46:30.823] [qtp1589931229-372] [DEBUG] [dku.tracing] - [ct: 8] Done call: /admin/api/services [POST] time=8ms user=none [2023/05/18-15:46:30.831] [qtp1589931229-405] [DEBUG] [dku.tracing] - [ct: 0] Start call: /admin/api/services/{serviceId}/generations/actions/importFromDirectory [POST] user=none [serviceId=myservice-qna] [2023/05/18-15:46:30.832] [qtp1589931229-405] [DEBUG] [dku.apinode.auth.service] - [ct: 1] Verify the admin API key [2023/05/18-15:46:30.832] [qtp1589931229-405] [DEBUG] [dku.apinode.auth.service] - [ct: 1] API apiKey configured, verifying the api apiKey [2023/05/18-15:46:30.832] [qtp1589931229-405] [INFO] [dku.lambda.dispatcher] - [ct: 1] Start to import to service myservice-qna from /Users/private/Library/DataScienceStudio/dss_home/tmp/apinode-devserver-package/myservice-qna8705048009247844349 [2023/05/18-15:46:30.836] [qtp1589931229-405] [INFO] [dku.lambda.dispatcher] - [ct: 5] Importing to /Users/private/Library/DataScienceStudio/dss_home/tmp/apinode-devserver/services/myservice-qna/gens/myservice-qna8705048009247844349 [2023/05/18-15:46:31.607] [qtp1589931229-405] [DEBUG] [dku.tracing] - [ct: 776] Done call: /admin/api/services/{serviceId}/generations/actions/importFromDirectory [POST] time=774ms user=none [serviceId=myservice-qna] [2023/05/18-15:46:31.618] [qtp1589931229-372] [DEBUG] [dku.tracing] - [ct: 0] Start call: /admin/api/services/{serviceId}/actions/switchToNewest [POST] user=none [serviceId=myservice-qna] [2023/05/18-15:46:31.619] [qtp1589931229-372] [DEBUG] [dku.apinode.auth.service] - [ct: 1] Verify the admin API key [2023/05/18-15:46:31.623] [qtp1589931229-372] [DEBUG] [dku.apinode.auth.service] - [ct: 5] API apiKey configured, verifying the api apiKey [2023/05/18-15:46:31.625] [qtp1589931229-372] [INFO] [dku.lambda.dispatcher] - [ct: 7] Asking service myservice-qna to switch to NEWEST generation only [2023/05/18-15:46:31.631] [qtp1589931229-372] [INFO] [dku.lambda.dispatcher] - [ct: 13] Asking service myservice-qna to switch to SINGLE generation myservice-qna8705048009247844349 [2023/05/18-15:46:31.639] [qtp1589931229-372] [INFO] [dku.lambda.dispatcher] - [ct: 21] About to set mapping on service myservice-qna: {"auditMetadata":{},"strategy":"RANDOM","mode":"SINGLE_GENERATION","generation":"myservice-qna8705048009247844349","entries":[{"generation":"myservice-qna8705048009247844349","proba":1.0}]} [2023/05/18-15:46:31.641] [qtp1589931229-372] [INFO] [dku.lambda.service.manager] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 23] Creating ServiceGeneration for myservice-qna [2023/05/18-15:46:31.653] [qtp1589931229-372] [INFO] [dku.lambda.service.manager] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 35] Verifying package version {"generationId":"myservice-qna8705048009247844349","createdOn":1684392390259,"createdBy":"admin","originalProjectKey":"myservice_BOT_DATAIKU","createdWithDSSVersion":"11.4.2","createdWithDSSConfVersion":"11400"} [2023/05/18-15:46:31.654] [qtp1589931229-372] [INFO] [dku.lambda.service.manager] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 36] Loading data resources [2023/05/18-15:46:31.655] [qtp1589931229-372] [INFO] [dku.lambda.dataloadmanager] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 37] Loading data resources for myservice-qna-myservice-qna8705048009247844349 [2023/05/18-15:46:31.660] [qtp1589931229-372] [INFO] [dku.lambda.dataloadmanager] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 42] Existing mapping: {"dataGens":[]} [2023/05/18-15:46:31.662] [qtp1589931229-372] [INFO] [dku.lambda.dataloadmanager] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 44] Updating mapping: {"dataGens":[]} [2023/05/18-15:46:31.665] [qtp1589931229-372] [INFO] [dku.lambda.service.manager] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 47] Loading code environments [2023/05/18-15:46:31.666] [qtp1589931229-372] [INFO] [dku.code-envs] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 48] Importing env PYTHON from /Users/private/Library/DataScienceStudio/dss_home/tmp/apinode-devserver/services/myservice-qna/gens/myservice-qna8705048009247844349/endpoint-ask_question/python-env-desc to /Users/private/Library/DataScienceStudio/dss_home/tmp/apinode-devserver/services/myservice-qna/gens/myservice-qna8705048009247844349/endpoint-ask_question/python-env-live [2023/05/18-15:46:31.668] [qtp1589931229-372] [INFO] [dku.codeenvs.versioncheck] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 49] Getting code env hash for lang=PYTHON folder=/Users/private/Library/DataScienceStudio/dss_home/tmp/apinode-devserver/services/myservice-qna/gens/myservice-qna8705048009247844349/endpoint-ask_question/python-env-desc [2023/05/18-15:46:31.691] [qtp1589931229-372] [INFO] [dku.code-envs] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 73] Hash of the env to import is c285ef5956bdc052a8bdd44144033bb5 [2023/05/18-15:46:31.692] [qtp1589931229-372] [INFO] [dku.code-envs] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 74] Hash cache root exists [2023/05/18-15:46:31.694] [qtp1589931229-372] [INFO] [dku.code-envs] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 76] versionToUse=v1 build=false [2023/05/18-15:46:31.694] [qtp1589931229-372] [INFO] [dku.code-envs] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 76] Linking /Users/private/Library/DataScienceStudio/dss_home/tmp/apinode-devserver/services/myservice-qna/gens/myservice-qna8705048009247844349/endpoint-ask_question/python-env-live -> /Users/private/Library/DataScienceStudio/dss_home/tmp/apinode-devserver/code-envs-cache/myservice-qna/ask_question/python/c285ef5956bdc052a8bdd44144033bb5/v1 [2023/05/18-15:46:31.695] [qtp1589931229-372] [INFO] [dku.lambda.service.manager] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 77] Creating endpoint handler for ask_question [2023/05/18-15:46:31.698] [qtp1589931229-372] [INFO] [dku.lambda.pipelinepool] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 80] Initializing pipelines pool for myservice-qna/ask_question: floor=1 cruise=8 ceil=16 queue=16 timeout=10000 [2023/05/18-15:46:31.699] [qtp1589931229-372] [DEBUG] [dku.lambda.pipelinepool] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 81] acquiring pipeline (myservice-qna/ask_question) pending=0 inFlight=0 free=0 baking=0 [2023/05/18-15:46:31.699] [qtp1589931229-372] [INFO] [dku.lambda.pipelinepool] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 81] Creating a pipeline (myservice-qna/ask_question) [2023/05/18-15:46:31.700] [qtp1589931229-372] [INFO] [dku.block.link] service:myservice-qna gen:myservice-qna8705048009247844349 - Started a socket on port 52218 [2023/05/18-15:46:31.706] [qtp1589931229-372] [INFO] [dku.python.single_command.kernel] service:myservice-qna gen:myservice-qna8705048009247844349 - Starting Python process for kernel python-single-command-kernel [2023/05/18-15:46:31.707] [qtp1589931229-372] [INFO] [dku.security.process] service:myservice-qna gen:myservice-qna8705048009247844349 - Starting process (regular) [2023/05/18-15:46:31.741] [qtp1589931229-372] [INFO] [dku.security.process] service:myservice-qna gen:myservice-qna8705048009247844349 - Process started with pid=74182 [2023/05/18-15:46:33.783] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - /Users/private/Library/DataScienceStudio/dss_home/tmp/apinode-devserver/services/myservice-qna/gens/myservice-qna8705048009247844349/endpoint-ask_question/python-env-live/env/lib/python3.8/site-packages/pkg_resources/__init__.py:121: DeprecationWarning: pkg_resources is deprecated as an API [2023/05/18-15:46:33.784] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - warnings.warn("pkg_resources is deprecated as an API", DeprecationWarning) [2023/05/18-15:46:33.832] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - /Users/private/Library/DataScienceStudio/dss_home/tmp/apinode-devserver/services/myservice-qna/gens/myservice-qna8705048009247844349/endpoint-ask_question/python-env-live/env/lib/python3.8/site-packages/pkg_resources/__init__.py:2870: DeprecationWarning: Deprecated call to `pkg_resources.declare_namespace('mpl_toolkits')`. [2023/05/18-15:46:33.832] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - Implementing implicit namespace packages (as specified in PEP 420) is preferred to `pkg_resources.declare_namespace`. See https://setuptools.pypa.io/en/latest/references/keywords.html#keyword-namespace-packages [2023/05/18-15:46:33.832] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - declare_namespace(pkg) [2023/05/18-15:46:33.872] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - /Users/private/Library/DataScienceStudio/kits/dataiku-dss-11.4.2-osx/python/dataiku/__init__.py:41: DeprecationWarning: Deprecated call to `pkg_resources.declare_namespace('dataiku')`. [2023/05/18-15:46:33.872] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - Implementing implicit namespace packages (as specified in PEP 420) is preferred to `pkg_resources.declare_namespace`. See https://setuptools.pypa.io/en/latest/references/keywords.html#keyword-namespace-packages [2023/05/18-15:46:33.873] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - pkg_resources.declare_namespace(__name__) [2023/05/18-15:46:33.876] [KNL-python-single-command-kernel-out-458] [INFO] [dku.utils] - Installing debugging signal handler [2023/05/18-15:46:33.883] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - 2023-05-18 15:46:33,880 INFO Did not register ScipyCSRSerializer (No module named 'scipy') [2023/05/18-15:46:33.886] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - 2023-05-18 15:46:33,886 INFO Connecting to localhost (127.0.0.1) at port 52218 [2023/05/18-15:46:33.887] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - 2023-05-18 15:46:33,887 INFO Connected to localhost (127.0.0.1) at port 52218 [2023/05/18-15:46:33.889] [qtp1589931229-372] [INFO] [dku.link.secret_protected] service:myservice-qna gen:myservice-qna8705048009247844349 - Connected to kernel [2023/05/18-15:46:33.889] [qtp1589931229-372] [INFO] [dku.block.link.interaction] service:myservice-qna gen:myservice-qna8705048009247844349 - Execute link command respClazz=true respTypeToken=false respIsString=false is=false asyncInputStream=false os=false [2023/05/18-15:46:38.363] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - 2023-05-18 15:46:38,362 ERROR Function user code failed [2023/05/18-15:46:38.363] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - Traceback (most recent call last): [2023/05/18-15:46:38.363] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - File "/Users/private/Library/DataScienceStudio/kits/dataiku-dss-11.4.2-osx/python/dataiku/apinode/predict/functionserver.py", line 58, in serve [2023/05/18-15:46:38.363] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - loaded_function = LoadedFunction(code_file, function_name, data_folders) [2023/05/18-15:46:38.363] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - File "/Users/private/Library/DataScienceStudio/kits/dataiku-dss-11.4.2-osx/python/dataiku/apinode/predict/functionserver.py", line 31, in __init__ [2023/05/18-15:46:38.363] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - exec(code, ctx, ctx) [2023/05/18-15:46:38.363] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - File "", line 22, in [2023/05/18-15:46:38.363] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - File "/Users/private/Library/DataScienceStudio/kits/dataiku-dss-11.4.2-osx/python/dataiku/__init__.py", line 103, in get_custom_variables [2023/05/18-15:46:38.363] [qtp1589931229-372] [INFO] [dku.block.link.interaction] service:myservice-qna gen:myservice-qna8705048009247844349 - Check result for nullity exceptionIfNull=true result=null [2023/05/18-15:46:38.363] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - "projectKey" : default_project_key() if project_key is None else project_key, [2023/05/18-15:46:38.363] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - File "/Users/private/Library/DataScienceStudio/kits/dataiku-dss-11.4.2-osx/python/dataiku/core/__init__.py", line 9, in default_project_key [2023/05/18-15:46:38.363] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - raise Exception("Default project key is not specified (no DKU_CURRENT_PROJECT_KEY in env)") [2023/05/18-15:46:38.363] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - Exception: Default project key is not specified (no DKU_CURRENT_PROJECT_KEY in env) [2023/05/18-15:46:38.363] [KNL-python-single-command-kernel-err-459] [INFO] [dku.utils] - 2023-05-18 15:46:38,363 INFO Client closed [2023/05/18-15:46:38.867] [qtp1589931229-372] [INFO] [dku.kernels] service:myservice-qna gen:myservice-qna8705048009247844349 - Getting kernel tail [2023/05/18-15:46:38.878] [qtp1589931229-372] [INFO] [dku.kernels] service:myservice-qna gen:myservice-qna8705048009247844349 - Trying to enrich exception: com.dataiku.dip.io.SocketBlockLinkKernelException: Failed to initiate function server : : Default project key is not specified (no DKU_CURRENT_PROJECT_KEY in env) from kernel com.dataiku.lambda.endpoints.pyfunction.PyFunctionKernel@53944bf9 process=com.dataiku.dip.security.process.RegularProcess@265de6 pid=74182 retcode=null [2023/05/18-15:46:38.879] [qtp1589931229-372] [INFO] [dku.block.link] service:myservice-qna gen:myservice-qna8705048009247844349 - Closed socket [2023/05/18-15:46:38.879] [qtp1589931229-372] [INFO] [dku.block.link] service:myservice-qna gen:myservice-qna8705048009247844349 - Closed socket [2023/05/18-15:46:38.879] [qtp1589931229-372] [INFO] [dku.block.link] service:myservice-qna gen:myservice-qna8705048009247844349 - Closed serverSocket [2023/05/18-15:46:38.879] [qtp1589931229-372] [WARN] [dku.kernels] service:myservice-qna gen:myservice-qna8705048009247844349 - Killing kernel python-single-command-kernel [2023/05/18-15:46:38.881] [qtp1589931229-372] [INFO] [dku.utils] service:myservice-qna gen:myservice-qna8705048009247844349 - [ct: 7263] Killing process PID: 74182 [2023/05/18-15:46:38.886] [KNL-python-single-command-kernel-out-458] [DEBUG] [process] - StreamToLine: EOF (stream closed) [2023/05/18-15:46:38.886] [KNL-python-single-command-kernel-err-459] [DEBUG] [process] - StreamToLine: EOF (stream closed) [2023/05/18-15:46:38.889] [KNL-python-single-command-kernel-monitor-457] [INFO] [dku.kernels] - Process done with code 143 [2023/05/18-15:46:38.904] [qtp1589931229-372] [DEBUG] [dku.tracing] - [ct: 7286] Done call: /admin/api/services/{serviceId}/actions/switchToNewest [POST] time=7283ms user=none [serviceId=myservice-qna] thrown=SocketBlockLinkKernelException [2023/05/18-15:46:38.924] [qtp1589931229-372] [ERROR] [dku.lambda.api] - API call '/admin/api/services/myservice-qna/actions/switchToNewest' failed com.dataiku.dip.io.SocketBlockLinkKernelException: Failed to initiate function server : : Default project key is not specified (no DKU_CURRENT_PROJECT_KEY in env) at com.dataiku.dip.io.SocketBlockLinkInteraction.throwExceptionFromPython(SocketBlockLinkInteraction.java:302) at com.dataiku.dip.io.SocketBlockLinkInteraction$AsyncResult.checkException(SocketBlockLinkInteraction.java:215) at com.dataiku.dip.io.SocketBlockLinkInteraction$AsyncResult.get(SocketBlockLinkInteraction.java:190) at com.dataiku.dip.io.ResponderKernelLink$1.call(ResponderKernelLink.java:120) at com.dataiku.dip.io.ResponderKernelLink.execute(ResponderKernelLink.java:83) at com.dataiku.lambda.endpoints.pyfunction.PyFunctionPipeline.(PyFunctionPipeline.java:42) at com.dataiku.lambda.endpoints.pyfunction.PyFunctionEndpointHandler.instantiatePipeline(PyFunctionEndpointHandler.java:54) at com.dataiku.lambda.endpoints.pyfunction.PyFunctionEndpointHandler.instantiatePipeline(PyFunctionEndpointHandler.java:24) at com.dataiku.lambda.endpoints.pool.PipelinePool.acquire(PipelinePool.java:168) at com.dataiku.lambda.endpoints.pool.PipelinePool.init(PipelinePool.java:67) at com.dataiku.lambda.endpoints.pyfunction.PyFunctionEndpointHandler.init(PyFunctionEndpointHandler.java:49) at com.dataiku.lambda.services.ServiceManager.mount(ServiceManager.java:357) at com.dataiku.lambda.services.ServiceManager.mountIfNeeded(ServiceManager.java:404) at com.dataiku.lambda.services.ServicesService.setMapping(ServicesService.java:276) at com.dataiku.lambda.services.ServicesService.switchToSingleGeneration(ServicesService.java:324) at com.dataiku.lambda.services.ServicesService.switchToNewestGeneration(ServicesService.java:306) at com.dataiku.lambda.admin.ServicesAdminController.switchToNewest(ServicesAdminController.java:211) at com.dataiku.lambda.admin.ServicesAdminController$$FastClassBySpringCGLIB$$3c7950d9.invoke() at org.springframework.cglib.proxy.MethodProxy.invoke(MethodProxy.java:218) at org.springframework.aop.framework.CglibAopProxy$CglibMethodInvocation.invokeJoinpoint(CglibAopProxy.java:793) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:163) at org.springframework.aop.framework.CglibAopProxy$CglibMethodInvocation.proceed(CglibAopProxy.java:763) at org.springframework.aop.aspectj.MethodInvocationProceedingJoinPoint.proceed(MethodInvocationProceedingJoinPoint.java:89) at com.dataiku.lambda.LambdaCallTracingAspect.doCall(LambdaCallTracingAspect.java:72) at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethodWithGivenArgs(AbstractAspectJAdvice.java:634) at org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethod(AbstractAspectJAdvice.java:624) at org.springframework.aop.aspectj.AspectJAroundAdvice.invoke(AspectJAroundAdvice.java:72) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:175) at org.springframework.aop.framework.CglibAopProxy$CglibMethodInvocation.proceed(CglibAopProxy.java:763) at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:97) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:186) at org.springframework.aop.framework.CglibAopProxy$CglibMethodInvocation.proceed(CglibAopProxy.java:763) at org.springframework.aop.framework.CglibAopProxy$DynamicAdvisedInterceptor.intercept(CglibAopProxy.java:708) at com.dataiku.lambda.admin.ServicesAdminController$$EnhancerBySpringCGLIB$$7699f99a.switchToNewest() at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:205) at org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:150) at org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:117) at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:895) at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:808) at org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:87) at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:1072) at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:965) at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:1006) at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:909) at javax.servlet.http.HttpServlet.service(HttpServlet.java:707) at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:883) at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:554) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1440) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:505) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1355) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) at org.eclipse.jetty.server.Server.handle(Server.java:516) at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:487) at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:732) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:479) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) at java.lang.Thread.run(Thread.java:750) [2023/05/18-15:46:38.990] [qtp1589931229-406] [DEBUG] [dku.tracing] - [ct: 1] Start call: /public/api/v1/ping [POST] user=none [2023/05/18-15:46:38.991] [qtp1589931229-406] [DEBUG] [dku.tracing] - [ct: 2] Done call: /public/api/v1/ping [POST] time=2ms user=none