Pervasive PSQL- App Server acts differently than developer

[Chuck.Amata]

Finally was able to get JDBC connection to Parvasive 11.x database to work in the developer and to connect on the application server. However, while solutions run on the developer, they do not on the server for some reason.

Can launch test solution connecting to pervasive 11.x database through JDBC in web and smart client on developer but the same solution will not run on the application server. The test solution is very simple, contains 1 form that just connects to 3 columns on one table in the database.

Both developer and application server are using identical database connection settings and have the same files in the JDBC directory.

Created a similar test solution using a postgreSQL database (same database server as the repository_server database) and it runs fine on the application server (both web client and smart client).

It appears that there is some sort of difference when the application server tries to open the pervasive database from the developer (both are connected to the same pervasive database).

Note that the developer is running on a different PC (OS X and WIN8 both work) than the application server (App Server running windows 2012).

I shutdown the server, cleared both logs, restarted the server, turned on tracing, and then attempted to launch the web-client. The contents of both logs are attached below. As you can see, the service log has no new information since server startup and looks normal. The servoy_log has some informational messages that seem to occur when the server first connects to the pervasive database but no errors. When the web client attempts to launch, there are a bunch of info messages as it attempts to connect the solution to the database, but no errors.

If anyone has additional ideas on how to debug this, it would be appreciated.

============
SERVOY_LOG: (on the application server)
============
These info/warning messages generate when the application server starts:

2014-11-05 17:07:34,625 WARN [pool-1-thread-1] org.hibernate.dialect.resolver.AbstractDialectResolver - Unable to query java.sql.DatabaseMetaData : Function not implemented [ ]
2014-11-05 17:07:46,939 WARN [pool-1-thread-1] com.servoy.j2db.server.Za.Zo - Column 'IS_NOTE_NOTE' from table 'ISANOTES' is char(0) – this is not supported when creating tables - it will be altered when exporting/importing solutions [ ]
2014-11-05 17:07:51,048 WARN [pool-1-thread-1] com.servoy.j2db.server.Za.Zo - Column 'IS_LNK_NOTE' from table 'ISLINKS' is char(0) – this is not supported when creating tables - it will be altered when exporting/importing solutions [ ]
2014-11-05 17:07:51,470 WARN [pool-1-thread-1] com.servoy.j2db.server.Za.Zo - Column 'IS_NOTE_NOTE' from table 'ISNOTES' is char(0) – this is not supported when creating tables - it will be altered when exporting/importing solutions [ ]
2014-11-05 17:07:52,220 WARN [pool-1-thread-1] com.servoy.j2db.server.Za.Zo - Column 'IS_REM_MEMO' from table 'ISREMIND' is char(0) – this is not supported when creating tables - it will be altered when exporting/importing solutions [ ]
2014-11-05 17:07:52,658 WARN [pool-1-thread-1] com.servoy.j2db.server.Za.Zo - Column 'IS_SERR_DOF' from table 'ISSERR' is char(0) – this is not supported when creating tables - it will be altered when exporting/importing solutions [ ]
2014-11-05 17:07:52,658 WARN [pool-1-thread-1] com.servoy.j2db.server.Za.Zo - Column 'IS_SERR_DIAG' from table 'ISSERR' is char(0) – this is not supported when creating tables - it will be altered when exporting/importing solutions [ ]
2014-11-05 17:07:52,658 WARN [pool-1-thread-1] com.servoy.j2db.server.Za.Zo - Column 'IS_SERR_REWORK' from table 'ISSERR' is char(0) – this is not supported when creating tables - it will be altered when exporting/importing solutions [ ]
2014-11-05 17:07:53,080 WARN [pool-1-thread-1] com.servoy.j2db.server.Za.Zo - Column 'IS_SPC_NOTES' from table 'ISSPC' is char(0) – this is not supported when creating tables - it will be altered when exporting/importing solutions [ ]

Debug tracing turned on just before solution is launched. These are the messages that generate. There does not appear to be any errors in the log but both smart and web client indicate the solution cannot be loaded

