Hi Isomorphic,
I found a bug in v10.0p_2015-10-08/PowerEdition Deployment that was not there in August.
For a includeFrom-field where the base field has a unique-validator, the validator seems to be included as well. This renders 1:n cardinalities to 1:1, as you can't have a parent entry more than once in a child table.
I tried hard to create a testcase (I even "copied" the BuildInDS schema and data to Oracle, as I could not reproduce in HSQLDB), but was not able to do so. But I'm pretty sure you can find the problem from the last code changes in this area.
This is the server log:
Please see the bold SELECT-statement. It contains a join, but should only be about T_PRODUCT.
These are the .ds.xml, if needed:
T_PRODUCT.ds.xml:
T_PRODUCT_CATEGORY.ds.xml:
I attached my test case as files (add employees2 in the DataSourceLoader-string in the html as well!).
Here is the add-log for the testcase:
Please note the "Ignored data for non-existent or included columns: [ReportsToName]". This is missing in the erroneous one, which does two unique-check queries.
I'd think that this one is a pretty severe one.
Best regards
Blama
I found a bug in v10.0p_2015-10-08/PowerEdition Deployment that was not there in August.
For a includeFrom-field where the base field has a unique-validator, the validator seems to be included as well. This renders 1:n cardinalities to 1:1, as you can't have a parent entry more than once in a child table.
I tried hard to create a testcase (I even "copied" the BuildInDS schema and data to Oracle, as I could not reproduce in HSQLDB), but was not able to do so. But I'm pretty sure you can find the problem from the last code changes in this area.
This is the server log:
Code:
=== 2015-10-09 23:09:02,310 [c-29] DEBUG RPCManager - Request #1 (DSRequest) payload: { values:{ AVAILABLE:true, PRODUCTCATEGORY_ID:92, PRODUCTCATEGORY_NAME:"PARENTENTRY_NAME", SHORTNAME:"ChildEntry", NAME:"ChildEntry" }, operationConfig:{ dataSource:"T_PRODUCT", repo:null, operationType:"add", textMatchStyle:"exact" }, componentId:"isc_ProductProductcategoryList_6", appID:"builtinApplication", operation:"T_PRODUCT_add", oldValues:{ AVAILABLE:true, PRODUCTCATEGORY_ID:92, PRODUCTCATEGORY_NAME:"PARENTENTRY_NAME", SHORTNAME:"ChildEntry", NAME:"ChildEntry" }, criteria:{ } } === 2015-10-09 23:09:02,310 [c-29] INFO LMSIDACall - Performing 1 operation(s) === 2015-10-09 23:09:02,311 [c-29] DEBUG AppBase - [builtinApplication.T_PRODUCT_add] No userTypes defined, allowing anyone access to all operations for this application === 2015-10-09 23:09:02,311 [c-29] DEBUG AppBase - [builtinApplication.T_PRODUCT_add] No public zero-argument method named '_T_PRODUCT_add' found, performing generic datasource operation === 2015-10-09 23:09:02,311 [c-29] DEBUG AppBase - [builtinApplication.T_PRODUCT_add, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application === 2015-10-09 23:09:02,311 [c-29] DEBUG AppBase - [builtinApplication.T_PRODUCT_add, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation === 2015-10-09 23:09:02,311 [c-29] INFO SQLDataSource - [builtinApplication.T_PRODUCT_add, builtinApplication.null] Performing fetch operation with outputs: [NAME, ID] criteria: {NAME:"ChildEntry",TENANT_ID:15} values: {NAME:"ChildEntry",TENANT_ID:15} === 2015-10-09 23:09:02,312 [c-29] INFO SQLDataSource - [builtinApplication.T_PRODUCT_add, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause === 2015-10-09 23:09:02,312 [c-29] INFO SQLDataSource - [builtinApplication.T_PRODUCT_add, builtinApplication.null] 688: Executing SQL query on 'Oracle': SELECT T_PRODUCT.ID, T_PRODUCT.NAME FROM T_PRODUCT WHERE (LOWER(T_PRODUCT.NAME)='childentry' AND T_PRODUCT.TENANT_ID=15) === 2015-10-09 23:09:02,312 [c-29] DEBUG SQLConnectionManager - [builtinApplication.T_PRODUCT_add, builtinApplication.null] Borrowed connection '2087315966' === 2015-10-09 23:09:02,312 [c-29] DEBUG SQLTransaction - [builtinApplication.T_PRODUCT_add, builtinApplication.null] Started new Oracle transaction "2087315966" === 2015-10-09 23:09:02,312 [c-29] DEBUG SQLDriver - [builtinApplication.T_PRODUCT_add, builtinApplication.null] About to execute SQL query in 'Oracle' using connection '2087315966' === 2015-10-09 23:09:02,312 [c-29] INFO SQLDriver - [builtinApplication.T_PRODUCT_add, builtinApplication.null] Executing SQL query on 'Oracle': SELECT T_PRODUCT.ID, T_PRODUCT.NAME FROM T_PRODUCT WHERE (LOWER(T_PRODUCT.NAME)='childentry' AND T_PRODUCT.TENANT_ID=15) === 2015-10-09 23:09:02,313 [c-29] INFO DSResponse - [builtinApplication.T_PRODUCT_add, builtinApplication.null] DSResponse: List with 0 items === 2015-10-09 23:09:02,313 [c-29] DEBUG AppBase - [builtinApplication.T_PRODUCT_add, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application === 2015-10-09 23:09:02,313 [c-29] DEBUG AppBase - [builtinApplication.T_PRODUCT_add, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation === 2015-10-09 23:09:02,314 [c-29] INFO SQLDataSource - [builtinApplication.T_PRODUCT_add, builtinApplication.null] Performing fetch operation with outputs: [ID, SHORTNAME] criteria: {SHORTNAME:"ChildEntry",TENANT_ID:15} values: {SHORTNAME:"ChildEntry",TENANT_ID:15} === 2015-10-09 23:09:02,314 [c-29] INFO SQLDataSource - [builtinApplication.T_PRODUCT_add, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause === 2015-10-09 23:09:02,314 [c-29] INFO SQLDataSource - [builtinApplication.T_PRODUCT_add, builtinApplication.null] 688: Executing SQL query on 'Oracle': SELECT T_PRODUCT.ID, T_PRODUCT.SHORTNAME FROM T_PRODUCT WHERE (LOWER(T_PRODUCT.SHORTNAME)='childentry' AND T_PRODUCT.TENANT_ID=15) === 2015-10-09 23:09:02,314 [c-29] DEBUG SQLDriver - [builtinApplication.T_PRODUCT_add, builtinApplication.null] About to execute SQL query in 'Oracle' using connection '2087315966' === 2015-10-09 23:09:02,314 [c-29] INFO SQLDriver - [builtinApplication.T_PRODUCT_add, builtinApplication.null] Executing SQL query on 'Oracle': SELECT T_PRODUCT.ID, T_PRODUCT.SHORTNAME FROM T_PRODUCT WHERE (LOWER(T_PRODUCT.SHORTNAME)='childentry' AND T_PRODUCT.TENANT_ID=15) === 2015-10-09 23:09:02,315 [c-29] INFO DSResponse - [builtinApplication.T_PRODUCT_add, builtinApplication.null] DSResponse: List with 0 items === 2015-10-09 23:09:02,315 [c-29] DEBUG AppBase - [builtinApplication.T_PRODUCT_add, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application === 2015-10-09 23:09:02,315 [c-29] DEBUG AppBase - [builtinApplication.T_PRODUCT_add, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation === 2015-10-09 23:09:02,315 [c-29] INFO SQLDataSource - [builtinApplication.T_PRODUCT_add, builtinApplication.null] Performing fetch operation with outputs: [ID, PRODUCTCATEGORY_NAME] criteria: {PRODUCTCATEGORY_NAME:"PARENTENTRY_NAME",TENANT_ID:15} values: {PRODUCTCATEGORY_NAME:"PARENTENTRY_NAME",TENANT_ID:15} === 2015-10-09 23:09:02,316 [c-29] INFO SQLDataSource - [builtinApplication.T_PRODUCT_add, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause$defaultAnsiJoinClause WHERE $defaultWhereClause === 2015-10-09 23:09:02,316 [c-29] INFO SQLDataSource - [builtinApplication.T_PRODUCT_add, builtinApplication.null] 688: Executing SQL query on 'Oracle': SELECT T_PRODUCT.ID, T_PRODUCTCATEGORY.NAME AS PRODUCTCATEGORY_NAME FROM T_PRODUCT JOIN T_PRODUCTCATEGORY ON T_PRODUCT.PRODUCTCATEGORY_ID = T_PRODUCTCATEGORY.ID WHERE (LOWER(T_PRODUCTCATEGORY.NAME)='parententry_name' AND T_PRODUCT.TENANT_ID=15) === 2015-10-09 23:09:02,316 [c-29] DEBUG SQLDriver - [builtinApplication.T_PRODUCT_add, builtinApplication.null] About to execute SQL query in 'Oracle' using connection '2087315966' === 2015-10-09 23:09:02,316 [c-29] INFO SQLDriver - [builtinApplication.T_PRODUCT_add, builtinApplication.null] Executing SQL query on 'Oracle': [B]SELECT T_PRODUCT.ID, T_PRODUCTCATEGORY.NAME AS PRODUCTCATEGORY_NAME FROM T_PRODUCT JOIN T_PRODUCTCATEGORY ON T_PRODUCT.PRODUCTCATEGORY_ID = T_PRODUCTCATEGORY.ID WHERE (LOWER(T_PRODUCTCATEGORY.NAME)='parententry_name' AND T_PRODUCT.TENANT_ID=15)[/B] === 2015-10-09 23:09:02,317 [c-29] INFO DSResponse - [builtinApplication.T_PRODUCT_add, builtinApplication.null] DSResponse: List with 1 items === 2015-10-09 23:09:02,318 [c-29] DEBUG ValidationContext - [builtinApplication.T_PRODUCT_add] Adding validation errors at path '/T_PRODUCT/PRODUCTCATEGORY_NAME/PRODUCTCATEGORY_NAME': {errorMessage=Der Name wird bereits verwendet!} === 2015-10-09 23:09:02,318 [c-29] INFO Validation - [builtinApplication.T_PRODUCT_add] Validation error: [ { PRODUCTCATEGORY_NAME:{ errorMessage:"Der Name wird bereits verwendet!" } } ] === 2015-10-09 23:09:02,318 [c-29] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8 === 2015-10-09 23:09:02,318 [c-29] DEBUG SQLTransaction - Rolling back Oracle transaction "2087315966" === 2015-10-09 23:09:02,318 [c-29] DEBUG RPCManager - non-DMI response, dropExtraFields: false === 2015-10-09 23:09:02,318 [c-29] DEBUG SQLTransaction - getConnection() found transactional connection for Oracle with hashcode "2087315966" === 2015-10-09 23:09:02,318 [c-29] DEBUG SQLTransaction - Ending Oracle transaction "2087315966" === 2015-10-09 23:09:02,319 [c-29] DEBUG SQLConnectionManager - About to close connection with hashcode "2087315966" === 2015-10-09 23:09:02,319 [c-29] DEBUG SQLTransaction - getConnection() found transactional connection for Oracle (connection is null) === 2015-10-09 23:09:02,319 [c-29] DEBUG SQLTransaction - getConnection() found transactional connection for Oracle (connection is null) === 2015-10-09 23:09:02,319 [c-29] INFO Compression - /***/lms/sc/IDACall: 240 -> 209 bytes
These are the .ds.xml, if needed:
T_PRODUCT.ds.xml:
Code:
<DataSource dbName="Oracle" tableName="T_PRODUCT" ID="T_PRODUCT" serverType="sql" serverConstructor="com.lmscompany.lms.server.LMSSQLDataSource"> <fields> <field primaryKey="true" hidden="true" name="ID" type="sequence" /> <field hidden="true" name="TENANT_ID" type="integer" canEdit="false" /> <field foreignKey="V_USER_CREATED_BY.ID" relatedTableAlias="USER_CREATED_BY" name="CREATED_BY" title="Erstellt von" type="creator" /> <field name="CREATED_AT" title="Erstellt am" type="creatorTimestamp" /> <field foreignKey="V_USER_MODIFIED_BY.ID" relatedTableAlias="USER_MODIFIED_BY" name="MODIFIED_BY" title="Geändert von" type="modifier" /> <field name="MODIFIED_AT" title="Geändert am" type="modifierTimestamp" /> <field name="SHORTNAME" title="Kurzname" length="30" type="text" escapeHTML="true" required="true"> <validators> <validator type="isUnique" errorMessage="Der Kurzname wird bereits verwendet!" /> </validators> </field> <field name="NAME" title="Name" length="60" type="text" escapeHTML="true" required="true"> <validators> <validator type="isUnique" errorMessage="Der Name wird bereits verwendet!" /> </validators> </field> <field name="DESCRIPTION" title="Beschreibung" length="1000" type="text" escapeHTML="true" /> <field foreignKey="T_PRODUCTCATEGORY.ID" name="PRODUCTCATEGORY_ID" title="Produktkategorie" displayField="PRODUCTCATEGORY_NAME" type="integer" /> <field name="PRODUCTCATEGORY_NAME" includeFrom="T_PRODUCTCATEGORY.NAME" title="Produktkategorie" length="60" type="text" escapeHTML="true" canEdit="false" /> <field name="AVAILABLE" title="Verfügbar?" type="boolean" escapeHTML="true" sqlStorageStrategy="singleCharYN" required="true" /> <!-- TENANT_IDs for WHERE-clause generation --> <field name="CREATED_BY_TENANT_ID" includeFrom="V_USER_CREATED_BY.TENANT_ID" hidden="true" /> <field name="MODIFIED_BY_TENANT_ID" includeFrom="V_USER_MODIFIED_BY.TENANT_ID" hidden="true" /> <field name="PRODUCTCATEGORY_TENANT_ID" includeFrom="T_PRODUCTCATEGORY.TENANT_ID" hidden="true" /> </fields> <operationBindings> </operationBindings> </DataSource>
Code:
<DataSource dbName="Oracle" tableName="T_PRODUCTCATEGORY" ID="T_PRODUCTCATEGORY" serverType="sql" serverConstructor="com.lmscompany.lms.server.LMSSQLDataSource"> <fields> <field primaryKey="true" hidden="true" name="ID" type="sequence" /> <field hidden="true" name="TENANT_ID" type="integer" canEdit="false" /> <field foreignKey="V_USER_CREATED_BY.ID" relatedTableAlias="USER_CREATED_BY" name="CREATED_BY" title="Erstellt von" type="creator" /> <field name="CREATED_AT" title="Erstellt am" type="creatorTimestamp" /> <field foreignKey="V_USER_MODIFIED_BY.ID" relatedTableAlias="USER_MODIFIED_BY" name="MODIFIED_BY" title="Geändert von" type="modifier" /> <field name="MODIFIED_AT" title="Geändert am" type="modifierTimestamp" /> <field name="SHORTNAME" title="Kurzname" length="30" type="text" escapeHTML="true" required="true"> <validators> <validator type="isUnique" errorMessage="Der Kurzname wird bereits verwendet!" /> </validators> </field> <field name="NAME" title="Name" length="60" type="text" escapeHTML="true" required="true"> <validators> <validator type="isUnique" errorMessage="Der Name wird bereits verwendet!" /> </validators> </field> <field name="DESCRIPTION" title="Beschreibung" length="1000" type="text" escapeHTML="true" /> </fields> <operationBindings> <operationBinding operationType="add" /> <operationBinding operationType="update" /> </operationBindings> </DataSource>
I attached my test case as files (add employees2 in the DataSourceLoader-string in the html as well!).
Here is the add-log for the testcase:
Code:
=== 2015-10-09 23:36:50,914 [3-31] DEBUG RPCManager - Request #1 (DSRequest) payload: { values:{ Name:"TEST", ReportsTo:4, ReportsToName:"Charles Madigen", Job:"TEST1" }, operationConfig:{ dataSource:"employees", repo:null, operationType:"add", textMatchStyle:"exact" }, componentId:"isc_ListGrid_0", appID:"builtinApplication", operation:"employees_add", oldValues:{ Name:"TEST", ReportsTo:4, ReportsToName:"Charles Madigen", Job:"TEST1" }, criteria:{ } } === 2015-10-09 23:36:50,914 [3-31] INFO IDACall - Performing 1 operation(s) === 2015-10-09 23:36:50,914 [3-31] DEBUG ISCKeyedObjectPool - Borrowing object for 'employees' === 2015-10-09 23:36:50,914 [3-31] DEBUG PoolableDataSourceFactory - Activated DataSource 29 of type 'employees' === 2015-10-09 23:36:50,915 [3-31] DEBUG ISCKeyedObjectPool - Borrowing object for 'employees2' === 2015-10-09 23:36:50,915 [3-31] DEBUG PoolableDataSourceFactory - Activated DataSource 25 of type 'employees2' === 2015-10-09 23:36:50,915 [3-31] DEBUG Relation - Caching instance of toDS 'employees2' in the DSRequest map === 2015-10-09 23:36:50,915 [3-31] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null === 2015-10-09 23:36:50,915 [3-31] DEBUG DeclarativeSecurity - DataSource employees is not in the pre-checked list, processing... === 2015-10-09 23:36:50,916 [3-31] DEBUG AppBase - [builtinApplication.employees_add] No userTypes defined, allowing anyone access to all operations for this application === 2015-10-09 23:36:50,916 [3-31] DEBUG AppBase - [builtinApplication.employees_add] No public zero-argument method named '_employees_add' found, performing generic datasource operation === 2015-10-09 23:36:50,917 [3-31] DEBUG DeclarativeSecurity - [builtinApplication.employees_add] Processing security checks for DataSource null, field null === 2015-10-09 23:36:50,918 [3-31] DEBUG AppBase - [builtinApplication.employees_add, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application === 2015-10-09 23:36:50,918 [3-31] DEBUG AppBase - [builtinApplication.employees_add, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation === 2015-10-09 23:36:50,919 [3-31] INFO SQLDataSource - [builtinApplication.employees_add, builtinApplication.null] Performing fetch operation with outputs: [Name, EmployeeId] criteria: {Name:"TEST"} values: {Name:"TEST"} === 2015-10-09 23:36:50,920 [3-31] INFO SQLDataSource - [builtinApplication.employees_add, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause === 2015-10-09 23:36:50,921 [3-31] INFO SQLDataSource - [builtinApplication.employees_add, builtinApplication.null] 29: Executing SQL query on 'OracleAdmin': SELECT employeeTable.EmployeeId, employeeTable.Name FROM employeeTable WHERE (LOWER(employeeTable.Name)='test') === 2015-10-09 23:36:50,921 [3-31] DEBUG PoolableSQLConnectionFactory - [builtinApplication.employees_add, builtinApplication.null] Executing pingTest 'select 1 from dual' on connection 1265710057 === 2015-10-09 23:36:50,923 [3-31] DEBUG SQLConnectionManager - [builtinApplication.employees_add, builtinApplication.null] Borrowed connection '1265710057' === 2015-10-09 23:36:50,923 [3-31] DEBUG SQLTransaction - [builtinApplication.employees_add, builtinApplication.null] Started new OracleAdmin transaction "1265710057" === 2015-10-09 23:36:50,923 [3-31] DEBUG SQLDriver - [builtinApplication.employees_add, builtinApplication.null] About to execute SQL query in 'OracleAdmin' using connection '1265710057' === 2015-10-09 23:36:50,923 [3-31] INFO SQLDriver - [builtinApplication.employees_add, builtinApplication.null] Executing SQL query on 'OracleAdmin': SELECT employeeTable.EmployeeId, employeeTable.Name FROM employeeTable WHERE (LOWER(employeeTable.Name)='test') === 2015-10-09 23:36:50,930 [3-31] INFO DSResponse - [builtinApplication.employees_add, builtinApplication.null] DSResponse: List with 0 items === 2015-10-09 23:36:50,931 [3-31] DEBUG DSRequest - [builtinApplication.employees_add] freeOnExecute is false for request of type fetch on DataSource employees - not freeing resources! === 2015-10-09 23:36:50,932 [3-31] INFO SQLDataSource - [builtinApplication.employees_add] Performing add operation with criteria: {Name:"TEST",ReportsTo:4,ReportsToName:"Charles Madigen",Job:"TEST1"} values: {Name:"TEST",ReportsTo:4,ReportsToName:"Charles Madigen",Job:"TEST1"} === 2015-10-09 23:36:50,933 [3-31] INFO SQLValuesClause - [builtinApplication.employees_add] [B]Ignored data for non-existent or included columns: [ReportsToName][/B] === 2015-10-09 23:36:50,933 [3-31] DEBUG SQLValuesClause - [builtinApplication.employees_add] Sequences: {EmployeeId=__default} === 2015-10-09 23:36:50,935 [3-31] DEBUG SQLDriver - [builtinApplication.employees_add] About to execute SQL update in 'OracleAdmin' using connection'1265710057' === 2015-10-09 23:36:50,935 [3-31] INFO SQLDriver - [builtinApplication.employees_add] Executing SQL update on 'OracleAdmin': INSERT INTO employeeTable (Job, Name, ReportsTo, EmployeeId) VALUES ('TEST1', 'TEST', 4, employeeTable_EmployeeId.NextVal) === 2015-10-09 23:36:50,939 [3-31] DEBUG SQLDriver - [builtinApplication.employees_add] SequenceMode is not JDBC_DRIVER, skipping search for generated values === 2015-10-09 23:36:50,939 [3-31] DEBUG SQLDataSource - [builtinApplication.employees_add] add operation affected 1 rows === 2015-10-09 23:36:50,942 [3-31] DEBUG SQLDriver - [builtinApplication.employees_add] About to execute SQL query in 'OracleAdmin' using connection '1265710057' === 2015-10-09 23:36:50,942 [3-31] INFO SQLDriver - [builtinApplication.employees_add] Executing SQL query on 'OracleAdmin': SELECT employeeTable_EmployeeId.CurrVal FROM DUAL === 2015-10-09 23:36:50,945 [3-31] INFO SQLDataSource - [builtinApplication.employees_add] primaryKeys: {EmployeeId=1000} === 2015-10-09 23:36:50,946 [3-31] DEBUG ISCKeyedObjectPool - [builtinApplication.employees_add] Borrowing object for 'employees' === 2015-10-09 23:36:50,949 [3-31] DEBUG PoolableDataSourceFactory - [builtinApplication.employees_add] Created DataSource 36 of type 'employees' and assigned it to thread qtp1028027903-31 === 2015-10-09 23:36:50,949 [3-31] DEBUG PoolableDataSourceFactory - [builtinApplication.employees_add] Created DataSource 36 of type 'employees' in the pooling flow === 2015-10-09 23:36:50,949 [3-31] DEBUG PoolableDataSourceFactory - [builtinApplication.employees_add] Activated DataSource 36 of type 'employees' === 2015-10-09 23:36:50,949 [3-31] DEBUG DeclarativeSecurity - [builtinApplication.employees_add] Processing security checks for DataSource null, field null === 2015-10-09 23:36:50,950 [3-31] DEBUG DeclarativeSecurity - [builtinApplication.employees_add] DataSource employees is not in the pre-checked list, processing... === 2015-10-09 23:36:50,950 [3-31] DEBUG DeclarativeSecurity - [builtinApplication.employees_add] Processing security checks for DataSource employees2, field EmployeeId === 2015-10-09 23:36:50,950 [3-31] DEBUG DeclarativeSecurity - [builtinApplication.employees_add] DataSource employees2 is not in the pre-checked list, processing... === 2015-10-09 23:36:50,950 [3-31] DEBUG DeclarativeSecurity - [builtinApplication.employees_add] Processing security checks for DataSource employees2, field Name === 2015-10-09 23:36:50,950 [3-31] DEBUG DeclarativeSecurity - [builtinApplication.employees_add] DataSource employees2 is not in the pre-checked list, processing... === 2015-10-09 23:36:50,951 [3-31] DEBUG AppBase - [builtinApplication.employees_add, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application === 2015-10-09 23:36:50,951 [3-31] DEBUG AppBase - [builtinApplication.employees_add, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation === 2015-10-09 23:36:50,952 [3-31] INFO SQLDataSource - [builtinApplication.employees_add, builtinApplication.null] Performing fetch operation with criteria: {EmployeeId:"1000"} values: {EmployeeId:"1000"} === 2015-10-09 23:36:50,953 [3-31] INFO SQLDataSource - [builtinApplication.employees_add, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause AND $defaultJoinWhereClause === 2015-10-09 23:36:50,954 [3-31] INFO SQLDataSource - [builtinApplication.employees_add, builtinApplication.null] 19: Executing SQL query on 'OracleAdmin': SELECT employeeTable.EmployeeId, employeeTable.Name, employeeTable.ReportsTo, employeeTable.Job, employeeTable.Email, employeeTable.EmployeeType, employeeTable.EmployeeStatus, employeeTable.Salary, employeeTable.OrgUnit, employeeTable.Gender, employeeTable.MaritalStatus, bugHereAsWell.Name AS ReportsToName FROM employeeTable bugHereAsWell, employeeTable WHERE (employeeTable.EmployeeId=1000) AND employeeTable.ReportsTo = bugHereAsWell.EmployeeId === 2015-10-09 23:36:50,954 [3-31] DEBUG SQLDriver - [builtinApplication.employees_add, builtinApplication.null] About to execute SQL query in 'OracleAdmin' using connection '1265710057' === 2015-10-09 23:36:50,954 [3-31] INFO SQLDriver - [builtinApplication.employees_add, builtinApplication.null] Executing SQL query on 'OracleAdmin': SELECT employeeTable.EmployeeId, employeeTable.Name, employeeTable.ReportsTo, employeeTable.Job, employeeTable.Email, employeeTable.EmployeeType, employeeTable.EmployeeStatus, employeeTable.Salary, employeeTable.OrgUnit, employeeTable.Gender, employeeTable.MaritalStatus, bugHereAsWell.Name AS ReportsToName FROM employeeTable bugHereAsWell, employeeTable WHERE (employeeTable.EmployeeId=1000) AND employeeTable.ReportsTo = bugHereAsWell.EmployeeId === 2015-10-09 23:36:50,960 [3-31] INFO DSResponse - [builtinApplication.employees_add, builtinApplication.null] DSResponse: List with 1 items === 2015-10-09 23:36:50,961 [3-31] DEBUG DSRequest - [builtinApplication.employees_add] freeOnExecute is false for request of type fetch on DataSource employees - not freeing resources! === 2015-10-09 23:36:50,961 [3-31] INFO DSResponse - [builtinApplication.employees_add] DSResponse: List with 1 items === 2015-10-09 23:36:50,961 [3-31] DEBUG DSRequest - freeOnExecute is false for request of type add on DataSource employees - not freeing resources! === 2015-10-09 23:36:50,961 [3-31] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8 === 2015-10-09 23:36:50,961 [3-31] DEBUG SQLTransaction - Committing OracleAdmin transaction "1265710057" === 2015-10-09 23:36:50,963 [3-31] DEBUG RPCManager - non-DMI response, dropExtraFields: false === 2015-10-09 23:36:50,964 [3-31] DEBUG SQLTransaction - getConnection() found transactional connection for OracleAdmin with hashcode "1265710057" === 2015-10-09 23:36:50,964 [3-31] DEBUG SQLTransaction - Ending OracleAdmin transaction "1265710057" === 2015-10-09 23:36:50,965 [3-31] DEBUG SQLConnectionManager - About to close connection with hashcode "1265710057" === 2015-10-09 23:36:50,965 [3-31] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 1265710057 === 2015-10-09 23:36:50,966 [3-31] DEBUG PoolableDataSourceFactory - Cleared and passivated DataSource 25 of type 'employees2' === 2015-10-09 23:36:50,966 [3-31] DEBUG PoolableDataSourceFactory - Cleared and passivated DataSource 36 of type 'employees' === 2015-10-09 23:36:50,966 [3-31] DEBUG PoolableDataSourceFactory - Cleared and passivated DataSource 19 of type 'employees' === 2015-10-09 23:36:50,966 [3-31] DEBUG SQLTransaction - getConnection() found transactional connection for OracleAdmin (connection is null) === 2015-10-09 23:36:50,966 [3-31] DEBUG PoolableDataSourceFactory - Cleared and passivated DataSource 29 of type 'employees' === 2015-10-09 23:36:50,967 [3-31] DEBUG DSRequest - Ignoring freeResources call because this is not a primary request! === 2015-10-09 23:36:50,967 [3-31] DEBUG DSRequest - Ignoring freeQueueResources call because this is not a primary request! === 2015-10-09 23:36:50,967 [3-31] INFO Compression - /builtinds/sc/IDACall: 239 -> 196 bytes
I'd think that this one is a pretty severe one.
Best regards
Blama
Comment