Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Detected Oracle Driver stuck during Statement initialization #145

Open
DrErique opened this issue Apr 30, 2019 · 45 comments · Fixed by #158
Open

Detected Oracle Driver stuck during Statement initialization #145

DrErique opened this issue Apr 30, 2019 · 45 comments · Fixed by #158

Comments

@DrErique
Copy link

Hello,

I get the error message above regularly after two or three succesfull testings.
Then I have two or three unsuccesful tries with the Oracle Driver stucks, after that in the next try can be succesfull again.
There is no unpredictability if the driver stucks or not.
So we have

30-Apr-2019 15:20:52
30-Apr-2019 15:20:52 C:\Users\vmwareadmin\bamboo-agent-home\xml-data\build-dir\SWITEGIT-WT-JOB1>utplsql run UNITTEST/P6YkF-k4il83_0s@AGTEST -p=UNITTEST.UT_PKG_AUSW_HP_SCHAEDEN -f=UT_JUNIT_REPORTER -o=C:\Users\vmwareadmin\bamboo-agent-home\xml-data\build-dir\SWITEGIT-WT-JOB1\UNIT_TEST_AGTEST_30-04-2019.xml
30-Apr-2019 15:20:52 ###################### utPLSQL cli #######################
30-Apr-2019 15:20:52 # #
30-Apr-2019 15:20:52 # utPLSQL-cli 3.1.6.local #
30-Apr-2019 15:20:52 # utPLSQL-java-api 3.1.6.411 #
30-Apr-2019 15:20:52 # Java-Version: 1.8.0_201 #
30-Apr-2019 15:20:52 # ORACLE_HOME: C:\oracle\product\12.2.0\client_1_x64 #
30-Apr-2019 15:20:52 # NLS_LANG: null #
30-Apr-2019 15:20:52 # #
30-Apr-2019 15:20:52 # Thanks for testing! #
30-Apr-2019 15:20:52 # #
30-Apr-2019 15:20:52 ##########################################################
30-Apr-2019 15:20:52
30-Apr-2019 15:20:53 HikariPool-1 - Driver does not support get/set network timeout for connections. (Nicht unterstützte Funktion: getNetworkTimeout)
30-Apr-2019 15:20:53 Use connection string jdbc:oracle:oci8:/@AGTest
30-Apr-2019 15:20:53 Successfully connected to database. UtPLSQL core: v3.1.2.2134
30-Apr-2019 15:20:53 Oracle-Version: 12.2.0.1.0
30-Apr-2019 15:20:53 Running tests now.
30-Apr-2019 15:20:53 --------------------------------------
30-Apr-2019 15:20:53 TestRunner initialized
30-Apr-2019 15:20:53 Running on utPLSQL v3.1.2.2134
30-Apr-2019 15:20:53 Initializing reporters
30-Apr-2019 15:20:55 Detected Oracle driver stuck during Statement initialization

I tried to experiment with different Clients and Java Versions but as it is said here in the other issues, there is not much scope for experiments while we have to use
Oracle Client 12.2 with jdbc 8 for the 3.1.6 Version of the utPLSQL-cli.
Since it's actually working and it's also the correct configuration I wonder what I can do to become more stability in the testings.
Perhaps I can send you a debug file of a failed test or you can suggest some better working versions of the ojdbc8.jar or the orai18n.jar ( both from 22.02.2019 ) ?

Greetings from Hannover, Germany !

@pesse
Copy link
Member

pesse commented Apr 30, 2019

Hi @DrErique

what happens after the Detected Oracle driver stuck during Statement initialization?
The expected behavior would be that CLI does an automatic retry (and potentially some error stacks are written). Does this happen?
It's a problem we investigate since several months now and our solution (because it is very likely to be a driver bug) was to have an internal watchdog which detects such stuck scenario and does up to 5 retries. In that case the outcome of your tests wouldn't be changed, the only thing that happens is that the previous connection is abandoned and a second one is started.

Also: what would be your expectation how CLI deals with such a scenario?

Cheers,
Samuel

P.S. If you happen to be at ApexConnect in Bonn next week, make sure to say hello :)

@DrErique
Copy link
Author

DrErique commented Apr 30, 2019 via email

@pesse
Copy link
Member

pesse commented May 3, 2019

Hm, this is strange - the Stuck is detected but it's not aborted (for whatever reason).
Can you try a different OJDBC driver?
I use ojdbc8-12.2.0.1.jar currently.

@DrErique
Copy link
Author

DrErique commented May 3, 2019 via email

@DrErique
Copy link
Author

DrErique commented May 8, 2019

Hello Samuel,

Unfortunately that didn't solve the problem.
Do we have any debug option ?

You mentioned the –d Parameter in another issue ?

What about this "HikariPool-1 - Driver does not support get/set network timeout for connections. " report. Perhaps we have a problem with the HikariCP ?

@pesse
Copy link
Member

pesse commented May 9, 2019