2014-11-05 17:09:49,298 INFO [http-8090-1] com.servoy.j2db.util.Debug - sql select CUSTOM ELEMENT select user_uid,user_password from servoy_users where user_name=? ([Administrator]) [ ]
2014-11-05 17:09:49,298 INFO [http-8090-1] com.servoy.j2db.util.Debug - Analysing filters null [ ]
2014-11-05 17:09:49,298 INFO [http-8090-1] com.servoy.j2db.util.Debug - CUSTOM ELEMENT select user_uid,user_password from servoy_users where user_name=? ([Administrator]) FILTER null [ ]
2014-11-05 17:09:49,298 INFO [http-8090-1] com.servoy.j2db.util.Debug - QuerySet
{ prepares = <null>, select = select user_uid,user_password from servoy_users where user_name=? limit ? [[Administrator,2]] <0^2>, cleanups = <null> } [ ]
2014-11-05 17:09:49,298 INFO [http-8090-1] com.servoy.j2db.util.Debug - used sql select user_uid,user_password from servoy_users where user_name=? limit ? questiondata.length 2 [ ]
2014-11-05 17:09:49,298 INFO [http-8090-1] com.servoy.j2db.util.Debug - questiondata[0] = 'Administrator' ,type: java.lang.String [ ]
2014-11-05 17:09:49,298 INFO [http-8090-1] com.servoy.j2db.util.Debug - questiondata[1] = 2 ,type: java.lang.Integer [ ]
2014-11-05 17:09:49,314 INFO [http-8090-1] com.servoy.j2db.util.Debug - sql select CUSTOM ELEMENT select user_uid, user_name, user_password from servoy_users su, servoy_user_groups sug, servoy_groups sg where sg.group_name = ? and sg.group_id = sug.group_id and sug.user_id = su.user_id ([Administrators]) [ ]
2014-11-05 17:09:49,314 INFO [http-8090-1] com.servoy.j2db.util.Debug - Analysing filters null [ ]
2014-11-05 17:09:49,314 INFO [http-8090-1] com.servoy.j2db.util.Debug - CUSTOM ELEMENT select user_uid, user_name, user_password from servoy_users su, servoy_user_groups sug, servoy_groups sg where sg.group_name = ? and sg.group_id = sug.group_id and sug.user_id = su.user_id ([Administrators]) FILTER null [ ]
2014-11-05 17:09:49,314 INFO [http-8090-1] com.servoy.j2db.util.Debug - QuerySet { prepares = <null>, select = select user_uid, user_name, user_password from servoy_users su, servoy_user_groups sug, servoy_groups sg where sg.group_name = ? and sg.group_id = sug.group_id and sug.user_id = su.user_id [[Administrators]] <0^-1>, cleanups = <null> } [ ]
2014-11-05 17:09:49,314 INFO [http-8090-1] com.servoy.j2db.util.Debug - used sql select user_uid, user_name, user_password from servoy_users su, servoy_user_groups sug, servoy_groups sg where sg.group_name = ? and sg.group_id = sug.group_id and sug.user_id = su.user_id questiondata.length 1 [ ]
2014-11-05 17:09:49,314 INFO [http-8090-1] com.servoy.j2db.util.Debug - questiondata[0] = 'Administrators' ,type: java.lang.String [ ]
2014-11-05 17:10:33,952 INFO [http-8090-1] com.servoy.j2db.util.Debug - sql select CUSTOM ELEMENT select user_uid,user_password from servoy_users where user_name=? ([Administrator]) [ ]
2014-11-05 17:10:33,952 INFO [http-8090-1] com.servoy.j2db.util.Debug - Analysing filters null [ ]
2014-11-05 17:10:33,952 INFO [http-8090-1] com.servoy.j2db.util.Debug - CUSTOM ELEMENT select user_uid,user_password from servoy_users where user_name=? ([Administrator]) FILTER null [ ]
2014-11-05 17:10:33,952 INFO [http-8090-1] com.servoy.j2db.util.Debug - QuerySet { prepares = <null>, select = select user_uid,user_password from servoy_users where user_name=? limit ? [[Administrator,2]] <0^2>, cleanups = <null> }
[ ]
2014-11-05 17:10:33,952 INFO [http-8090-1] com.servoy.j2db.util.Debug - used sql select user_uid,user_password from servoy_users where user_name=? limit ? questiondata.length 2 [ ]
2014-11-05 17:10:33,952 INFO [http-8090-1] com.servoy.j2db.util.Debug - questiondata[0] = 'Administrator' ,type: java.lang.String [ ]
2014-11-05 17:10:33,952 INFO [http-8090-1] com.servoy.j2db.util.Debug - questiondata[1] = 2 ,type: java.lang.Integer [ ]
2014-11-05 17:10:33,968 INFO [http-8090-1] com.servoy.j2db.util.Debug - sql select CUSTOM ELEMENT select user_uid, user_name, user_password from servoy_users su, servoy_user_groups sug, servoy_groups sg where sg.group_name = ? and sg.group_id = sug.group_id and sug.user_id = su.user_id ([Administrators]) [ ]
2014-11-05 17:10:33,968 INFO [http-8090-1] com.servoy.j2db.util.Debug - Analysing filters null [ ]
2014-11-05 17:10:33,968 INFO [http-8090-1] com.servoy.j2db.util.Debug - CUSTOM ELEMENT select user_uid, user_name, user_password from servoy_users su, servoy_user_groups sug, servoy_groups sg where sg.group_name = ? and sg.group_id = sug.group_id and sug.user_id = su.user_id ([Administrators]) FILTER null [ ]
2014-11-05 17:10:33,968 INFO [http-8090-1] com.servoy.j2db.util.Debug - QuerySet
{ prepares = <null>, select = select user_uid, user_name, user_password from servoy_users su, servoy_user_groups sug, servoy_groups sg where sg.group_name = ? and sg.group_id = sug.group_id and sug.user_id = su.user_id [[Administrators]] <0^-1>, cleanups = <null> } [ ]
2014-11-05 17:10:33,968 INFO [http-8090-1] com.servoy.j2db.util.Debug - used sql select user_uid, user_name, user_password from servoy_users su, servoy_user_groups sug, servoy_groups sg where sg.group_name = ? and sg.group_id = sug.group_id and sug.user_id = su.user_id questiondata.length 1 [ ]
2014-11-05 17:10:33,968 INFO [http-8090-1] com.servoy.j2db.util.Debug - questiondata[0] = 'Administrators' ,type: java.lang.String [ ]
2014-11-05 17:10:35,630 INFO [http-8090-1] com.servoy.j2db.util.Debug - sql select CUSTOM ELEMENT select user_uid,user_password from servoy_users where user_name=? ([Administrator]) [ ]
2014-11-05 17:10:35,630 INFO [http-8090-1] com.servoy.j2db.util.Debug - Analysing filters null [ ]
2014-11-05 17:10:35,630 INFO [http-8090-1] com.servoy.j2db.util.Debug - CUSTOM ELEMENT select user_uid,user_password from servoy_users where user_name=? ([Administrator]) FILTER null [ ]
2014-11-05 17:10:35,630 INFO [http-8090-1] com.servoy.j2db.util.Debug - QuerySet { prepares = <null>, select = select user_uid,user_password from servoy_users where user_name=? limit ? [[Administrator,2]] <0^2>, cleanups = <null> } [ ]
2014-11-05 17:10:35,630 INFO [http-8090-1] com.servoy.j2db.util.Debug - used sql select user_uid,user_password from servoy_users where user_name=? limit ? questiondata.length 2 [ ]
2014-11-05 17:10:35,646 INFO [http-8090-1] com.servoy.j2db.util.Debug - questiondata[0] = 'Administrator' ,type: java.lang.String [ ]
2014-11-05 17:10:35,646 INFO [http-8090-1] com.servoy.j2db.util.Debug - questiondata[1] = 2 ,type: java.lang.Integer [ ]
2014-11-05 17:10:35,646 INFO [http-8090-1] com.servoy.j2db.util.Debug - sql select CUSTOM ELEMENT select user_uid, user_name, user_password from servoy_users su, servoy_user_groups sug, servoy_groups sg where sg.group_name = ? and sg.group_id = sug.group_id and sug.user_id = su.user_id ([Administrators]) [ ]
2014-11-05 17:10:35,646 INFO [http-8090-1] com.servoy.j2db.util.Debug - Analysing filters null [ ]
2014-11-05 17:10:35,646 INFO [http-8090-1] com.servoy.j2db.util.Debug - CUSTOM ELEMENT select user_uid, user_name, user_password from servoy_users su, servoy_user_groups sug, servoy_groups sg where sg.group_name = ? and sg.group_id = sug.group_id and sug.user_id = su.user_id ([Administrators]) FILTER null [ ]
2014-11-05 17:10:35,646 INFO [http-8090-1] com.servoy.j2db.util.Debug - QuerySet { prepares = <null>, select = select user_uid, user_name, user_password from servoy_users su, servoy_user_groups sug, servoy_groups sg where sg.group_name = ? and sg.group_id = sug.group_id and sug.user_id = su.user_id [[Administrators]] <0^-1>, cleanups = <null> }
[ ]
2014-11-05 17:10:35,646 INFO [http-8090-1] com.servoy.j2db.util.Debug - used sql select user_uid, user_name, user_password from servoy_users su, servoy_user_groups sug, servoy_groups sg where sg.group_name = ? and sg.group_id = sug.group_id and sug.user_id = su.user_id questiondata.length 1 [ ]
2014-11-05 17:10:35,646 INFO [http-8090-1] com.servoy.j2db.util.Debug - questiondata[0] = 'Administrators' ,type: java.lang.String [ ]
2014-11-05 17:10:35,662 INFO [http-8090-1] com.servoy.j2db.util.Debug - tracing disabled [ ]

