Guest User

Hi. I would like to ask a question regarding GET-CONNECTION DataSource.getConnection. as I noticed connection pull at DB frequently, our server had been closed multiple times. Based on information below, I have been trying to find the root cause, however, I could not find the right solution. I noticed delay between #91 and #92. As such delay occurred multiple time in a short period, the connection pull continued to occur at DB server. Thanks.

912][1,378][ 0] SQL-EXECUTE{11} [1,378 ms] param1:[ , ,000,1,] param2:[‘201’] 951][ 39][ 0] FETCH[12/133] [39 ms] 886][1,935][ 0] COMMIT 886][ 0][ 0] CLOSE-CONNECTION 203][4,317][ 0] GET-CONNECTION DataSource.getConnection[sjp_pjt] 203][ 0][ 0] SET_AUTO_COMMIT: false 203][ 0][ 0] SQL-PREPARE_STMT{12} SELECT PROJ_TYPE AS DATA1, CODE_NAME AS DATA2, LARGE_CLASS AS DATA3, MIDDLE_CLASS AS DATA4, SMALL_CLASS AS DATA5 FROM PROJTYPE WHERE USE_YN = # AND PROJ_YEAR = ? AND LARGE_CLASS in(‘$’,‘$’,‘$’,‘$’,‘$’, ‘$’, ‘$’) AND MIDDLE_CLASS = ‘$’ AND SMALL_CLASS = ‘$’ ORDER BY PROJ_TYPE param1:[1,010,020,030,040,050,090,100,000,0000,] jndi:[sjp_pjt] 516][2,313][ 0] SQL-EXECUTE{12} [2,313 ms] param1:[1,010,020,030,040,050,090,100,000,0000,] param2:[‘2015’] 516][ 0][ 0] FETCH[7/140] 081][ 565][ 0] COMMIT 081][ 0][ 0] CLOSE-CONNECTION 137][15,056][ 0] GET-CONNECTION DataSource.getConnection[sjp_pjt] 137][ 0][ 0] SET_AUTO_COMMIT: false 138][ 1][ 0] SQL-PREPARE_STMT{13} SELECT CODE_ID AS CD, NVL AS CD_NM, NVL AS CD_DESC FROM CODEMST WHERE LARGE_CLASS = ? AND MIDDLE_CLASS <> ‘$’ AND USE_YN = # ORDER BY SORT_ORDER, CODE_ID param1:[ , ,000,1,] jndi:[sjp_pjt] 454][ 316][ 0] SQL-EXECUTE{13} [316 ms] param1:[ , ,000,1,] param2:[‘202’] 454][ 0][ 0] FETCH[3/143] 769][1,315][ 0] COMMIT 769][ 0][ 0] CLOSE-CONNECTION 769][ 0][ 0] GET-CONNECTION DataSource.getConnection[sjp_pjt] 769][ 0][ 0] SET_AUTO_COMMIT: false 769][ 0][ 0] SQL-PREPARE_STMT{14} SELECT CODE_ID AS CD, NVL AS CD_NM, NVL AS CD_DESC FROM CODEMST WHERE LARGE_CLASS = ? AND MIDDLE_CLASS <> ‘$’ AND USE_YN = # ORDER BY SORT_ORDER, CODE_ID param1:[ , ,000,1,] jndi:[sjp_pjt]
Khalid Saeed

It is just a gap time you see between 92 and 93. If getConnection takes longer, you could see the getConnection time like below. (sample)
137][15,056][ 0] GET-CONNECTION DataSource.getConnection[sjp_pjt] [1,2020 ms]
It is to recreate time in case the connection pool is not sufficient inside the transcation. It might be taking much time during the connection close. To see more detail, you might want to look at the service dump or threa dump.