Yeah, this line is strange - didn't notice it so far in any of my runs.
Did you use the latest version of cli from the github page? (yes, there's still something wrong with putting the build number into the version automatically)

@DrErique
Copy link
Author

DrErique commented May 9, 2019 via email

@dmpe
Copy link
Contributor

dmpe commented May 10, 2019

Hi,
I am also facing same issue here with Oracle DB 11.2,Java-Version: 1.8.0_212 , latest utPLSQL-cli 3.1.6 and jdbc 6 (the one from oracle site)

Actually I wanted to file a request for allowing to specifying more HikariConfig options, i.e. addDataSourceProperty (e.g. allowPoolSuspension)...maybe it would help slightly to debug the problem ("enterprise configurations")

I should also add that utPLSQL 3.1.2 was working well (as expected) - Hikari update ....

2019-05-10 11:03:22 [main] INFO  com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Starting...
2019-05-10 11:03:22 [main] DEBUG c.z.hikari.util.DriverDataSource - Loaded driver with class name oracle.jdbc.OracleDriver for jdbcUrl=jdbc:oracle:thin:xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
2019-05-10 11:03:23 [main] INFO  com.zaxxer.hikari.pool.PoolBase - HikariPool-1 - Driver does not support get/set network timeout for connections. (oracle.jdbc.driver.T4CConnection.getNetworkTimeout()I)
2019-05-10 11:03:23 [main] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection oracle.jdbc.driver.T4CConnection@2d928643
2019-05-10 11:03:23 [main] INFO  com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Start completed.
2019-05-10 11:03:23 [main] INFO  o.u.c.d.TestedDataSourceProvider - Use connection string jdbc:oracle:thin:****/****@//xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
2019-05-10 11:03:23 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Pool stats (total=1, active=1, idle=0, waiting=0)
2019-05-10 11:03:23 [main] INFO  org.utplsql.cli.RunCommand - Successfully connected to database. UtPLSQL core: v3.1.1.1868
2019-05-10 11:03:23 [main] INFO  org.utplsql.cli.RunCommand - Oracle-Version: 11.2.0.4.0
2019-05-10 11:03:23 [main] DEBUG c.zaxxer.hikari.pool.ProxyConnection - HikariPool-1 - Executed rollback on connection oracle.jdbc.driver.T4CConnection@2d928643 due to dirty commit state on close().
2019-05-10 11:03:23 [main] DEBUG org.utplsql.api.reporter.Reporter - Database-reporter initialized, Type: UT_COVERAGE_HTML_REPORTER, ID: 8885D6BC54DF5E4BE05311C8130A76B7
2019-05-10 11:03:23 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection oracle.jdbc.driver.T4CConnection@483c7db7
2019-05-10 11:03:23 [main] DEBUG org.utplsql.api.reporter.Reporter - Database-reporter initialized, Type: UT_DOCUMENTATION_REPORTER, ID: 8885D6BC54E35E4BE05311C8130A76B7
2019-05-10 11:03:23 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection oracle.jdbc.driver.T4CConnection@46fb8ac0
2019-05-10 11:03:23 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection oracle.jdbc.driver.T4CConnection@42c416c7
2019-05-10 11:03:23 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After adding stats (total=4, active=0, idle=4, waiting=0)
2019-05-10 11:03:25 [pool-1-thread-1] INFO  org.utplsql.cli.RunTestRunnerTask - Running tests now.
2019-05-10 11:03:25 [pool-1-thread-1] INFO  org.utplsql.cli.RunTestRunnerTask - --------------------------------------
2019-05-10 11:03:25 [pool-1-thread-1] INFO  org.utplsql.api.TestRunner - TestRunner initialized
2019-05-10 11:03:25 [pool-1-thread-1] INFO  org.utplsql.api.TestRunner - Running on utPLSQL v3.1.1.1868
2019-05-10 11:03:25 [pool-1-thread-1] INFO  org.utplsql.api.TestRunner - Initializing reporters
2019-05-10 11:03:27 [pool-1-thread-1] ERROR org.utplsql.api.TestRunner - Detected Oracle driver stuck during Statement initialization
2019-05-10 11:03:31 [pool-1-thread-1] DEBUG c.zaxxer.hikari.pool.ProxyConnection - HikariPool-1 - Executed rollback on connection oracle.jdbc.driver.T4CConnection@2d928643 due to dirty commit state on close().
2019-05-10 11:03:53 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Pool stats (total=4, active=2, idle=2, waiting=0)
2019-05-10 11:04:23 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Pool stats (total=4, active=2, idle=2, waiting=0)
2019-05-10 11:04:53 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Pool stats (total=4, active=2, idle=2, waiting=0)
2019-05-10 11:05:23 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Pool stats (total=4, active=2, idle=2, waiting=0)

EDIT: so using jdbc8 --the one for 12c on the database 11.2; instead of jdbc6 for 11.2), error has changed to .... This is also retried 4 times. I wonder if it has really something to do with Hikari, e.g brettwooldridge/HikariCP#198

2019-05-10 12:51:32 [pool-1-thread-1] ERROR org.utplsql.api.TestRunner - Detected Oracle driver stuck during Statement initialization
2019-05-10 12:51:32 [pool-2-thread-1] WARN  c.zaxxer.hikari.pool.ProxyConnection - HikariPool-1 - **Connection oracle.jdbc.driver.T4CConnection@3b088d51 marked as broken because of SQLSTATE(08006)**, ErrorCode(17002)
java.sql.SQLRecoverableException: IO Error: Socket read interrupted
	at oracle.jdbc.driver.T4CCallableStatement.executeForRows(T4CCallableStatement.java:929)
	at oracle.jdbc.driver.OracleStatement.doExecuteWithTimeout(OracleStatement.java:1119)
	at oracle.jdbc.driver.OraclePreparedStatement.executeInternal(OraclePreparedStatement.java:3780)
	at oracle.jdbc.driver.T4CCallableStatement.executeInternal(T4CCallableStatement.java:1300)
	at oracle.jdbc.driver.OraclePreparedStatement.execute(OraclePreparedStatement.java:3887)
	at oracle.jdbc.driver.OracleCallableStatement.execute(OracleCallableStatement.java:4230)
	at oracle.jdbc.driver.OraclePreparedStatementWrapper.execute(OraclePreparedStatementWrapper.java:1079)
	at com.zaxxer.hikari.pool.ProxyPreparedStatement.execute(ProxyPreparedStatement.java:44)
	at com.zaxxer.hikari.pool.HikariProxyCallableStatement.execute(HikariProxyCallableStatement.java)
	at org.utplsql.api.FileMapper.buildFileMappingArray(FileMapper.java:84)
	at org.utplsql.api.FileMapper.buildFileMappingList(FileMapper.java:90)
	at org.utplsql.api.testRunner.AbstractTestRunnerStatement.createStatement(AbstractTestRunnerStatement.java:60)
	at org.utplsql.api.testRunner.AbstractTestRunnerStatement.<init>(AbstractTestRunnerStatement.java:33)
	at org.utplsql.api.testRunner.Pre312TestRunnerStatement.<init>(Pre312TestRunnerStatement.java:17)
	at org.utplsql.api.testRunner.TestRunnerStatementProvider.getCompatibleTestRunnerStatement(TestRunnerStatementProvider.java:37)
	at org.utplsql.api.compatibility.CompatibilityProxy.getTestRunnerStatement(CompatibilityProxy.java:157)
	at org.utplsql.api.TestRunner.lambda$initStatementWithTimeout$0(TestRunner.java:199)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
	at java.lang.Thread.run(Thread.java:748)
Caused by: java.net.SocketTimeoutException: Socket read interrupted
	at oracle.net.nt.TimeoutSocketChannel.read(TimeoutSocketChannel.java:152)
	at oracle.net.ns.NIOHeader.readHeaderBuffer(NIOHeader.java:82)
	at oracle.net.ns.NIOPacket.readFromSocketChannel(NIOPacket.java:139)
	at oracle.net.ns.NIOPacket.readFromSocketChannel(NIOPacket.java:101)
	at oracle.net.ns.NIONSDataChannel.readDataFromSocketChannel(NIONSDataChannel.java:80)
	at oracle.jdbc.driver.T4CMAREngineNIO.prepareForReading(T4CMAREngineNIO.java:98)
	at oracle.jdbc.driver.T4CMAREngineNIO.unmarshalUB1(T4CMAREngineNIO.java:534)
	at oracle.jdbc.driver.T4CTTIfun.receive(T4CTTIfun.java:485)
	at oracle.jdbc.driver.T4CTTIfun.doRPC(T4CTTIfun.java:252)
	at oracle.jdbc.driver.T4C8Oall.doOALL(T4C8Oall.java:612)
	at oracle.jdbc.driver.T4CCallableStatement.doOall8(T4CCallableStatement.java:223)
	at oracle.jdbc.driver.T4CCallableStatement.doOall8(T4CCallableStatement.java:56)
	at oracle.jdbc.driver.T4CCallableStatement.executeForRows(T4CCallableStatement.java:907)
	... 20 common frames omitted
2019-05-10 12:51:32 [pool-1-thread-3] WARN  c.zaxxer.hikari.pool.ProxyConnection - HikariPool-1 - Connection oracle.jdbc.driver.T4CConnection@1fb473a8 marked as broken because of SQLSTATE(08003), ErrorCode(17008)
java.sql.SQLRecoverableException: Closed Connection
	at oracle.jdbc.driver.PhysicalConnection.clearWarnings(PhysicalConnection.java:2573)
	at com.zaxxer.hikari.pool.ProxyConnection.close(ProxyConnection.java:246)
	at org.utplsql.cli.ReporterManager.lambda$startReporterGatherers$0(ReporterManager.java:130)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
	at java.lang.Thread.run(Thread.java:748)
2019-05-10 12:51:32 [pool-1-thread-2] WARN  c.zaxxer.hikari.pool.ProxyConnection - HikariPool-1 - Connection oracle.jdbc.driver.T4CConnection@742dfdaf marked as broken because of SQLSTATE(08003), ErrorCode(17008)
java.sql.SQLRecoverableException: Closed Connection
	at oracle.jdbc.driver.PhysicalConnection.clearWarnings(PhysicalConnection.java:2573)
	at com.zaxxer.hikari.pool.ProxyConnection.close(ProxyConnection.java:246)
	at org.utplsql.cli.ReporterManager.lambda$startReporterGatherers$0(ReporterManager.java:130)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
	at java.lang.Thread.run(Thread.java:748)

@pesse
Copy link
Member

pesse commented May 13, 2019

For we don't necessarily need a connection pool, I'll try to remove HikariCP completely.

@dmpe
Copy link
Contributor

dmpe commented May 13, 2019

ok, that sounds awesome if it can be done. Actually I wanted to build several versions of utplsql-cli with different HakariCP and jdbc drivers to test it out where the problem may be. i would try to report my results on this friday...though do no wait on me, just go ahead.

EDIT:
@pesse ok. so i have just tried 2 recent versions of utPLSQL with different versions of Hakari and jdbc

For utPLSQL 3.1.6 code base:

Hakari 2.7.9 and 3.3.1 did not work

For utPLSQL 3.1.2 code base:

Hakari 2.7.9 and 3.3.1 did not work
what works is only 2.7.2 version with this codebase, with jdbc7.

@guicampos
Copy link

I had a similar issue:

UtPLSQL core: v3.1.6.2735
Oracle Database 12.1.0.2
utPLSQL-cli 3.1.6.local
utPLSQL-java-api 3.1.6.411
Java-Version: 1.8.0_211
ojdbc7.jar

When i first executed the command:

../utPLSQL-cli/bin/utplsql run -d user/passwd@mydb:1521/mysid -source_path=sources -test_path=tests -f=ut_documentation_reporter -c -f=ut_coverage_sonar_reporter -o=coverage.xml -f=ut_sonar_test_reporter -o=test_results.xml --failure-exit-code=0

The command hanged for sometime with

##################### utPLSQL cli ######################
#                                                      #
#   utPLSQL-cli 3.1.6.local                            #
#   utPLSQL-java-api 3.1.6.411                         #
#   Java-Version: 1.8.0_211                            #
#   ORACLE_HOME: /u01/app/oracle/product/12.1.0/db_1   #
#   NLS_LANG: null                                     #
#                                                      #
#   Thanks for testing!                                #
#                                                      #
########################################################

HikariPool-1 - Driver does not support get/set network timeout for connections. (Recurso não suportado)
Use connection string jdbc:oracle:oci8:****/****@mydb:1521/mysid
Successfully connected to database. UtPLSQL core: v3.1.6.2735
Oracle-Version: 12.1.0.2.0
Running tests now.
--------------------------------------
TestRunner initialized
Running on utPLSQL v3.1.6.2735
Initializing reporters
Detected Oracle driver stuck during Statement initialization
WARNING: Caught Oracle stuck during creation of Runner-Statement. Retrying (1)

After a quite long wait time, and every time it retried the warning was reissued

##################### utPLSQL cli ######################
#                                                      #
#   utPLSQL-cli 3.1.6.local                            #
#   utPLSQL-java-api 3.1.6.411                         #
#   Java-Version: 1.8.0_211                            #
#   ORACLE_HOME: /u01/app/oracle/product/12.1.0/db_1   #
#   NLS_LANG: null                                     #
#                                                      #
#   Thanks for testing!                                #
#                                                      #
########################################################

HikariPool-2 - Driver does not support get/set network timeout for connections. (Recurso não suportado)
Use connection string jdbc:oracle:oci8:****/****@mydb:1521/mysid
Successfully connected to database. UtPLSQL core: v3.1.6.2735
Oracle-Version: 12.1.0.2.0
Running tests now.
--------------------------------------
TestRunner initialized
Running on utPLSQL v3.1.6.2735
Initializing reporters
Detected Oracle driver stuck during Statement initialization
WARNING: Caught Oracle stuck during creation of Runner-Statement. Retrying (2)
##################### utPLSQL cli ######################
#                                                      #
#   utPLSQL-cli 3.1.6.local                            #
#   utPLSQL-java-api 3.1.6.411                         #
#   Java-Version: 1.8.0_211                            #
#   ORACLE_HOME: /u01/app/oracle/product/12.1.0/db_1   #
#   NLS_LANG: null                                     #
#                                                      #
#   Thanks for testing!                                #
#                                                      #
########################################################

HikariPool-3 - Driver does not support get/set network timeout for connections. (Recurso não suportado)
Use connection string jdbc:oracle:oci8:****/****@mydb:1521/mysid
Successfully connected to database. UtPLSQL core: v3.1.6.2735
Oracle-Version: 12.1.0.2.0
Running tests now.
--------------------------------------
TestRunner initialized
Running on utPLSQL v3.1.6.2735
Initializing reporters
Detected Oracle driver stuck during Statement initialization
WARNING: Caught Oracle stuck during creation of Runner-Statement. Retrying (3)

after the 4th attempt the test runned smoothly:

##################### utPLSQL cli ######################
#                                                      #
#   utPLSQL-cli 3.1.6.local                            #
#   utPLSQL-java-api 3.1.6.411                         #
#   Java-Version: 1.8.0_211                            #
#   ORACLE_HOME: /u01/app/oracle/product/12.1.0/db_1   #
#   NLS_LANG: null                                     #
#                                                      #
#   Thanks for testing!                                #
#                                                      #
########################################################

HikariPool-4 - Driver does not support get/set network timeout for connections. (Recurso não suportado)
Use connection string jdbc:oracle:oci8:****/****@mydb:1521/mysid
Successfully connected to database. UtPLSQL core: v3.1.6.2735
Oracle-Version: 12.1.0.2.0
Running tests now.
--------------------------------------
TestRunner initialized
Running on utPLSQL v3.1.6.2735
Initializing reporters
Running tests
teste de teste
  resultado final [,046 sec] (FAILED - 1)


Failures:

  1) test_resultado_final
      Actual: 'TESTE NAO OK' (varchar2) was expected to equal: 'Teste OK' (varchar2)
      at "ESMPU.TEST_PKG_TESTE", line 4 ut.expect(pkg_teste.resultado_final).to_equal('Teste OK');


Finished in ,048334 seconds
1 tests, 1 failed, 0 errored, 0 disabled, 0 warning(s)

--------------------------------------
All tests done.

And now there's no more wait time in new executions.

I'm sending my current debug output, maybe it can help solving it.

and Hey! utPLSQL is awesome!
utplsql-cli-log.txt

@pesse pesse mentioned this issue Jun 27, 2019
@pesse pesse reopened this Jun 27, 2019
@pesse
Copy link
Member

pesse commented Jun 27, 2019

Hey @DrErique @dmpe @guicampos
could you try out the latest cli and tell me if you still experience the stuck problems?
https://bintray.com/utplsql/utPLSQL-cli/download_file?file_path=utPLSQL-cli-develop-201906272140.zip

@DrErique
Copy link
Author

DrErique commented Jun 28, 2019 via email

@dmpe
Copy link
Contributor

dmpe commented Jun 28, 2019

############### utPLSQL cli ###############
#                                         #
#   utPLSQL-cli 3.1.7-SNAPSHOT.local      #
#   utPLSQL-java-api 3.1.7-SNAPSHOT.483   #
#   Java-Version: 1.8.0_212               #
#   ORACLE_HOME: null                     #
#   NLS_LANG: AMERICAN_AMERICA.UTF8       #
#                                         #
#   Thanks for testing!                   #
#                                         #
###########################################
Use connection string jdbc:oracle:thin:
Successfully connected to database. UtPLSQL core: v3.1.1.1868
Oracle-Version: 11.2.0.4.0
Running tests now.
--------------------------------------
TestRunner initialized
Running on utPLSQL v3.1.1.1868
Initializing reporters
Detected Oracle driver stuck during Statement initialization
Timeout while waiting for reporters to finish for 60 minutes

Thanks for the effort!!!
It is better now but getting some errors. unfortunately cannot debug this today more extensively. Only later in the month.

@pesse
Copy link
Member

pesse commented Jun 28, 2019 via email

@dmpe
Copy link
Contributor

dmpe commented Jun 28, 2019

Yes. I have just deleted login info

EDIT: I have used -source_path=. -test_path=. -f=ut_coverage_html_reporter -o=coverage.html -f=UT_DOCUMENTATION_REPORTER -s parameters but my issues may be related to ent. environment and the fact of very old core utplsql

@pesse
Copy link
Member

pesse commented Jun 28, 2019 via email

@DrErique
Copy link
Author

DrErique commented Jul 8, 2019 via email

@pesse
Copy link
Member

pesse commented Jul 8, 2019

Thanks for reporting back, @DrErique
I'll create a new, dedicated issue for the situations that still lead to a stuck scenario.

@kapbka
Copy link

kapbka commented Aug 19, 2019

Hi everyone,

Is there any news on this issue ?

I have come across the same problem "Detected Oracle driver stuck during Statement initialization" when trying to calculate the coverage:

2019-08-19 11:50:54 [main] INFO  org.utplsql.cli.RunAction - ############### utPLSQL cli ###############
#                                         #
#   utPLSQL-cli 3.1.7-SNAPSHOT.local      #
#   utPLSQL-java-api 3.1.7-SNAPSHOT.483   #
#   Java-Version: 1.8.0_201               #
#   ORACLE_HOME: null                     #
#   NLS_LANG: null                        #
#                                         #
#   Thanks for testing!                   #
#                                         #
###########################################
2019-08-19 11:50:54 [main] INFO  org.utplsql.cli.RunAction -
2019-08-19 11:50:55 [main] INFO  o.u.c.d.TestedDataSourceProvider - Use connection string jdbc:oracle:oci8:****/****@****:****:****
2019-08-19 11:50:57 [main] INFO  org.utplsql.cli.RunAction - Successfully connected to database. UtPLSQL core: v3.1.7.3085
2019-08-19 11:50:57 [main] INFO  org.utplsql.cli.RunAction - Oracle-Version: 12.2.0.1.0
2019-08-19 11:51:05 [main] DEBUG org.utplsql.api.reporter.Reporter - Database-reporter initialized, Type: UT_COVERAGE_HTML_REPORTER, ID: 90771DD22B780564E0530100007F1016
2019-08-19 11:51:05 [main] DEBUG org.utplsql.cli.RunAction - BaseDir: C:\work\repo\Main\db\utPLSQL-cli\bin
2019-08-19 11:51:05 [main] DEBUG org.utplsql.cli.RunAction - SourcePath: ../../test_proc
2019-08-19 11:51:05 [main] DEBUG org.utplsql.cli.RunAction - Getting FileMapperOptions - Files:
2019-08-19 11:51:05 [main] DEBUG org.utplsql.cli.RunAction - ..\..\test_proc\test_lib.pkb
2019-08-19 11:51:05 [main] DEBUG org.utplsql.cli.RunAction - ..\..\test_proc\test_lib.pks
2019-08-19 11:51:05 [main] DEBUG org.utplsql.cli.RunAction - BaseDir: C:\work\repo\Main\db\utPLSQL-cli\bin
2019-08-19 11:51:05 [main] DEBUG org.utplsql.cli.RunAction - SourcePath: ../../test_unit_testing
2019-08-19 11:51:05 [main] DEBUG org.utplsql.cli.RunAction - Getting FileMapperOptions - Files:
2019-08-19 11:51:05 [main] DEBUG org.utplsql.cli.RunAction - ..\..\test_unit_testing\test_test_lib.pkb
2019-08-19 11:51:05 [main] DEBUG org.utplsql.cli.RunAction - ..\..\test_unit_testing\test_test_lib.pks
2019-08-19 11:51:06 [pool-10-thread-1] INFO  org.utplsql.cli.RunTestRunnerTask - Running tests now.
2019-08-19 11:51:06 [pool-10-thread-1] INFO  org.utplsql.cli.RunTestRunnerTask - --------------------------------------
2019-08-19 11:51:06 [pool-10-thread-1] INFO  org.utplsql.api.TestRunner - TestRunner initialized
2019-08-19 11:51:06 [pool-10-thread-1] INFO  org.utplsql.api.TestRunner - Running on utPLSQL v3.1.7.3085
2019-08-19 11:51:06 [pool-10-thread-1] INFO  org.utplsql.api.TestRunner - Initializing reporters
2019-08-19 11:51:08 [pool-11-thread-1] DEBUG org.utplsql.api.FileMapper - Building fileMappingArray
2019-08-19 11:51:08 [pool-11-thread-1] DEBUG org.utplsql.api.FileMapper - Path: ..\..\test_proc\test_lib.pkb
2019-08-19 11:51:08 [pool-11-thread-1] DEBUG org.utplsql.api.FileMapper - Path: ..\..\test_proc\test_lib.pks
2019-08-19 11:51:08 [pool-10-thread-1] ERROR org.utplsql.api.TestRunner - Detected Oracle driver stuck during Statement initialization
2019-08-19 11:52:10 [main] WARN  org.utplsql.cli.RunAction - WARNING: Caught Oracle stuck during creation of Runner-Statement. Retrying (4)

If it's just the run of the tests then it works:

############### utPLSQL cli ###############
#                                         #
#   utPLSQL-cli 3.1.7-SNAPSHOT.local      #
#   utPLSQL-java-api 3.1.7-SNAPSHOT.483   #
#   Java-Version: 1.8.0_201               #
#   ORACLE_HOME: null                     #
#   NLS_LANG: null                        #
#                                         #
#   Thanks for testing!                   #
#                                         #
###########################################

Use connection string jdbc:oracle:oci8:****/****@****:****:****
Successfully connected to database. UtPLSQL core: v3.1.7.3096
Oracle-Version: 12.2.0.1.0
Running tests now.
--------------------------------------
TestRunner initialized
Running on utPLSQL v3.1.7.3096
Initializing reporters
Running tests
****
Running tests finished.

Finished in 11.875065 seconds
36 tests, 0 failed, 0 errored, 0 disabled, 0 warning(s)

--------------------------------------
All tests done.

Thanks & Regards,
Natalia

@vasanthsumanath
Copy link

Hi All,

I have recently started doing POC on the utPLSQL for using it as a unit test frame work for PLSQL developments.

But I am encountering below issue when try executing the test from CLI.

Kindly help.

WARNING: Caught Oracle stuck during creation of Runner-Statement. Retrying (1)
############# utPLSQL cli ##############
#                                      #
#   utPLSQL-cli 3.1.8-SNAPSHOT.local   #
#   utPLSQL-java-api 3.1.8.546         #
#   Java-Version: 11.0.2               #
#   ORACLE_HOME: null                  #
#   NLS_LANG: null                     #
#                                      #
#   Thanks for testing!                #
#                                      #
########################################

Use connection string jdbc:oracle:thin:****/****@//XXXXX:1521/XXXXX
Successfully connected to database. UtPLSQL core: v3.1.9.3270
Oracle-Version: 12.1.0.2.0
Running tests now.
--------------------------------------
TestRunner initialized
Running on utPLSQL v3.1.9.3270
Initializing reporters
Detected Oracle driver stuck during Statement initialization
java.lang.NullPointerException
	at oracle.net.nt.TcpNTAdapter.abort(TcpNTAdapter.java:423)
	at oracle.net.ns.NSProtocol.abort(NSProtocol.java:532)
	at oracle.jdbc.driver.T4CConnection.doAbort(T4CConnection.java:5148)
	at oracle.jdbc.driver.PhysicalConnection.abort(PhysicalConnection.java:9353)
	at org.utplsql.cli.RunTestRunnerTask.call(RunTestRunnerTask.java:50)
	at org.utplsql.cli.RunTestRunnerTask.call(RunTestRunnerTask.java:24)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
	at java.base/java.lang.Thread.run(Thread.java:834)

@pesse
Copy link
Member

pesse commented Dec 4, 2019

Hey @vasanthsumanath , can you please provide a full log as file, running the cli with the -d flag?

@vasanthsumanath
Copy link

Just to add, its working fine when connected to a different DB,

Below are the major difference between the 2 DB environments

Successfully executed in

Oracle-Version: 12.2.0.1.0
Platform - Docker image

Failed Executing from
Oracle-Version: 12.1.0.2.0
Platform - CentOS 7 VM

############# utPLSQL cli ##############
#                                      #
#   utPLSQL-cli 3.1.8-SNAPSHOT.local   #
#   utPLSQL-java-api 3.1.8.546         #
#   Java-Version: 11.0.2               #
#   ORACLE_HOME: null                  #
#   NLS_LANG: null                     #
#                                      #
#   Thanks for testing!                #
#                                      #
########################################

Use connection string jdbc:oracle:thin:****/****@//localhost:1521/orclpdb1
Successfully connected to database. UtPLSQL core: v3.1.9.3270
Oracle-Version: 12.2.0.1.0
Running tests now.
--------------------------------------
TestRunner initialized
Running on utPLSQL v3.1.9.3270
Initializing reporters
Running tests
Between string function
Running tests finished.
  Returns substring from start position to end position [.056 sec]
  Returns substring when start position is zero [.003 sec]

Finished in .070032 seconds
2 tests, 0 failed, 0 errored, 0 disabled, 0 warning(s)

--------------------------------------
All tests done. 

@vasanthsumanath
Copy link

Hi @pesse,

Thanks for your quick response, yes will do it.

Thanks
Vasanth.M

@vasanthsumanath
Copy link

Hey @vasanthsumanath , can you please provide a full log as file, running the cli with the -d flag?

Hi @pesse

I have attached log when executed in debug mode.

Below the command executed
utplsql run ***/***@//***:1521/*** -d 1>>utplsql_cli.log 2>&1

utplsql_cli.log

@vasanthsumanath
Copy link

Any help/suggestion with the issue I am facing?

@pesse
Copy link
Member

pesse commented Dec 11, 2019

These issues are among the hardest to investigate, because they are near impossible to reproduce on a different database.
You also found a possible solution yourself already, for it seems to work on Oracle 12.2. This suggests some kind of Oracle DB bug which is even harder to work around.

My guess from the error message is that it's not really a "Stuck" problem as we have faced it in the past, but some general issue with the driver.

What you could do and that would probably help to investigate:

  • Make sure you have the latest DB patches installed
  • Try running ut.run() from within SQLcl (not SQLPlus)
  • Try out different OJDBC-Versions
  • Try out OJDBC-OCI (thick driver)

@rafael-trevisan
Copy link

Hi guys, I've started facing this issue too. I can say it's indeed intermittent and it's likely to happen more often (don't know why) when using the CLI in a pipeline. In my case here I am using GitHub Actions and there are times the test step fails because of this. If I simply hit "re-run jobs" I got cases that it worked on the first re-try and other it took more re-tries to work.