=============
.SERVICE_LOG (on the application server)
=============

The service does not appear to be generating any errors

STATUS | wrapper | 2014/11/05 17:07:26 | Launching a JVM...
INFO | jvm 10 | 2014/11/05 17:07:26 | WrapperManager: Initializing...
INFO | jvm 10 | 2014/11/05 17:07:37 | Nov 05, 2014 5:07:37 PM org.apache.coyote.http11.Http11Protocol init
INFO | jvm 10 | 2014/11/05 17:07:37 | INFO: Initializing Coyote HTTP/1.1 on http-8090
INFO | jvm 10 | 2014/11/05 17:07:37 | Nov 05, 2014 5:07:37 PM org.apache.catalina.startup.Catalina load
INFO | jvm 10 | 2014/11/05 17:07:37 | INFO: Initialization processed in 441 ms
INFO | jvm 10 | 2014/11/05 17:07:37 | Nov 05, 2014 5:07:37 PM org.apache.catalina.core.StandardService start
INFO | jvm 10 | 2014/11/05 17:07:37 | INFO: Starting service Catalina
INFO | jvm 10 | 2014/11/05 17:07:37 | Nov 05, 2014 5:07:37 PM org.apache.catalina.core.StandardEngine start
INFO | jvm 10 | 2014/11/05 17:07:37 | INFO: Starting Servlet Engine: Apache Tomcat/6.0.37
INFO | jvm 10 | 2014/11/05 17:07:38 | Nov 05, 2014 5:07:38 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO | jvm 10 | 2014/11/05 17:07:38 | INFO: Deploying web application directory ROOT
INFO | jvm 10 | 2014/11/05 17:07:39 | Nov 05, 2014 5:07:39 PM org.apache.coyote.http11.Http11Protocol start
INFO | jvm 10 | 2014/11/05 17:07:39 | INFO: Starting Coyote HTTP/1.1 on http-8090
INFO | jvm 10 | 2014/11/05 17:07:39 | Nov 05, 2014 5:07:39 PM org.apache.catalina.startup.Catalina start
INFO | jvm 10 | 2014/11/05 17:07:39 | INFO: Server startup in 1771 ms

