RonWeasley
Active Member
Hi,
We have a JDE lab instance running application 9.1 with tools 9.1.5.7. Our WebLogic 12c based HTML server is having unexplained performance issues. Each time we login, it takes more than a 1.5 mins to come to home screen. Each time a new application is opened, it takes almost an eternity to open it up. Subsequent openings are fast. Unfortunately, the debug log hardly reveals anything with unexplained time gaps ranging from 15 secs to 40 secs at various stages. Here are examples.
This is a lab and we don't have the permission to create SR and pursue it with Oracle. Any idea on what might be wrong? Please note that our server is pretty good by lab standards (32 GB RAM, 2.8 GHz Intel Xeon CPU 4 cores). Each time its slow, I checked the performance through windows task manager and hardly see any significant load in the system. There are no error messages in the system as well.
Any idea on how to proceed troubleshooting would be really appreciated...
Logs from Login Activity (Debug - Technical format with threads)
-------------------------------------------------------------------------
Excerpt #1 (unexplained 37 second gap in debug log)
------------------------------------------------------------
24 Feb 2016 13:11:00,225 [Line -1] [Timer-3] [DEBUG ] - [JDBJ] JDBj service [UDC table] found key [98-JS- D-null] in the cache, returned 1 records.
24 Feb 2016 13:11:00,225 [Line -1] [Timer-3] [DEBUG ] - [JDBJ] JDBj service [Environment Lookup Service] getting key [JDV910].
24 Feb 2016 13:11:00,225 [Line -1] [Timer-3] [DEBUG ] - [JDBJ] JDBj service [Environment Lookup Service] found key [JDV910] in the cache, returned 1 records.
24 Feb 2016 13:11:00,225 [Line -1] [Timer-3] [DEBUG ] - [JDBJ] JDBj service [Table group lookup] getting key [98-JS-UDC].
24 Feb 2016 13:11:00,225 [Line -1] [Timer-3] [DEBUG ] - [JDBJ] JDBj service [Table group lookup] found key [98-JS-UDC] in the cache, returned 0 records.
24 Feb 2016 13:11:37,131 [Line -1] [[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [KERNEL] complete
24 Feb 2016 13:11:37,146 [Line -1] [[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [JDBJ] Execute Business Function:LeftJustifyUDCValue, Host: E1NJINTES01:6016
24 Feb 2016 13:11:37,146 [Line -1] [[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [KERNEL] execute
24 Feb 2016 13:11:37,146 [Line -1] [[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [KERNEL] complete
Excerpt #2 (unexplained 15 sec gap - I see multiple gaps like this)
---------------------------------------------------------------------------
24 Feb 2016 13:12:27,588 [Line -1] [[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [JDBJ] Checking exclusive application security, User = TESTUSER, Environment: JDV910, Role:*ALL
24 Feb 2016 13:12:27,588 [Line -1] [[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [JDBJ] No application name is specified. Exclusive application security is not in effect., User = TESTUSER, Environment: JDV910, Role:*ALL
24 Feb 2016 13:12:27,588 [Line -1] [[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [JDBJ] Setting connection runtime property No Where Paren to false.
24 Feb 2016 13:12:42,603 [Line -1] [[ACTIVE] ExecuteThread: '2' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [MAFLETCONTAINER] A URL of /WatchlistManagerService.mafService?e1.namespace=&e1.service=WatchlistManagerService&RENDER_MAFLET=E1Menu&cmd=getNamesMenu&e1.state=maximized&e1.mode=view was Requested
24 Feb 2016 13:12:42,635 [Line -1] [[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)'] [APP ] - [RUNTIME] WL:WatchlistManager:getManager:Obtaining the WatchlistManager singleton
24 Feb 2016 13:12:42,635 [Line -1] [[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)'] [APP ] - [RUNTIME] WL:WLMetaDataManager:loadWatchlistForUserByDropdown: :TESTUSER
24 Feb 2016 13:12:42,635 [Line -1] [[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [JAS] Calling server component com.jdedwards.jas.JDEQueries#processServerRequest
Hope this gives you some idea as I am running out of it! Thanks in advance for your help...
Regards...
We have a JDE lab instance running application 9.1 with tools 9.1.5.7. Our WebLogic 12c based HTML server is having unexplained performance issues. Each time we login, it takes more than a 1.5 mins to come to home screen. Each time a new application is opened, it takes almost an eternity to open it up. Subsequent openings are fast. Unfortunately, the debug log hardly reveals anything with unexplained time gaps ranging from 15 secs to 40 secs at various stages. Here are examples.
This is a lab and we don't have the permission to create SR and pursue it with Oracle. Any idea on what might be wrong? Please note that our server is pretty good by lab standards (32 GB RAM, 2.8 GHz Intel Xeon CPU 4 cores). Each time its slow, I checked the performance through windows task manager and hardly see any significant load in the system. There are no error messages in the system as well.
Any idea on how to proceed troubleshooting would be really appreciated...
Logs from Login Activity (Debug - Technical format with threads)
-------------------------------------------------------------------------
Excerpt #1 (unexplained 37 second gap in debug log)
------------------------------------------------------------
24 Feb 2016 13:11:00,225 [Line -1] [Timer-3] [DEBUG ] - [JDBJ] JDBj service [UDC table] found key [98-JS- D-null] in the cache, returned 1 records.
24 Feb 2016 13:11:00,225 [Line -1] [Timer-3] [DEBUG ] - [JDBJ] JDBj service [Environment Lookup Service] getting key [JDV910].
24 Feb 2016 13:11:00,225 [Line -1] [Timer-3] [DEBUG ] - [JDBJ] JDBj service [Environment Lookup Service] found key [JDV910] in the cache, returned 1 records.
24 Feb 2016 13:11:00,225 [Line -1] [Timer-3] [DEBUG ] - [JDBJ] JDBj service [Table group lookup] getting key [98-JS-UDC].
24 Feb 2016 13:11:00,225 [Line -1] [Timer-3] [DEBUG ] - [JDBJ] JDBj service [Table group lookup] found key [98-JS-UDC] in the cache, returned 0 records.
24 Feb 2016 13:11:37,131 [Line -1] [[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [KERNEL] complete
24 Feb 2016 13:11:37,146 [Line -1] [[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [JDBJ] Execute Business Function:LeftJustifyUDCValue, Host: E1NJINTES01:6016
24 Feb 2016 13:11:37,146 [Line -1] [[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [KERNEL] execute
24 Feb 2016 13:11:37,146 [Line -1] [[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [KERNEL] complete
Excerpt #2 (unexplained 15 sec gap - I see multiple gaps like this)
---------------------------------------------------------------------------
24 Feb 2016 13:12:27,588 [Line -1] [[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [JDBJ] Checking exclusive application security, User = TESTUSER, Environment: JDV910, Role:*ALL
24 Feb 2016 13:12:27,588 [Line -1] [[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [JDBJ] No application name is specified. Exclusive application security is not in effect., User = TESTUSER, Environment: JDV910, Role:*ALL
24 Feb 2016 13:12:27,588 [Line -1] [[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [JDBJ] Setting connection runtime property No Where Paren to false.
24 Feb 2016 13:12:42,603 [Line -1] [[ACTIVE] ExecuteThread: '2' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [MAFLETCONTAINER] A URL of /WatchlistManagerService.mafService?e1.namespace=&e1.service=WatchlistManagerService&RENDER_MAFLET=E1Menu&cmd=getNamesMenu&e1.state=maximized&e1.mode=view was Requested
24 Feb 2016 13:12:42,635 [Line -1] [[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)'] [APP ] - [RUNTIME] WL:WatchlistManager:getManager:Obtaining the WatchlistManager singleton
24 Feb 2016 13:12:42,635 [Line -1] [[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)'] [APP ] - [RUNTIME] WL:WLMetaDataManager:loadWatchlistForUserByDropdown: :TESTUSER
24 Feb 2016 13:12:42,635 [Line -1] [[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] - [JAS] Calling server component com.jdedwards.jas.JDEQueries#processServerRequest
Hope this gives you some idea as I am running out of it! Thanks in advance for your help...
Regards...