If any log is needed or further information that may help troubleshooting this, let me know.

@pesse
Copy link
Member

pesse commented Jun 25, 2020

Which Oracle version are you on? Latest patchs applied?

Same is true for you as for Vasanthsumanath:

What you could do and that would probably help to investigate:

  • Make sure you have the latest DB patches installed
  • Try running ut.run() from within SQLcl (not SQLPlus)
  • Try out different OJDBC-Versions
  • Try out OJDBC-OCI (thick driver)

@rafael-trevisan
Copy link

I am using Oracle SE2 on AWS RDS. I am not sure about where it's in patches but RDS's settings are to upgrade even minor patches.

I am not installing any custom version of OJDBC. I am getting the CLI from

wget https://github.com/utPLSQL/utPLSQL-cli/releases/download/v3.1.8/utPLSQL-cli.zip

and I assume it contains the latest OJDBC, am I right?

If I want to try different version of OJDBC is it just replacing the existing one shipped with the CLI with whatever version I want to test?

@rafael-trevisan
Copy link

rafael-trevisan commented Jun 25, 2020

I am not sure if that helps, but when this issue happens I see a couple of sessions for my "testing" user waiting while trying to run:
SELECT TEXT, ITEM_TYPE FROM TABLE((:B1 ).GET_LINES(:B2 , :B3 ))