[rgansevles]

Chuck,

I don't see any errors about not being able to start the solution.
It seems to me the connection to the db from appserver is fine.

You could add trace logging for com.servoy.j2db.server.Za.Zo in servoy.properties, this would list more info about found tables and columns.

Hope this helps,

Rob

[Chuck.Amata]

Servo tech support had us change the java connection to "direct". This corrected the smart client - it works fine now. We still get an error loading solution on the web client with nothing in the logs. Tracing the launch event does not show anything either. Ideas?

[jcompagner]

if the smart client can just load the solution then the webclient also should just be able to load it.
As long as the solution is not marked as "smart client (only)"

if you really get an error loading solution, then i find it very weird that there is not any log showing anything (servoy log or the tomcat log)

[Chuck.Amata]

Jon:

I would agree but that does not seem to be the case. We have two identical solutions - one pointing to postgres database and The other pointing to Pervasive. Both the Web and smart client work fine on the solution that points to the postgres data. Once we changed the java setting to direct connect, the smart client for the pervasive solution works but the web client still generates an error. We attached both logs and also the debug trace - there is nothing there. The only error we get is the "Error loading Solution" dialog on the webpage. The only other log we found in the Servoy Directory (and its sub-directories) is the Local Access Log below:

Local Access Log:
10.6.1.102 - - [16/Jan/2015:14:05:36 -0500] "GET /servoy-webclient/solutions/solution/test HTTP/1.1" 200 3658
10.6.1.102 - - [16/Jan/2015:14:05:36 -0500] "GET /favicon.ico HTTP/1.1" 404 973
10.6.1.102 - - [16/Jan/2015:14:05:36 -0500] "GET /servoy-webclient/solutions/solution/test HTTP/1.1" 200 3731
10.6.1.102 - - [16/Jan/2015:14:05:37 -0500] "GET /servoy-webclient/?x=GVMfO3UsF-fmUMKVSC-m0g HTTP/1.1" 302 -
10.6.1.102 - - [16/Jan/2015:14:05:37 -0500] "GET /servoy-webclient/?x=yAjlIfSrIxNpK9jHdKyFNYS8F-uvxHDC HTTP/1.1" 200 3612
10.6.1.102 - - [16/Jan/2015:14:05:37 -0500] "GET /apple-touch-icon-precomposed.png HTTP/1.1" 404 1015
10.6.1.102 - - [16/Jan/2015:14:05:37 -0500] "GET /apple-touch-icon.png HTTP/1.1" 404 991
10.6.1.102 - - [16/Jan/2015:14:05:37 -0500] "GET /servoy-webclient/solutions/solution/DBAtest HTTP/1.1" 200 1752
10.6.1.102 - - [16/Jan/2015:14:05:42 -0500] "GET /servoy-webclient/solutions/solution/DBAtest HTTP/1.1" 200 1752
10.6.1.102 - - [16/Jan/2015:14:05:43 -0500] "GET /apple-touch-icon-precomposed.png HTTP/1.1" 404 1015
10.6.1.102 - - [16/Jan/2015:14:05:43 -0500] "GET /apple-touch-icon.png HTTP/1.1" 404 991

[jcompagner]

so you are saying that on the same server you run 2 solutions

1 of them always works in smart and webclient
the other one
never works in webclient and for a smart client you have to choose direct connect (so really pointing to that exact same server?)


Direct connection has nothing to do with the database you connect to, that's completely unrelated

are you sue that you have the name exactly right of that solution? because for example if i hit: http://localhost:8080/servoy-webclient/ ... lution/tst on my machine and i don't have "tst" then i exactly get what you get (a complete silent response)

if you just hit http://YOURSERVER/servoy-webclient/

what does it say for the solution you have ? What urls does it generate (maybe you should enable solution browsing if you disabled that)

[Chuck.Amata]

Jon:

We just reloaded the solution, enabled the solution browser, and accessed the web client via the solution browser (vs using a deep link) and the web client is now working when we click the solution browser link. Not sure why the deep link does not work for the solution (using the same format as the other solution that does work) - not important though. Thanks for the suggestion.