During some monitoring work on a WebLogic 12.1.3 environment, I noticed that top shows the process 12013 using 800% CPU (We have a 12 CPU machine) and had to find out what’s happening !!<\/p>\n
I used top to check the process ID and then the threads taking the CPU<\/p>\n
ps -ef |grep 12013\n\nweblogic 12013 11836 23 Apr03 ? 03:38:31 \/app\/weblogic\/Java\/jdk\/bin\/java -server -Xms2048m -Xmx2048m -XX:MaxMetaspaceSize=512m -Dweblogic.Name=msD2-02 -Djava.security.policy=\/app\/weblogic\/Middleware\/wlserver\/server\/lib\/weblogic.policy -Dweblogic.ProductionModeEnabled=true -Dweblogic.system.BootIdentityFile=\/app\/weblogic\/domains\/myDomain\/servers\/msD2-02\/data\/nodemanager\/boot.properties -Dweblogic.nodemanager.ServiceEnabled=true -Dweblogic.nmservice.RotationEnabled=true -Dweblogic.security.SSL.ignoreHostnameVerification=false -Dweblogic.ReverseDNSAllowed=false -Xms2048m -Xmx2048m -XX:MaxMetaspaceSize=512m -Dcom.sun.xml.ws.api.streaming.XMLStreamReaderFactory.woodstox=true -Dcom.sun.xml.ws.api.streaming.XMLStreamWriterFactory.woodstox=true -Djava.io.tmpdir=\/app\/weblogic\/tmp\/myDomain\/msD2-02 -Ddomain.home=\/app\/weblogic\/domains\/myDomain -Dweblogic.nodemanager.ServiceEnabled=true -Dweblogic.security.SSL.protocolVersion=TLS1 -Dweblogic.security.disableNullCipher=true -Djava.security.egd=file:\/\/\/dev\/.\/urandom -Dweblogic.security.allowCryptoJDefaultJCEVerification=true -Dweblogic.nodemanager.ServiceEnabled=true -Djava.endorsed.dirs=\/app\/weblogic\/Java\/jdk1.8.0_45\/jre\/lib\/endorsed:\/app\/weblogic\/Middleware\/wlserver\/..\/oracle_common\/modules\/endorsed -da -Dwls.home=\/app\/weblogic\/Middleware\/wlserver\/server -Dweblogic.home=\/app\/weblogic\/Middleware\/wlserver\/server -Dweblogic.management.server=https:\/\/10.10.1.1:7001 -Dweblogic.utils.cmm.lowertier.ServiceDisabled=true weblogic.Server\n<\/pre>\ntop -H -b -p 12013\n\n PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND\n12624 weblogic 20 0 10.5g 3.6g 30m S 83.1 11.5 18053:38 java\n 2540 weblogic 20 0 10.5g 3.6g 30m S 79.4 11.5 3:11.62 java\n 2591 weblogic 20 0 10.5g 3.6g 30m S 79.4 11.5 3:08.02 java\n12436 weblogic 20 0 10.5g 3.6g 30m S 9.2 11.5 3:14.59 java\n<\/pre>\nNow that I have the Unix threads PID, I will get the thread dump of the WebLogic Server process and try to figure out the culprits. To generate the thread dump I used the jstack java tool.<\/p>\n
jstack -l 12013 > $HOME\/jstack_100_CPU.txt\n<\/pre>\nThe Thread dump provides the threads with the ID in Hexadecimal, thus those provided from the top needs to be converted:<\/p>\n
[myDomain]$ printf '%xn' 12624\n3150\n[myDomain]$ printf '%xn' 2540\n9ec\n[myDomain]$ printf '%xn' 2591\na1f\n[myDomain]$\n<\/pre>\n\"LDAPConnThread-1172 ldaps:\/\/ldapserver.dbi-lab.com:3636\" #167119 daemon prio=5 os_prio=0 tid=0x00007f5444087800 nid=0xa1f runnable [0x00007f5409e88000]\n java.lang.Thread.State: RUNNABLE\n at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)\n at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)\n at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:79)\n at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)\n - locked (a sun.nio.ch.Util$2)\n - locked (a java.util.Collections$UnmodifiableSet)\n - locked (a sun.nio.ch.EPollSelectorImpl)\n at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)\n at weblogic.socket.NIOSocketMuxer$NIOInputStream.readInternal(NIOSocketMuxer.java:802)\n at weblogic.socket.NIOSocketMuxer$NIOInputStream.read(NIOSocketMuxer.java:746)\n at weblogic.socket.NIOSocketMuxer$NIOInputStream.read(NIOSocketMuxer.java:729)\n at weblogic.socket.JSSEFilterImpl.readFromNetwork(JSSEFilterImpl.java:462)\n at weblogic.socket.JSSEFilterImpl.read(JSSEFilterImpl.java:424)\n at weblogic.socket.JSSESocket$JSSEInputStream.read(JSSESocket.java:98)\n at java.io.BufferedInputStream.fill(BufferedInputStream.java:246)\n at java.io.BufferedInputStream.read(BufferedInputStream.java:265)\n - locked (a java.io.BufferedInputStream)\n at netscape.ldap.ber.stream.BERElement.getElement(Unknown Source)\n at netscape.ldap.LDAPConnThread.run(Unknown Source)\n at java.lang.Thread.run(Thread.java:745)\n\n \n\"[STUCK] ExecuteThread: '302' for queue: 'weblogic.kernel.Default (self-tuning)'\" #326 daemon prio=1 os_prio=0 tid=0x00007f5560376000 nid=0x3150 runnable [0x00007f543636e000]\n java.lang.Thread.State: RUNNABLE\n at java.io.InputStream.skip(InputStream.java:224)\n at weblogic.utils.http.HttpChunkInputStream.skip(HttpChunkInputStream.java:287)\n at weblogic.utils.http.HttpChunkInputStream.skipAllChunk(HttpChunkInputStream.java:497)\n at weblogic.servlet.internal.ServletInputStreamImpl.ensureChunkedConsumed(ServletInputStreamImpl.java:51)\n at weblogic.servlet.internal.ServletRequestImpl.skipUnreadBody(ServletRequestImpl.java:217)\n at weblogic.servlet.internal.ServletRequestImpl.reset(ServletRequestImpl.java:169)\n at weblogic.servlet.internal.HttpConnectionHandler.prepareRequestForReuse(HttpConnectionHandler.java:258)\n at weblogic.servlet.internal.HttpConnectionHandler.requeue(HttpConnectionHandler.java:665)\n at weblogic.servlet.internal.VirtualConnection.requeue(VirtualConnection.java:332)\n at weblogic.servlet.internal.ServletResponseImpl.send(ServletResponseImpl.java:1657)\n at weblogic.servlet.internal.ServletRequestImpl.run(ServletRequestImpl.java:1582)\n at weblogic.servlet.provider.ContainerSupportProviderImpl$WlsRequestExecutor.run(ContainerSupportProviderImpl.java:255)\n at weblogic.work.ExecuteThread.execute(ExecuteThread.java:311)\n at weblogic.work.ExecuteThread.run(ExecuteThread.java:263)\n\n\"LDAPConnThread-1171 ldaps:\/\/ldapserver.dbi-lab.com:3636\"\" #167118 daemon prio=5 os_prio=0 tid=0x00007f5544048000 nid=0x9ec runnable [0x00007f540a28c000]\n java.lang.Thread.State: RUNNABLE\n at sun.nio.ch.EPollArrayWrapper.interrupt(Native Method)\n at sun.nio.ch.EPollArrayWrapper.interrupt(EPollArrayWrapper.java:317)\n at sun.nio.ch.EPollSelectorImpl.wakeup(EPollSelectorImpl.java:193)\n - locked (a java.lang.Object)\n at java.nio.channels.spi.AbstractSelector$1.interrupt(AbstractSelector.java:213)\n at java.nio.channels.spi.AbstractSelector.begin(AbstractSelector.java:219)\n at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:78)\n at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)\n - locked (a sun.nio.ch.Util$2)\n - locked (a java.util.Collections$UnmodifiableSet)\n - locked (a sun.nio.ch.EPollSelectorImpl)\n at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)\n at weblogic.socket.NIOSocketMuxer$NIOInputStream.readInternal(NIOSocketMuxer.java:802)\n at weblogic.socket.NIOSocketMuxer$NIOInputStream.read(NIOSocketMuxer.java:746)\n at weblogic.socket.NIOSocketMuxer$NIOInputStream.read(NIOSocketMuxer.java:729)\n at weblogic.socket.JSSEFilterImpl.readFromNetwork(JSSEFilterImpl.java:462)\n at weblogic.socket.JSSEFilterImpl.read(JSSEFilterImpl.java:424)\n at weblogic.socket.JSSESocket$JSSEInputStream.read(JSSESocket.java:98)\n at java.io.BufferedInputStream.fill(BufferedInputStream.java:246)\n at java.io.BufferedInputStream.read(BufferedInputStream.java:265)\n - locked (a java.io.BufferedInputStream)\n at netscape.ldap.ber.stream.BERElement.getElement(Unknown Source)\n at netscape.ldap.LDAPConnThread.run(Unknown Source)\n at java.lang.Thread.run(Thread.java:745)\n<\/pre>\nThis looks to be an issue with the LDAP server or the network.<\/p>\n
The WebLogic java threads loops on the LDAP connection to get data. This loop should fail in a timeout depending on the Authentication provider LDAP connection timeout settings. But it looks there is an issue with the JDK that re-initiates the connection in the sun.nio.ch.EPollArrayWrapper class.<\/p>\n
Once the LDAP connection was back the WebLogic Domain could be restarted properly and the service was back.<\/p>\n
A possible workaround could be to not use the weblogic.socket.NIOSocketMuxer but the weblogic.socket.PosixSocketMuxer. This workaround is described in Oracle Support note:2128032.1
\nOr a better solution is to upgrade to the latest WebLogic Software version and apply the latest PSU where this issue is fixed.<\/p>\n","protected":false},"excerpt":{"rendered":"During some monitoring work on a WebLogic 12.1.3 environment, I noticed that top shows the process 12013 using 800% CPU (We have a 12 CPU machine) and had to find out what’s happening !!<\/p>\n","protected":false},"author":40,"featured_media":0,"comment_status":"open","ping_status":"open","sticky":false,"template":"","format":"standard","meta":{"_acf_changed":false,"footnotes":""},"categories":[197],"tags":[307],"type_dbi":[],"class_list":["post-13502","post","type-post","status-publish","format-standard","hentry","category-application-integration-middleware","tag-oracle-weblogic"],"acf":[],"yoast_head":"\n
WebLogic Server process takes 100% CPU - dbi Blog<\/title>\n<meta name=\"robots\" content=\"index, follow, max-snippet:-1, max-image-preview:large, max-video-preview:-1\" \/>\n<link rel=\"canonical\" href=\"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/\" \/>\n<meta property=\"og:locale\" content=\"en_US\" \/>\n<meta property=\"og:type\" content=\"article\" \/>\n<meta property=\"og:title\" content=\"WebLogic Server process takes 100% CPU\" \/>\n<meta property=\"og:description\" content=\"During some monitoring work on a WebLogic 12.1.3 environment, I noticed that top shows the process 12013 using 800% CPU (We have a 12 CPU machine) and had to find out what's happening !!\" \/>\n<meta property=\"og:url\" content=\"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/\" \/>\n<meta property=\"og:site_name\" content=\"dbi Blog\" \/>\n<meta property=\"article:published_time\" content=\"2020-02-26T15:09:40+00:00\" \/>\n<meta property=\"article:modified_time\" content=\"2024-09-10T15:32:19+00:00\" \/>\n<meta name=\"author\" content=\"Middleware Team\" \/>\n<meta name=\"twitter:card\" content=\"summary_large_image\" \/>\n<meta name=\"twitter:label1\" content=\"Written by\" \/>\n\t<meta name=\"twitter:data1\" content=\"Middleware Team\" \/>\n\t<meta name=\"twitter:label2\" content=\"Est. reading time\" \/>\n\t<meta name=\"twitter:data2\" content=\"5 minutes\" \/>\n<script type=\"application\/ld+json\" class=\"yoast-schema-graph\">{\"@context\":\"https:\/\/schema.org\",\"@graph\":[{\"@type\":\"Article\",\"@id\":\"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/#article\",\"isPartOf\":{\"@id\":\"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/\"},\"author\":{\"name\":\"Middleware Team\",\"@id\":\"https:\/\/www.dbi-services.com\/blog\/#\/schema\/person\/8d8563acfc6e604cce6507f45bac0ea1\"},\"headline\":\"WebLogic Server process takes 100% CPU\",\"datePublished\":\"2020-02-26T15:09:40+00:00\",\"dateModified\":\"2024-09-10T15:32:19+00:00\",\"mainEntityOfPage\":{\"@id\":\"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/\"},\"wordCount\":236,\"commentCount\":0,\"keywords\":[\"Oracle WebLogic\"],\"articleSection\":[\"Application integration & Middleware\"],\"inLanguage\":\"en-US\",\"potentialAction\":[{\"@type\":\"CommentAction\",\"name\":\"Comment\",\"target\":[\"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/#respond\"]}]},{\"@type\":\"WebPage\",\"@id\":\"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/\",\"url\":\"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/\",\"name\":\"WebLogic Server process takes 100% CPU - dbi Blog\",\"isPartOf\":{\"@id\":\"https:\/\/www.dbi-services.com\/blog\/#website\"},\"datePublished\":\"2020-02-26T15:09:40+00:00\",\"dateModified\":\"2024-09-10T15:32:19+00:00\",\"author\":{\"@id\":\"https:\/\/www.dbi-services.com\/blog\/#\/schema\/person\/8d8563acfc6e604cce6507f45bac0ea1\"},\"breadcrumb\":{\"@id\":\"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/#breadcrumb\"},\"inLanguage\":\"en-US\",\"potentialAction\":[{\"@type\":\"ReadAction\",\"target\":[\"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/\"]}]},{\"@type\":\"BreadcrumbList\",\"@id\":\"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/#breadcrumb\",\"itemListElement\":[{\"@type\":\"ListItem\",\"position\":1,\"name\":\"Accueil\",\"item\":\"https:\/\/www.dbi-services.com\/blog\/\"},{\"@type\":\"ListItem\",\"position\":2,\"name\":\"WebLogic Server process takes 100% CPU\"}]},{\"@type\":\"WebSite\",\"@id\":\"https:\/\/www.dbi-services.com\/blog\/#website\",\"url\":\"https:\/\/www.dbi-services.com\/blog\/\",\"name\":\"dbi Blog\",\"description\":\"\",\"potentialAction\":[{\"@type\":\"SearchAction\",\"target\":{\"@type\":\"EntryPoint\",\"urlTemplate\":\"https:\/\/www.dbi-services.com\/blog\/?s={search_term_string}\"},\"query-input\":{\"@type\":\"PropertyValueSpecification\",\"valueRequired\":true,\"valueName\":\"search_term_string\"}}],\"inLanguage\":\"en-US\"},{\"@type\":\"Person\",\"@id\":\"https:\/\/www.dbi-services.com\/blog\/#\/schema\/person\/8d8563acfc6e604cce6507f45bac0ea1\",\"name\":\"Middleware Team\",\"image\":{\"@type\":\"ImageObject\",\"inLanguage\":\"en-US\",\"@id\":\"https:\/\/secure.gravatar.com\/avatar\/ddcae7ba0f9d1a0e7ae707f0e689e4a9c95bb48ec49c8e6d9cc86d43f4121cb6?s=96&d=mm&r=g\",\"url\":\"https:\/\/secure.gravatar.com\/avatar\/ddcae7ba0f9d1a0e7ae707f0e689e4a9c95bb48ec49c8e6d9cc86d43f4121cb6?s=96&d=mm&r=g\",\"contentUrl\":\"https:\/\/secure.gravatar.com\/avatar\/ddcae7ba0f9d1a0e7ae707f0e689e4a9c95bb48ec49c8e6d9cc86d43f4121cb6?s=96&d=mm&r=g\",\"caption\":\"Middleware Team\"},\"url\":\"https:\/\/www.dbi-services.com\/blog\/author\/middleware-team\/\"}]}<\/script>\n<!-- \/ Yoast SEO Premium plugin. -->","yoast_head_json":{"title":"WebLogic Server process takes 100% CPU - dbi Blog","robots":{"index":"index","follow":"follow","max-snippet":"max-snippet:-1","max-image-preview":"max-image-preview:large","max-video-preview":"max-video-preview:-1"},"canonical":"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/","og_locale":"en_US","og_type":"article","og_title":"WebLogic Server process takes 100% CPU","og_description":"During some monitoring work on a WebLogic 12.1.3 environment, I noticed that top shows the process 12013 using 800% CPU (We have a 12 CPU machine) and had to find out what's happening !!","og_url":"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/","og_site_name":"dbi Blog","article_published_time":"2020-02-26T15:09:40+00:00","article_modified_time":"2024-09-10T15:32:19+00:00","author":"Middleware Team","twitter_card":"summary_large_image","twitter_misc":{"Written by":"Middleware Team","Est. reading time":"5 minutes"},"schema":{"@context":"https:\/\/schema.org","@graph":[{"@type":"Article","@id":"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/#article","isPartOf":{"@id":"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/"},"author":{"name":"Middleware Team","@id":"https:\/\/www.dbi-services.com\/blog\/#\/schema\/person\/8d8563acfc6e604cce6507f45bac0ea1"},"headline":"WebLogic Server process takes 100% CPU","datePublished":"2020-02-26T15:09:40+00:00","dateModified":"2024-09-10T15:32:19+00:00","mainEntityOfPage":{"@id":"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/"},"wordCount":236,"commentCount":0,"keywords":["Oracle WebLogic"],"articleSection":["Application integration & Middleware"],"inLanguage":"en-US","potentialAction":[{"@type":"CommentAction","name":"Comment","target":["https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/#respond"]}]},{"@type":"WebPage","@id":"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/","url":"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/","name":"WebLogic Server process takes 100% CPU - dbi Blog","isPartOf":{"@id":"https:\/\/www.dbi-services.com\/blog\/#website"},"datePublished":"2020-02-26T15:09:40+00:00","dateModified":"2024-09-10T15:32:19+00:00","author":{"@id":"https:\/\/www.dbi-services.com\/blog\/#\/schema\/person\/8d8563acfc6e604cce6507f45bac0ea1"},"breadcrumb":{"@id":"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/#breadcrumb"},"inLanguage":"en-US","potentialAction":[{"@type":"ReadAction","target":["https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/"]}]},{"@type":"BreadcrumbList","@id":"https:\/\/www.dbi-services.com\/blog\/weblogic-server-process-takes-100-cpu\/#breadcrumb","itemListElement":[{"@type":"ListItem","position":1,"name":"Accueil","item":"https:\/\/www.dbi-services.com\/blog\/"},{"@type":"ListItem","position":2,"name":"WebLogic Server process takes 100% CPU"}]},{"@type":"WebSite","@id":"https:\/\/www.dbi-services.com\/blog\/#website","url":"https:\/\/www.dbi-services.com\/blog\/","name":"dbi Blog","description":"","potentialAction":[{"@type":"SearchAction","target":{"@type":"EntryPoint","urlTemplate":"https:\/\/www.dbi-services.com\/blog\/?s={search_term_string}"},"query-input":{"@type":"PropertyValueSpecification","valueRequired":true,"valueName":"search_term_string"}}],"inLanguage":"en-US"},{"@type":"Person","@id":"https:\/\/www.dbi-services.com\/blog\/#\/schema\/person\/8d8563acfc6e604cce6507f45bac0ea1","name":"Middleware Team","image":{"@type":"ImageObject","inLanguage":"en-US","@id":"https:\/\/secure.gravatar.com\/avatar\/ddcae7ba0f9d1a0e7ae707f0e689e4a9c95bb48ec49c8e6d9cc86d43f4121cb6?s=96&d=mm&r=g","url":"https:\/\/secure.gravatar.com\/avatar\/ddcae7ba0f9d1a0e7ae707f0e689e4a9c95bb48ec49c8e6d9cc86d43f4121cb6?s=96&d=mm&r=g","contentUrl":"https:\/\/secure.gravatar.com\/avatar\/ddcae7ba0f9d1a0e7ae707f0e689e4a9c95bb48ec49c8e6d9cc86d43f4121cb6?s=96&d=mm&r=g","caption":"Middleware Team"},"url":"https:\/\/www.dbi-services.com\/blog\/author\/middleware-team\/"}]}},"_links":{"self":[{"href":"https:\/\/www.dbi-services.com\/blog\/wp-json\/wp\/v2\/posts\/13502","targetHints":{"allow":["GET"]}}],"collection":[{"href":"https:\/\/www.dbi-services.com\/blog\/wp-json\/wp\/v2\/posts"}],"about":[{"href":"https:\/\/www.dbi-services.com\/blog\/wp-json\/wp\/v2\/types\/post"}],"author":[{"embeddable":true,"href":"https:\/\/www.dbi-services.com\/blog\/wp-json\/wp\/v2\/users\/40"}],"replies":[{"embeddable":true,"href":"https:\/\/www.dbi-services.com\/blog\/wp-json\/wp\/v2\/comments?post=13502"}],"version-history":[{"count":1,"href":"https:\/\/www.dbi-services.com\/blog\/wp-json\/wp\/v2\/posts\/13502\/revisions"}],"predecessor-version":[{"id":34688,"href":"https:\/\/www.dbi-services.com\/blog\/wp-json\/wp\/v2\/posts\/13502\/revisions\/34688"}],"wp:attachment":[{"href":"https:\/\/www.dbi-services.com\/blog\/wp-json\/wp\/v2\/media?parent=13502"}],"wp:term":[{"taxonomy":"category","embeddable":true,"href":"https:\/\/www.dbi-services.com\/blog\/wp-json\/wp\/v2\/categories?post=13502"},{"taxonomy":"post_tag","embeddable":true,"href":"https:\/\/www.dbi-services.com\/blog\/wp-json\/wp\/v2\/tags?post=13502"},{"taxonomy":"type","embeddable":true,"href":"https:\/\/www.dbi-services.com\/blog\/wp-json\/wp\/v2\/type_dbi?post=13502"}],"curies":[{"name":"wp","href":"https:\/\/api.w.org\/{rel}","templated":true}]}}