127.0.0.1 - - [28/Jun/2021:13:40:32 +0000] "GET /swagger.json HTTP/1.0" 200 27491 "-" "Go-http-client/1.1" 2021-06-28 13:40:35,333 | root | INFO | Scoring Timer is set to 60.0 seconds 2021-06-28 13:40:35,333 studio.core INFO Handling http request - Start: 2021-06-28 13:40:35,334 studio.azureml.designer.serving.dagengine.request_handler INFO | Run: is_classic = False, with_details = False, verbose = True 2021-06-28 13:40:35,334 studio.core INFO | Pre-processing - Start: 2021-06-28 13:40:35,334 studio.core INFO | Pre-processing - End with 0.0001s elapsed. 2021-06-28 13:40:35,334 studio.core INFO | Processing - Start: 2021-06-28 13:40:35,345 studio.core INFO | | Executing node 1: Execute Python Script - Start: 2021-06-28 13:40:35,346 studio.common DEBUG | | | Load schema successfully. 2021-06-28 13:40:35,347 studio.modulehost INFO | | | Return without parsing 2021-06-28 13:40:35,347 studio.modulehost INFO | | | Return without parsing 2021-06-28 13:40:35,347 studio.modulehost INFO | | | Parse str parameter 2021-06-28 13:40:35,347 studio.core INFO | | | ExecutePythonScriptModule.run - Start: 2021-06-28 13:40:35,347 studio.core DEBUG | | | | kwargs: 2021-06-28 13:40:35,347 studio.core DEBUG | | | | | dataset1 = 2021-06-28 13:40:35,347 studio.core DEBUG | | | | | dataset2 = 2021-06-28 13:40:35,347 studio.core DEBUG | | | | | python_stream_reader = # The script MUST contain a function named azureml_main # which is the entry point for this module. ... (omitted 145 lines) ... return dataframe1, 2021-06-28 13:40:35,348 studio.core DEBUG | | | | validated_args: 2021-06-28 13:40:35,348 studio.core DEBUG | | | | | dataset1 = 2021-06-28 13:40:35,348 studio.core DEBUG | | | | | dataset2 = 2021-06-28 13:40:35,348 studio.core DEBUG | | | | | python_stream_reader = # The script MUST contain a function named azureml_main # which is the entry point for this module. ... (omitted 145 lines) ... return dataframe1, 2021-06-28 13:40:35,348 studio.core DEBUG | | | | | bundle_file = None 2021-06-28 13:40:35,348 studio.core DEBUG | | | | | py_lib_version = None 2021-06-28 13:40:35,348 studio.module INFO | | | | Prepare python script 2021-06-28 13:40:35,348 studio.module INFO | | | | Check and extract bundle zip file to path ./Script Bundle 2021-06-28 13:40:35,348 studio.module INFO | | | | Add current temporary directory into sys.path 2021-06-28 13:40:35,349 studio.module INFO | | | | Check and convert DataTable to DataFrame Requirement already satisfied: nltk in /azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages (3.6.2) Requirement already satisfied: joblib in /azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages (from nltk) (0.14.0) Requirement already satisfied: tqdm in /azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages (from nltk) (4.61.0) Requirement already satisfied: click in /azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages (from nltk) (8.0.1) Requirement already satisfied: regex in /azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages (from nltk) (2021.4.4) Requirement already satisfied: importlib-metadata; python_version < "3.8" in /azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages (from click->nltk) (4.4.0) Requirement already satisfied: zipp>=0.5 in /azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages (from importlib-metadata; python_version < "3.8"->click->nltk) (3.4.1) Requirement already satisfied: typing-extensions>=3.6.4; python_version < "3.8" in /azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages (from importlib-metadata; python_version < "3.8"->click->nltk) (3.10.0.0) Importing nltk for tokenizer, stopwords, wordnet, lemmatizer Pip install successful. Now importing. Import successful 2021-06-28 13:40:36,575 studio.core INFO | | | | Execute python script - Start: Input pandas.DataFrame #1: ... 0 ... [1 rows x 3 columns] 2021-06-28 13:40:36,602 studio.core INFO | | | | Execute python script - End with 0.0261s elapsed. 2021-06-28 13:40:36,602 studio.core INFO | | | | Wrap output DataFrame to DataTable - Start: 2021-06-28 13:40:36,604 studio.core INFO | | | | Wrap output DataFrame to DataTable - End with 0.0018s elapsed. 2021-06-28 13:40:36,604 studio.core DEBUG | | | | return: 2021-06-28 13:40:36,604 studio.core DEBUG | | | | | [0] = 2021-06-28 13:40:36,604 studio.core DEBUG | | | | | [1] = 2021-06-28 13:40:36,604 studio.core INFO | | | ExecutePythonScriptModule.run - End with 1.2573s elapsed. 2021-06-28 13:40:36,605 studio.core INFO | | Executing node 1: Execute Python Script - End with 1.2603s elapsed. 2021-06-28 13:40:36,605 studio.core INFO | | Executing node 2: Execute Python Script - Start: 2021-06-28 13:40:36,606 studio.common DEBUG | | | Load schema successfully. 2021-06-28 13:40:36,607 studio.modulehost INFO | | | Return without parsing 2021-06-28 13:40:36,607 studio.modulehost INFO | | | Return without parsing 2021-06-28 13:40:36,608 studio.modulehost INFO | | | Parse str parameter 2021-06-28 13:40:36,608 studio.core INFO | | | ExecutePythonScriptModule.run - Start: 2021-06-28 13:40:36,608 studio.core DEBUG | | | | kwargs: 2021-06-28 13:40:36,608 studio.core DEBUG | | | | | dataset1 = 2021-06-28 13:40:36,608 studio.core DEBUG | | | | | dataset2 = 2021-06-28 13:40:36,608 studio.core DEBUG | | | | | python_stream_reader = # The script MUST contain a function named azureml_main # which is the entry point for this module. ... (omitted 75 lines) ... return dataframe1, 2021-06-28 13:40:36,608 studio.core DEBUG | | | | validated_args: 2021-06-28 13:40:36,608 studio.core DEBUG | | | | | dataset1 = 2021-06-28 13:40:36,608 studio.core DEBUG | | | | | dataset2 = 2021-06-28 13:40:36,608 studio.core DEBUG | | | | | python_stream_reader = # The script MUST contain a function named azureml_main # which is the entry point for this module. ... (omitted 75 lines) ... return dataframe1, 2021-06-28 13:40:36,608 studio.core DEBUG | | | | | bundle_file = None 2021-06-28 13:40:36,608 studio.core DEBUG | | | | | py_lib_version = None 2021-06-28 13:40:36,609 studio.module INFO | | | | Prepare python script 2021-06-28 13:40:36,609 studio.module INFO | | | | Check and extract bundle zip file to path ./Script Bundle 2021-06-28 13:40:36,609 studio.module INFO | | | | Add current temporary directory into sys.path 2021-06-28 13:40:36,609 studio.module INFO | | | | Check and convert DataTable to DataFrame 2021-06-28 13:40:36,610 studio.core INFO | | | | Execute python script - Start: Start my Python logic "None of ['Vocabulary'] are in the columns" 2021-06-28 13:40:36,611 studio.core INFO | | | | Execute python script - End with 0.0002s elapsed. 2021-06-28 13:40:36,611 studio.core INFO | | | | Wrap output DataFrame to DataTable - Start: 2021-06-28 13:40:36,612 studio.core INFO | | | | Wrap output DataFrame to DataTable - End with 0.0016s elapsed. 2021-06-28 13:40:36,613 studio.core DEBUG | | | | return: 2021-06-28 13:40:36,613 studio.core DEBUG | | | | | [0] = 2021-06-28 13:40:36,613 studio.core DEBUG | | | | | [1] = 2021-06-28 13:40:36,613 studio.core INFO | | | ExecutePythonScriptModule.run - End with 0.0053s elapsed. 2021-06-28 13:40:36,613 studio.core INFO | | Executing node 2: Execute Python Script - End with 0.0080s elapsed. 2021-06-28 13:40:36,613 studio.core INFO | | Executing node 3: Assign Data to Clusters - Start: 2021-06-28 13:40:36,614 studio.common DEBUG | | | Load schema successfully. 2021-06-28 13:40:36,615 studio.modulehost INFO | | | Return without parsing 2021-06-28 13:40:36,615 studio.modulehost INFO | | | Return without parsing 2021-06-28 13:40:36,615 studio.modulehost INFO | | | Parse bool parameter 2021-06-28 13:40:36,615 studio.core INFO | | | AssignDataToClustersModule.run - Start: 2021-06-28 13:40:36,615 studio.core DEBUG | | | | kwargs: 2021-06-28 13:40:36,615 studio.core DEBUG | | | | | cluster = 2021-06-28 13:40:36,615 studio.core DEBUG | | | | | data_table = 2021-06-28 13:40:36,615 studio.core DEBUG | | | | | append_or_result_only = True 2021-06-28 13:40:36,616 studio.core DEBUG | | | | validated_args: 2021-06-28 13:40:36,616 studio.core DEBUG | | | | | cluster = 2021-06-28 13:40:36,616 studio.core DEBUG | | | | | data_table = 2021-06-28 13:40:36,616 studio.core DEBUG | | | | | append_or_result_only = True 2021-06-28 13:40:36,616 studio.core INFO | | | AssignDataToClustersModule.run - End with 0.0009s elapsed. 2021-06-28 13:40:36,616 studio.core INFO | | Executing node 3: Assign Data to Clusters - End with 0.0029s elapsed. 2021-06-28 13:40:36,616 studio.core INFO | Processing - End with 1.2824s elapsed. 2021-06-28 13:40:36,616 studio.core INFO Handling http request - End with 1.2829s elapsed. 2021-06-28 13:40:36,617 studio.azureml.designer.serving.dagengine.request_handler ERROR Run: Server internal error is from Module Assign Data to Clusters : Error occurs when executing node 3 with module Assign Data to Clusters. Traceback (most recent call last): File "/azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages/azureml/designer/serving/dagengine/dag.py", line 139, in _execute node_outputs = node.execute(node_global_params) > node_outputs = {'2:Result_Dataset': DataFrameDirectory(meta={'type': 'DataFrameDirectory', 'extension': {}, 'format': 'Parquet', 'data': '_data.parquet'}), '2:Python_Device': DataFrameDirectory(meta={'type': 'DataFrameDirectory', 'extension': {}, 'format': 'Parquet', 'data': '_data.parquet'})} > node_global_params = {} > node = File "/azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages/azureml/designer/serving/dagengine/dag_node.py", line 88, in execute global_parameters=global_params > global_params = {} File "/azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages/azureml/designer/serving/dagengine/module_host.py", line 53, in execute return self._module_host.execute(deepcopy(dynamic_source_dict), deepcopy(global_parameters)) > dynamic_source_dict = {'Dataset': DataFrameDirectory(meta={'type': 'DataFrameDirectory', 'extension': {}, 'format': 'Parquet', 'data': '_data.parquet'})} > global_parameters = {} > self = File "/azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages/azureml/studio/modulehost/deployment_service_module_host.py", line 79, in execute output = self._method(**parameter_values) > self = > parameter_values = {'cluster': , 'data_table': , 'append_or_result_only': True} File "/azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages/azureml/studio/modulehost/module_reflector.py", line 76, in wrapper ret = func(*args, **validated_args) > func = > args = () > validated_args = {'cluster': , 'data_table': , 'append_or_result_only': True} File "/azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages/azureml/studio/modules/ml/score/assign_data_to_clusters/assign_data_to_clusters.py", line 51, in run output_values = AssignDataToClustersModule._assign_data_to_cluster(**input_values) > input_values = {'append_or_result_only': True, 'data_table': , 'cluster': , 'input_values': {...}} File "/azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages/azureml/studio/modules/ml/score/assign_data_to_clusters/assign_data_to_clusters.py", line 68, in _assign_data_to_cluster cls._validate_args(cluster, data_table=data_table) > cluster = > cls = > data_table = File "/azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages/azureml/studio/modules/ml/score/assign_data_to_clusters/assign_data_to_clusters.py", line 63, in _validate_args error_setting.ErrorMapping.throw(error_setting.MissingFeaturesError(str(missing_features))) > missing_features = ['Embedding dim 0', 'Embedding dim 1', 'Embedding dim 2', 'Embedding dim 3', 'Embedding dim 4', 'Embedding dim 5', 'Embedding dim 6', 'Embedding dim 7', 'Embedding dim 8', 'Embedding dim 9', 'Embedding dim 10', 'Embedding dim 11', 'Embedding dim 12',... (omitted 1490 chars) ...g dim 87', 'Embedding dim 88', 'Embedding dim 89', 'Embedding dim 90', 'Embedding dim 91', 'Embedding dim 92', 'Embedding dim 93', 'Embedding dim 94', 'Embedding dim 95', 'Embedding dim 96', 'Embedding dim 97', 'Embedding dim 98', 'Embedding dim 99'] File "/azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages/azureml/studio/common/error.py", line 835, in throw raise err > err = MissingFeaturesError("Features for ['Embedding dim 0', 'Embedding dim 1', 'Embedding dim 2', 'Embedding dim 3', 'Embedding dim 4', 'Embedding dim 5', 'Embedding dim 6', 'Embedding dim 7', 'Embedding dim 8', 'Embedding dim 9', 'Embedding dim 10', 'Emb... (omitted 1555 chars) ... 'Embedding dim 89', 'Embedding dim 90', 'Embedding dim 91', 'Embedding dim 92', 'Embedding dim 93', 'Embedding dim 94', 'Embedding dim 95', 'Embedding dim 96', 'Embedding dim 97', 'Embedding dim 98', 'Embedding dim 99'] required but not provided.",) MissingFeaturesError: Features for ['Embedding dim 0', 'Embedding dim 1', 'Embedding dim 2', 'Embedding dim 3', 'Embedding dim 4', 'Embedding dim 5', 'Embedding dim 6', 'Embedding dim 7', 'Embedding dim 8', 'Embedding dim 9', 'Embedding dim 10', 'Embedding dim 11', 'Embedding dim 12', 'Embedding dim 13', 'Embedding dim 14', 'Embedding dim 15', 'Embedding dim 16', 'Embedding dim 17', 'Embedding dim 18', 'Embedding dim 19', 'Embedding dim 20', 'Embedding dim 21', 'Embedding dim 22', 'Embedding dim 23', 'Embedding dim 24', 'Embedding dim 25', 'Embedding dim 26', 'Embedding dim 27', 'Embedding dim 28', 'Embedding dim 29', 'Embedding dim 30', 'Embedding dim 31', 'Embedding dim 32', 'Embedding dim 33', 'Embedding dim 34', 'Embedding dim 35', 'Embedding dim 36', 'Embedding dim 37', 'Embedding dim 38', 'Embedding dim 39', 'Embedding dim 40', 'Embedding dim 41', 'Embedding dim 42', 'Embedding dim 43', 'Embedding dim 44', 'Embedding dim 45', 'Embedding dim 46', 'Embedding dim 47', 'Embedding dim 48', 'Embedding dim 49', 'Embedding dim 50', 'Embedding dim 51', 'Embedding dim 52', 'Embedding dim 53', 'Embedding dim 54', 'Embedding dim 55', 'Embedding dim 56', 'Embedding dim 57', 'Embedding dim 58', 'Embedding dim 59', 'Embedding dim 60', 'Embedding dim 61', 'Embedding dim 62', 'Embedding dim 63', 'Embedding dim 64', 'Embedding dim 65', 'Embedding dim 66', 'Embedding dim 67', 'Embedding dim 68', 'Embedding dim 69', 'Embedding dim 70', 'Embedding dim 71', 'Embedding dim 72', 'Embedding dim 73', 'Embedding dim 74', 'Embedding dim 75', 'Embedding dim 76', 'Embedding dim 77', 'Embedding dim 78', 'Embedding dim 79', 'Embedding dim 80', 'Embedding dim 81', 'Embedding dim 82', 'Embedding dim 83', 'Embedding dim 84', 'Embedding dim 85', 'Embedding dim 86', 'Embedding dim 87', 'Embedding dim 88', 'Embedding dim 89', 'Embedding dim 90', 'Embedding dim 91', 'Embedding dim 92', 'Embedding dim 93', 'Embedding dim 94', 'Embedding dim 95', 'Embedding dim 96', 'Embedding dim 97', 'Embedding dim 98', 'Embedding dim 99'] required but not provided. The above exception was the direct cause of the following exception: Traceback (most recent call last): File "/azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages/azureml/designer/serving/dagengine/request_handler.py", line 70, in handle_request response = processor.run(raw_data) > raw_data = b'{"Inputs":{"WebServiceInput2":[{"FIELD_1":"VALUE_1","":23,"":31}]}}' > processor = File "/azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages/azureml/designer/serving/dagengine/processor.py", line 24, in run webservice_output, name2schema = self.dag.execute(webservice_input, global_parameters) > webservice_input = {'WebServiceInput2': defaultdict(, {'FIELD_1': ['VALUE_1'], '': [23], '': [31]})} > global_parameters = {} > self = File "/azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages/azureml/designer/serving/dagengine/dag.py", line 171, in execute dynamic_outputs = self._execute(graph_inputs, global_parameters) > graph_inputs = {'1:Dataset1': DataFrameDirectory(meta={'type': 'DataFrameDirectory', 'visualization': [{'type': 'Visualization', 'path': '_data.visualization'}], 'extension': {}, 'format': 'Parquet', 'data': '_data.parquet'})} > global_parameters = {} > self = File "/azureml-envs/azureml_2439cbd4ba441d4c922a33cfeff2215c/lib/python3.6/site-packages/azureml/designer/serving/dagengine/dag.py", line 142, in _execute raise DagNodeExecutionError(node_index, node.module_name) from e > node_index = '3' > node = DagNodeExecutionError: Error occurs when executing node 3 with module Assign Data to Clusters. [nltk_data] Downloading package stopwords to /root/nltk_data... [nltk_data] Package stopwords is already up-to-date! [nltk_data] Downloading package punkt to /root/nltk_data... [nltk_data] Package punkt is already up-to-date! [nltk_data] Downloading package averaged_perceptron_tagger to [nltk_data] /root/nltk_data... [nltk_data] Package averaged_perceptron_tagger is already up-to- [nltk_data] date! [nltk_data] Downloading package wordnet to /root/nltk_data... [nltk_data] Package wordnet is already up-to-date! 2021-06-28 13:40:36,620 | root | INFO | run() output is HTTP Response 2021-06-28 13:40:36,620 | root | INFO | 500 127.0.0.1 - - [28/Jun/2021:13:40:36 +0000] "POST /score?verbose=true HTTP/1.0" 500 5643 "-" "Go-http-client/1.1"