@pesse
Copy link
Member

pesse commented Jun 26, 2020

Can you please issue the following code to give a complete picture what Oracle version you're using? From what I read there are different versions of Oracle DB available on AWS:

set serveroutput on
declare
  l_version varchar2(255);
  l_compatibility varchar2(255);
begin
  dbms_utility.db_version( l_version, l_compatibility );
  dbms_output.put_line( l_version );
  dbms_output.put_line( l_compatibility );
end;
/
select substr(ut.version(),1,60) as ut_version from dual;
select * from v$version;
select * from nls_session_parameters;
select substr(dbms_utility.port_string,1,60) as port_string from dual;

And yes, you can replace the default one by just replacing the JAR with any other OJDBC driver.

@rafael-trevisan
Copy link

19.0.0.0.0
19.0.0
v3.1.10.3349
Oracle Database 19c Standard Edition 2 Release 19.0.0.0.0 - Production
Oracle Database 19c Standard Edition 2 Release 19.0.0.0.0 - Production Version 19.7.0.0.0
Oracle Database 19c Standard Edition 2 Release 19.0.0.0.0 - Production
NLS_LANGUAGE	ENGLISH
NLS_TERRITORY	CANADA
NLS_CURRENCY	$
NLS_ISO_CURRENCY	CANADA
NLS_NUMERIC_CHARACTERS	.,
NLS_CALENDAR	GREGORIAN
NLS_DATE_FORMAT	DD-MON-YYYY
NLS_DATE_LANGUAGE	ENGLISH
NLS_SORT	BINARY
NLS_TIME_FORMAT	HH24:MI:SSXFF
NLS_TIMESTAMP_FORMAT	DD-MON-YYYY HH:MI:SSXFF AM
NLS_TIME_TZ_FORMAT	HH24:MI:SSXFF TZR
NLS_TIMESTAMP_TZ_FORMAT	DD-MON-YYYY HH:MI:SSXFF AM TZR
NLS_DUAL_CURRENCY	$
NLS_COMP	BINARY
NLS_LENGTH_SEMANTICS	BYTE
NLS_NCHAR_CONV_EXCP	FALSE

@rafael-trevisan
Copy link

I did a test using the OJDBC8 from Oracle website and the message was a bit more verbose:

#   Thanks for testing!                #
#                                      #
########################################

Use connection string jdbc:oracle:thin:****/****@***
Successfully connected to database. UtPLSQL core: v3.1.10.3349
Oracle-Version: 19.0.0.0.0
Running tests now.
--------------------------------------
TestRunner initialized
Running on utPLSQL v3.1.10.3349
Initializing reporters
Detected Oracle driver stuck during Statement initialization
java.lang.NullPointerException
	at oracle.net.nt.TcpNTAdapter.abort(TcpNTAdapter.java:423)
	at oracle.net.ns.NSProtocol.abort(NSProtocol.java:532)
	at oracle.jdbc.driver.T4CConnection.doAbort(T4CConnection.java:5161)
	at oracle.jdbc.driver.PhysicalConnection.abort(PhysicalConnection.java:9353)
	at org.utplsql.cli.RunTestRunnerTask.call(RunTestRunnerTask.java:50)
	at org.utplsql.cli.RunTestRunnerTask.call(RunTestRunnerTask.java:24)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
	at java.lang.Thread.run(Thread.java:748)
##[error]Process completed with exit code 1.```

@rafael-trevisan
Copy link

Again, the issue is intermittent. If I just re-run the job to re-execute the tests is may pass - or not.

@pesse
Copy link
Member

pesse commented Jun 30, 2020

Hey @rafael-trevisan , could you attach the full logs of a failing run with -d (for extensive debug messages)?

This looks different to the original stuck statement error.

@rafael-trevisan
Copy link

Here is it @pesse

2020-07-22 21:38:46 [main] INFO  org.utplsql.cli.RunAction - ############# utPLSQL cli ##############
#                                      #
#   utPLSQL-cli 3.1.8-SNAPSHOT.local   #
#   utPLSQL-java-api 3.1.8.546         #
#   Java-Version: 11.0.7               #
#   ORACLE_HOME: null                  #
#   NLS_LANG: null                     #
#                                      #
#   Thanks for testing!                #
#                                      #
########################################
2020-07-22 21:38:46 [main] INFO  org.utplsql.cli.RunAction - 
2020-07-22 21:38:47 [main] INFO  o.u.c.d.TestedDataSourceProvider - Use connection string jdbc:oracle:thin:****/****@****
2020-07-22 21:38:49 [main] INFO  org.utplsql.cli.RunAction - Successfully connected to database. UtPLSQL core: v3.1.10.3349
2020-07-22 21:38:49 [main] INFO  org.utplsql.cli.RunAction - Oracle-Version: 19.0.0.0.0
2020-07-22 21:38:50 [main] DEBUG org.utplsql.api.reporter.Reporter - Database-reporter initialized, Type: UT_COVERAGE_SONAR_REPORTER, ID: AB156ECD41596161E0530100007FDCCD
2020-07-22 21:38:51 [main] DEBUG org.utplsql.api.reporter.Reporter - Database-reporter initialized, Type: UT_SONAR_TEST_REPORTER, ID: AB156ECD415D6161E0530100007FDCCD
2020-07-22 21:38:51 [main] DEBUG org.utplsql.api.reporter.Reporter - Database-reporter initialized, Type: UT_DOCUMENTATION_REPORTER, ID: AB156ECD41616161E0530100007FDCCD
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - BaseDir: /Users/username/Projects/project_name/reponame
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - SourcePath: server/db
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - Getting FileMapperOptions - Files: 
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/procedures/r__generate_api_methods.plsql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/triggers/r__projects_ai.plsql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/triggers/r__user_credentials_biu_hash_password.plsql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/util/create-schema.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/util/install-apex.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/util/drop-schema.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/callbacks/afterMigrate__002-enable-audit.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/callbacks/afterMigrate__003-load-data.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/callbacks/afterMigrate__001-compile-schema.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/v2020.01.01.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__something_body.pkb
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__team_body.pkb
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__util_body.pkb
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__error_body.pkb
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__role_spec.pks
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/.DS_Store
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__plan_spec.pks
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__environment_body.pkb
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__security_spec.pks
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__something_variation_body.pkb
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__project_body.pkb
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__account_body.pkb
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__something_spec.pks
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__team_spec.pks
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__util_spec.pks
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__role_body.pkb
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__error_spec.pks
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__plan_body.pkb
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__security_body.pkb
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__environment_spec.pks
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__something_variation_spec.pks
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__project_spec.pks
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__account_spec.pks
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__const_spec.pks
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__plans.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__projects.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__roles.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__something_variations.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__accounts.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__users_ext.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__users.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__environments.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__account_users.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__somethings.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__user_credentials.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__errors.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__history.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__teams.sql
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - BaseDir: /Users/username/Projects/project_name/reponame
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - SourcePath: server/test
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - Getting FileMapperOptions - Files: 
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/test/packages/r__test_util_spec.pks
2020-07-22 21:38:51 [main] DEBUG org.utplsql.cli.RunAction - server/test/packages/r__test_util_body.pkb
2020-07-22 21:38:52 [pool-1-thread-1] INFO  org.utplsql.cli.RunTestRunnerTask - Running tests now.
2020-07-22 21:38:52 [pool-1-thread-1] INFO  org.utplsql.cli.RunTestRunnerTask - --------------------------------------
2020-07-22 21:38:52 [pool-1-thread-1] INFO  org.utplsql.api.TestRunner - TestRunner initialized
2020-07-22 21:38:52 [pool-1-thread-1] INFO  org.utplsql.api.TestRunner - Running on utPLSQL v3.1.10.3349
2020-07-22 21:38:52 [pool-1-thread-1] INFO  org.utplsql.api.TestRunner - Initializing reporters
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Building fileMappingArray
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/procedures/r__generate_api_methods.plsql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/triggers/r__projects_ai.plsql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/triggers/r__user_credentials_biu_hash_password.plsql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/util/create-schema.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/util/install-apex.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/util/drop-schema.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/callbacks/afterMigrate__002-enable-audit.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/callbacks/afterMigrate__003-load-data.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/callbacks/afterMigrate__001-compile-schema.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/v2020.01.01.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__something_body.pkb
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__team_body.pkb
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__util_body.pkb
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__error_body.pkb
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__role_spec.pks
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/.DS_Store
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__plan_spec.pks
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__environment_body.pkb
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__security_spec.pks
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__something_variation_body.pkb
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__project_body.pkb
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__account_body.pkb
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__something_spec.pks
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__team_spec.pks
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__util_spec.pks
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__role_body.pkb
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__error_spec.pks
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__plan_body.pkb
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__security_body.pkb
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__environment_spec.pks
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__something_variation_spec.pks
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__project_spec.pks
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__account_spec.pks
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__const_spec.pks
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__plans.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__projects.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__roles.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__something_variations.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__accounts.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__users_ext.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__users.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__environments.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__account_users.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__somethings.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__user_credentials.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__errors.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__history.sql
2020-07-22 21:38:52 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__teams.sql
2020-07-22 21:38:54 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Building fileMappingArray
2020-07-22 21:38:54 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/test/packages/r__test_util_spec.pks
2020-07-22 21:38:54 [pool-2-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/test/packages/r__test_util_body.pkb
2020-07-22 21:38:54 [pool-1-thread-1] ERROR org.utplsql.api.TestRunner - Detected Oracle driver stuck during Statement initialization
2020-07-22 21:38:54 [main] WARN  org.utplsql.cli.RunAction - WARNING: Caught Oracle stuck during creation of Runner-Statement. Retrying (1)
2020-07-22 21:38:54 [main] INFO  org.utplsql.cli.RunAction - ############# utPLSQL cli ##############
#                                      #
#   utPLSQL-cli 3.1.8-SNAPSHOT.local   #
#   utPLSQL-java-api 3.1.8.546         #
#   Java-Version: 11.0.7               #
#   ORACLE_HOME: null                  #
#   NLS_LANG: null                     #
#                                      #
#   Thanks for testing!                #
#                                      #
########################################
2020-07-22 21:38:54 [main] INFO  org.utplsql.cli.RunAction - 
2020-07-22 21:38:55 [main] INFO  o.u.c.d.TestedDataSourceProvider - Use connection string jdbc:oracle:thin:****/****@****
2020-07-22 21:38:56 [main] INFO  org.utplsql.cli.RunAction - Successfully connected to database. UtPLSQL core: v3.1.10.3349
2020-07-22 21:38:56 [main] INFO  org.utplsql.cli.RunAction - Oracle-Version: 19.0.0.0.0
2020-07-22 21:38:58 [main] DEBUG org.utplsql.api.reporter.Reporter - Database-reporter initialized, Type: UT_COVERAGE_SONAR_REPORTER, ID: AB156F3F69C1617EE0530100007F7B5F
2020-07-22 21:38:58 [main] DEBUG org.utplsql.api.reporter.Reporter - Database-reporter initialized, Type: UT_SONAR_TEST_REPORTER, ID: AB156F3F69C5617EE0530100007F7B5F
2020-07-22 21:38:59 [main] DEBUG org.utplsql.api.reporter.Reporter - Database-reporter initialized, Type: UT_DOCUMENTATION_REPORTER, ID: AB156F3F69C9617EE0530100007F7B5F
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - BaseDir: /Users/username/Projects/project_name/reponame
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - SourcePath: server/db
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - Getting FileMapperOptions - Files: 
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/procedures/r__generate_api_methods.plsql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/triggers/r__projects_ai.plsql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/triggers/r__user_credentials_biu_hash_password.plsql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/util/create-schema.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/util/install-apex.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/util/drop-schema.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/callbacks/afterMigrate__002-enable-audit.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/callbacks/afterMigrate__003-load-data.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/callbacks/afterMigrate__001-compile-schema.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/v2020.01.01.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__something_body.pkb
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__team_body.pkb
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__util_body.pkb
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__error_body.pkb
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__role_spec.pks
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/.DS_Store
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__plan_spec.pks
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__environment_body.pkb
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__security_spec.pks
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__something_variation_body.pkb
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__project_body.pkb
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__account_body.pkb
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__something_spec.pks
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__team_spec.pks
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__util_spec.pks
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__role_body.pkb
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__error_spec.pks
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__plan_body.pkb
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__security_body.pkb
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__environment_spec.pks
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__something_variation_spec.pks
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__project_spec.pks
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__account_spec.pks
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__const_spec.pks
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__plans.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__projects.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__roles.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__something_variations.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__accounts.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__users_ext.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__users.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__environments.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__account_users.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__somethings.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__user_credentials.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__errors.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__history.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__teams.sql
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - BaseDir: /Users/username/Projects/project_name/reponame
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - SourcePath: server/test
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - Getting FileMapperOptions - Files: 
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/test/packages/r__test_util_spec.pks
2020-07-22 21:38:59 [main] DEBUG org.utplsql.cli.RunAction - server/test/packages/r__test_util_body.pkb
2020-07-22 21:39:00 [pool-4-thread-1] INFO  org.utplsql.cli.RunTestRunnerTask - Running tests now.
2020-07-22 21:39:00 [pool-4-thread-1] INFO  org.utplsql.cli.RunTestRunnerTask - --------------------------------------
2020-07-22 21:39:00 [pool-4-thread-1] INFO  org.utplsql.api.TestRunner - TestRunner initialized
2020-07-22 21:39:00 [pool-4-thread-1] INFO  org.utplsql.api.TestRunner - Running on utPLSQL v3.1.10.3349
2020-07-22 21:39:00 [pool-4-thread-1] INFO  org.utplsql.api.TestRunner - Initializing reporters
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Building fileMappingArray
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/procedures/r__generate_api_methods.plsql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/triggers/r__projects_ai.plsql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/triggers/r__user_credentials_biu_hash_password.plsql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/util/create-schema.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/util/install-apex.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/util/drop-schema.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/callbacks/afterMigrate__002-enable-audit.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/callbacks/afterMigrate__003-load-data.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/callbacks/afterMigrate__001-compile-schema.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/v2020.01.01.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__something_body.pkb
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__team_body.pkb
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__util_body.pkb
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__error_body.pkb
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__role_spec.pks
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/.DS_Store
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__plan_spec.pks
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__environment_body.pkb
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__security_spec.pks
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__something_variation_body.pkb
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__project_body.pkb
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__account_body.pkb
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__something_spec.pks
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__team_spec.pks
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__util_spec.pks
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__role_body.pkb
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__error_spec.pks
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__plan_body.pkb
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__security_body.pkb
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__environment_spec.pks
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__something_variation_spec.pks
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__project_spec.pks
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__account_spec.pks
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__const_spec.pks
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__plans.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__projects.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__roles.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__something_variations.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__accounts.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__users_ext.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__users.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__environments.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__account_users.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__somethings.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__user_credentials.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__errors.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__history.sql
2020-07-22 21:39:00 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__teams.sql
2020-07-22 21:39:01 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Building fileMappingArray
2020-07-22 21:39:01 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/test/packages/r__test_util_spec.pks
2020-07-22 21:39:01 [pool-5-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/test/packages/r__test_util_body.pkb
2020-07-22 21:39:02 [pool-4-thread-1] ERROR org.utplsql.api.TestRunner - Detected Oracle driver stuck during Statement initialization
2020-07-22 21:39:02 [main] WARN  org.utplsql.cli.RunAction - WARNING: Caught Oracle stuck during creation of Runner-Statement. Retrying (2)
2020-07-22 21:39:02 [main] INFO  org.utplsql.cli.RunAction - ############# utPLSQL cli ##############
#                                      #
#   utPLSQL-cli 3.1.8-SNAPSHOT.local   #
#   utPLSQL-java-api 3.1.8.546         #
#   Java-Version: 11.0.7               #
#   ORACLE_HOME: null                  #
#   NLS_LANG: null                     #
#                                      #
#   Thanks for testing!                #
#                                      #
########################################
2020-07-22 21:39:02 [main] INFO  org.utplsql.cli.RunAction - 
2020-07-22 21:39:03 [main] INFO  o.u.c.d.TestedDataSourceProvider - Use connection string jdbc:oracle:thin:****/****@****
2020-07-22 21:39:03 [main] INFO  org.utplsql.cli.RunAction - Successfully connected to database. UtPLSQL core: v3.1.10.3349
2020-07-22 21:39:04 [main] INFO  org.utplsql.cli.RunAction - Oracle-Version: 19.0.0.0.0
2020-07-22 21:39:05 [main] DEBUG org.utplsql.api.reporter.Reporter - Database-reporter initialized, Type: UT_COVERAGE_SONAR_REPORTER, ID: AB156FB0581B619FE0530100007F0719
2020-07-22 21:39:06 [main] DEBUG org.utplsql.api.reporter.Reporter - Database-reporter initialized, Type: UT_SONAR_TEST_REPORTER, ID: AB156FB0581F619FE0530100007F0719
2020-07-22 21:39:06 [main] DEBUG org.utplsql.api.reporter.Reporter - Database-reporter initialized, Type: UT_DOCUMENTATION_REPORTER, ID: AB156FB05823619FE0530100007F0719
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - BaseDir: /Users/username/Projects/project_name/reponame
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - SourcePath: server/db
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - Getting FileMapperOptions - Files: 
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/procedures/r__generate_api_methods.plsql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/triggers/r__projects_ai.plsql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/triggers/r__user_credentials_biu_hash_password.plsql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/util/create-schema.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/util/install-apex.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/util/drop-schema.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/callbacks/afterMigrate__002-enable-audit.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/callbacks/afterMigrate__003-load-data.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/callbacks/afterMigrate__001-compile-schema.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/v2020.01.01.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__something_body.pkb
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__team_body.pkb
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__util_body.pkb
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__error_body.pkb
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__role_spec.pks
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/.DS_Store
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__plan_spec.pks
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__environment_body.pkb
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__security_spec.pks
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__something_variation_body.pkb
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__project_body.pkb
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__account_body.pkb
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__something_spec.pks
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__team_spec.pks
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__util_spec.pks
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__role_body.pkb
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__error_spec.pks
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__plan_body.pkb
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__security_body.pkb
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__environment_spec.pks
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__something_variation_spec.pks
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__project_spec.pks
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__account_spec.pks
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__const_spec.pks
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__plans.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__projects.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__roles.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__something_variations.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__accounts.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__users_ext.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__users.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__environments.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__account_users.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__somethings.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__user_credentials.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__errors.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__history.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__teams.sql
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - BaseDir: /Users/username/Projects/project_name/reponame
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - SourcePath: server/test
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - Getting FileMapperOptions - Files: 
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/test/packages/r__test_util_spec.pks
2020-07-22 21:39:06 [main] DEBUG org.utplsql.cli.RunAction - server/test/packages/r__test_util_body.pkb
2020-07-22 21:39:07 [pool-7-thread-1] INFO  org.utplsql.cli.RunTestRunnerTask - Running tests now.
2020-07-22 21:39:07 [pool-7-thread-1] INFO  org.utplsql.cli.RunTestRunnerTask - --------------------------------------
2020-07-22 21:39:07 [pool-7-thread-1] INFO  org.utplsql.api.TestRunner - TestRunner initialized
2020-07-22 21:39:07 [pool-7-thread-1] INFO  org.utplsql.api.TestRunner - Running on utPLSQL v3.1.10.3349
2020-07-22 21:39:07 [pool-7-thread-1] INFO  org.utplsql.api.TestRunner - Initializing reporters
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Building fileMappingArray
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/procedures/r__generate_api_methods.plsql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/triggers/r__projects_ai.plsql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/triggers/r__user_credentials_biu_hash_password.plsql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/util/create-schema.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/util/install-apex.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/util/drop-schema.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/callbacks/afterMigrate__002-enable-audit.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/callbacks/afterMigrate__003-load-data.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/callbacks/afterMigrate__001-compile-schema.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/v2020.01.01.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__something_body.pkb
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__team_body.pkb
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__util_body.pkb
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__error_body.pkb
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__role_spec.pks
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/.DS_Store
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__plan_spec.pks
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__environment_body.pkb
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__security_spec.pks
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__something_variation_body.pkb
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__project_body.pkb
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__account_body.pkb
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__something_spec.pks
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__team_spec.pks
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__util_spec.pks
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__role_body.pkb
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__error_spec.pks
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__plan_body.pkb
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__security_body.pkb
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__environment_spec.pks
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__something_variation_spec.pks
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__project_spec.pks
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__account_spec.pks
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__const_spec.pks
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__plans.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__projects.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__roles.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__something_variations.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__accounts.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__users_ext.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__users.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__environments.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__account_users.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__somethings.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__user_credentials.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__errors.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__history.sql
2020-07-22 21:39:07 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__teams.sql
2020-07-22 21:39:08 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Building fileMappingArray
2020-07-22 21:39:08 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/test/packages/r__test_util_spec.pks
2020-07-22 21:39:08 [pool-8-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/test/packages/r__test_util_body.pkb
2020-07-22 21:39:09 [pool-7-thread-1] ERROR org.utplsql.api.TestRunner - Detected Oracle driver stuck during Statement initialization
2020-07-22 21:39:09 [main] WARN  org.utplsql.cli.RunAction - WARNING: Caught Oracle stuck during creation of Runner-Statement. Retrying (3)
2020-07-22 21:39:09 [main] INFO  org.utplsql.cli.RunAction - ############# utPLSQL cli ##############
#                                      #
#   utPLSQL-cli 3.1.8-SNAPSHOT.local   #
#   utPLSQL-java-api 3.1.8.546         #
#   Java-Version: 11.0.7               #
#   ORACLE_HOME: null                  #
#   NLS_LANG: null                     #
#                                      #
#   Thanks for testing!                #
#                                      #
########################################
2020-07-22 21:39:09 [main] INFO  org.utplsql.cli.RunAction - 
2020-07-22 21:39:10 [main] INFO  o.u.c.d.TestedDataSourceProvider - Use connection string jdbc:oracle:thin:****/****@****
2020-07-22 21:39:11 [main] INFO  org.utplsql.cli.RunAction - Successfully connected to database. UtPLSQL core: v3.1.10.3349
2020-07-22 21:39:11 [main] INFO  org.utplsql.cli.RunAction - Oracle-Version: 19.0.0.0.0
2020-07-22 21:39:13 [main] DEBUG org.utplsql.api.reporter.Reporter - Database-reporter initialized, Type: UT_COVERAGE_SONAR_REPORTER, ID: AB157021C63A61BCE0530100007FE167
2020-07-22 21:39:13 [main] DEBUG org.utplsql.api.reporter.Reporter - Database-reporter initialized, Type: UT_SONAR_TEST_REPORTER, ID: AB157021C63E61BCE0530100007FE167
2020-07-22 21:39:14 [main] DEBUG org.utplsql.api.reporter.Reporter - Database-reporter initialized, Type: UT_DOCUMENTATION_REPORTER, ID: AB157021C64261BCE0530100007FE167
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - BaseDir: /Users/username/Projects/project_name/reponame
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - SourcePath: server/db
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - Getting FileMapperOptions - Files: 
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/procedures/r__generate_api_methods.plsql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/triggers/r__projects_ai.plsql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/triggers/r__user_credentials_biu_hash_password.plsql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/util/create-schema.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/util/install-apex.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/util/drop-schema.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/callbacks/afterMigrate__002-enable-audit.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/callbacks/afterMigrate__003-load-data.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/callbacks/afterMigrate__001-compile-schema.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/scripts/v2020.01.01.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__something_body.pkb
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__team_body.pkb
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__util_body.pkb
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__error_body.pkb
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__role_spec.pks
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/.DS_Store
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__plan_spec.pks
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__environment_body.pkb
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__security_spec.pks
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__something_variation_body.pkb
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__project_body.pkb
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__account_body.pkb
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__something_spec.pks
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__team_spec.pks
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__util_spec.pks
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__role_body.pkb
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__error_spec.pks
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__plan_body.pkb
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__security_body.pkb
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__environment_spec.pks
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__something_variation_spec.pks
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__project_spec.pks
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__account_spec.pks
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/packages/r__const_spec.pks
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__plans.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__projects.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__roles.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__something_variations.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__accounts.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__users_ext.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__users.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__environments.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__account_users.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__somethings.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__user_credentials.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__errors.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__history.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/db/views/r__teams.sql
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - BaseDir: /Users/username/Projects/project_name/reponame
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - SourcePath: server/test
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - Getting FileMapperOptions - Files: 
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/test/packages/r__test_util_spec.pks
2020-07-22 21:39:14 [main] DEBUG org.utplsql.cli.RunAction - server/test/packages/r__test_util_body.pkb
2020-07-22 21:39:14 [pool-10-thread-1] INFO  org.utplsql.cli.RunTestRunnerTask - Running tests now.
2020-07-22 21:39:14 [pool-10-thread-1] INFO  org.utplsql.cli.RunTestRunnerTask - --------------------------------------
2020-07-22 21:39:14 [pool-10-thread-1] INFO  org.utplsql.api.TestRunner - TestRunner initialized
2020-07-22 21:39:15 [pool-10-thread-1] INFO  org.utplsql.api.TestRunner - Running on utPLSQL v3.1.10.3349
2020-07-22 21:39:15 [pool-10-thread-1] INFO  org.utplsql.api.TestRunner - Initializing reporters
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Building fileMappingArray
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/procedures/r__generate_api_methods.plsql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/triggers/r__projects_ai.plsql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/triggers/r__user_credentials_biu_hash_password.plsql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/util/create-schema.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/util/install-apex.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/util/drop-schema.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/callbacks/afterMigrate__002-enable-audit.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/callbacks/afterMigrate__003-load-data.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/callbacks/afterMigrate__001-compile-schema.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/scripts/v2020.01.01.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__something_body.pkb
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__team_body.pkb
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__util_body.pkb
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__error_body.pkb
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__role_spec.pks
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/.DS_Store
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__plan_spec.pks
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__environment_body.pkb
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__security_spec.pks
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__something_variation_body.pkb
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__project_body.pkb
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__account_body.pkb
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__something_spec.pks
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__team_spec.pks
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__util_spec.pks
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__role_body.pkb
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__error_spec.pks
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__plan_body.pkb
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__security_body.pkb
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__environment_spec.pks
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__something_variation_spec.pks
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__project_spec.pks
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__account_spec.pks
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/packages/r__const_spec.pks
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__plans.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__projects.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__roles.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__something_variations.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__accounts.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__users_ext.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__users.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__environments.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__account_users.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__somethings.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__user_credentials.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__errors.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__history.sql
2020-07-22 21:39:15 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/db/views/r__teams.sql
2020-07-22 21:39:16 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Building fileMappingArray
2020-07-22 21:39:16 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/test/packages/r__test_util_spec.pks
2020-07-22 21:39:16 [pool-11-thread-1] DEBUG o.utplsql.api.testRunner.FileMapper - Path: server/test/packages/r__test_util_body.pkb
2020-07-22 21:39:17 [pool-10-thread-1] ERROR org.utplsql.api.TestRunner - Detected Oracle driver stuck during Statement initialization
2020-07-22 21:39:17 [main] WARN  org.utplsql.cli.RunAction - WARNING: Caught Oracle stuck during creation of Runner-Statement. Retrying (4)

@rafael-trevisan
Copy link

rafael-trevisan commented Jul 23, 2020

I can see a strange pattern between these failures and around 4 sessions (all from JDBC Thin Client) getting stuck while running the following command:

SELECT TEXT, ITEM_TYPE FROM TABLE((:B1 ).GET_LINES(:B2 , :B3 ))

Every time utPLSQL retries "Retrying (n)" I can see another 3 new sessions running the same funny select. The wait event for these sessions is PL/SQL lock timer 🤔

Once utPLSQL finally fails, the sessions will be still "Active" for around 2 or 3 minutes.

@rafael-trevisan
Copy link

Ha! The number of hanging session is directly related to the number of reports I am generating in my tests!

In my case, I am using the following reports:

-f=ut_coverage_sonar_reporter -o=log/coverage.xml \
-f=ut_sonar_test_reporter     -o=log/test-results.xml \
-f=ut_documentation_reporter  -o=log/test-results.log -s

Another interesting finding is that I have only 1 report, I get the error once let's say every 10 times I run. With the 3 reports altogether I get the error every 2 or 3 times. Hope that makes sense and helps narrow down this nasty bug.

@tplazaro
Copy link

Is there any update on this one, any workaround? I'm having the same issue :(

@pesse
Copy link
Member

pesse commented Dec 14, 2020

This issue is very likely an Oracle bug and highly related to your used Oracle database, used driver etc.
Please provide

  • your database version/platform
  • your jdbc driver version
  • whether the problem occurs reproducably

@pesse
Copy link
Member

pesse commented Jan 27, 2021

@rafael-trevisan @tplazaro can you try the steps mentioned in https://github.com/utPLSQL/utPLSQL/issues/1121?

Either access the file $JAVA_HOME/jre/lib/security/java.security and change securerandom.source=file:/dev/random to securerandom.source=file:/dev/urandom

Or set up JAVA_TOOL_OPTIONS:

export JAVA_TOOL_OPTIONS='-Djava.security.egd=file:/dev/./urandom -Dsecurerandom.source=file:/dev/./urandom'

@pesse
Copy link
Member

pesse commented Jan 28, 2021

After sleeping over it I have little hopes this is the problem source (I'm actually quite convinced it's not)
I would still love to get some feedback from you folks, since this is something I cannot test/reproduce myself.

@dmpe
Copy link
Contributor

dmpe commented Jan 28, 2021

Hi, I am sorry but cannot test it anymore :(

@OsBlaineOra
Copy link

I am unable to run any code coverage reporter from the cli 9/10 times.

Switching back to running project based coverage through a .sql script requires that I list out every file to be checked. (Unless I'm wrong about that).

Here's how I'm running it from .sql

Create a .sql script to run coverage for corbertura and sonar. (If you're not using sqlcl, remove the !rm... lines)

utplsql_coverage.sql

set serveroutput on 
set timing off
set linesize 32767
set termout off
set feedback off
set verify off

!rm cobertura_coverage.xml
spool cobertura_coverage.xml
begin
  ut.run(
    ut_varchar2_list('unit_test'),
    ut_coverage_cobertura_reporter(),
    a_source_file_mappings =>
      ut_file_mapper.build_file_mappings(
        a_file_paths  => ut_varchar2_list('&1')
        ),
    a_test_file_mappings =>
      ut_file_mapper.build_file_mappings(
        a_file_paths  => ut_varchar2_list('&2')
      )
  );
end;
/
spool off

!rm sonar_coverage.xml
spool sonar_coverage.xml
begin
  ut.run(
    ut_varchar2_list('unit_test'),
    ut_coverage_sonar_reporter(),
    a_source_file_mappings =>
      ut_file_mapper.build_file_mappings(
        a_file_paths  => ut_varchar2_list('&1')
        ),
    a_test_file_mappings =>
      ut_file_mapper.build_file_mappings(
        a_file_paths  => ut_varchar2_list('&2')
      )
  );
end;
/
spool off
exit;

My source files are in source/plsql, my tests are in tests/utplsql

Create an env for my source and test list.

export source_files=$(find source/plsql/ -type f \( -name "*.pkb" -o -name "*.fnc" -o -name "*.prc" -o -name "*.tpb" -o -name "*.trg" \) -printf "'%p'\0" | tr '\0' , | sed 's/,$//')
echo $source_files

export test_files=$(find tests/utplsql/ -type f \( -name "*.pkb" -o -name "*.fnc" -o -name "*.prc" -o -name "*.tpb" -o -name "*.trg" \) -printf "'%p'\0" | tr '\0' , | sed 's/,$//')
echo $test_files

Run the script

sql <user>/<password>@<connect_string> @utplsql_coverage.sql $source_files $test_files

I can use this in my CICD pipeline until we can find a fix for the stuck problem.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

Successfully merging a pull request may close this issue.

9 participants