Return-Path: X-Original-To: archive-asf-public-internal@cust-asf2.ponee.io Delivered-To: archive-asf-public-internal@cust-asf2.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by cust-asf2.ponee.io (Postfix) with ESMTP id 05BEC200BC1 for ; Wed, 16 Nov 2016 18:04:12 +0100 (CET) Received: by cust-asf.ponee.io (Postfix) id 043A6160B08; Wed, 16 Nov 2016 17:04:12 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id 268F8160B02 for ; Wed, 16 Nov 2016 18:04:11 +0100 (CET) Received: (qmail 83631 invoked by uid 500); 16 Nov 2016 17:04:10 -0000 Mailing-List: contact issues-help@jmeter.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: issues@jmeter.apache.org Delivered-To: mailing list issues@jmeter.apache.org Received: (qmail 83620 invoked by uid 99); 16 Nov 2016 17:04:10 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd1-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 16 Nov 2016 17:04:10 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd1-us-west.apache.org (ASF Mail Server at spamd1-us-west.apache.org) with ESMTP id C4689C3192 for ; Wed, 16 Nov 2016 17:04:09 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd1-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -1.199 X-Spam-Level: X-Spam-Status: No, score=-1.199 tagged_above=-999 required=6.31 tests=[KAM_ASCII_DIVIDERS=0.8, KAM_LAZY_DOMAIN_SECURITY=1, RP_MATCHES_RCVD=-2.999] autolearn=disabled Received: from mx1-lw-eu.apache.org ([10.40.0.8]) by localhost (spamd1-us-west.apache.org [10.40.0.7]) (amavisd-new, port 10024) with ESMTP id gaxihJH0XzcB for ; Wed, 16 Nov 2016 17:04:06 +0000 (UTC) Received: from mailrelay1-us-west.apache.org (mailrelay1-us-west.apache.org [209.188.14.139]) by mx1-lw-eu.apache.org (ASF Mail Server at mx1-lw-eu.apache.org) with ESMTP id 400605F472 for ; Wed, 16 Nov 2016 17:04:05 +0000 (UTC) Received: from asf-bz1-us-mid.priv.apache.org (nat1-us-mid.apache.org [23.253.172.122]) by mailrelay1-us-west.apache.org (ASF Mail Server at mailrelay1-us-west.apache.org) with ESMTPS id E3DEBE0054 for ; Wed, 16 Nov 2016 17:04:01 +0000 (UTC) Received: by asf-bz1-us-mid.priv.apache.org (ASF Mail Server at asf-bz1-us-mid.priv.apache.org, from userid 33) id DFF50605FC; Wed, 16 Nov 2016 17:04:00 +0000 (UTC) From: bugzilla@apache.org To: issues@jmeter.apache.org Subject: [Bug 60382] DBCP2 - JDBC Sample became very slow since 3.0 Date: Wed, 16 Nov 2016 17:04:00 +0000 X-Bugzilla-Reason: AssignedTo X-Bugzilla-Type: changed X-Bugzilla-Watch-Reason: None X-Bugzilla-Product: JMeter X-Bugzilla-Component: Main X-Bugzilla-Version: 3.0 X-Bugzilla-Keywords: X-Bugzilla-Severity: regression X-Bugzilla-Who: thomas.peyrard@outlook.com X-Bugzilla-Status: NEW X-Bugzilla-Resolution: X-Bugzilla-Priority: P2 X-Bugzilla-Assigned-To: issues@jmeter.apache.org X-Bugzilla-Target-Milestone: --- X-Bugzilla-Flags: X-Bugzilla-Changed-Fields: op_sys Message-ID: In-Reply-To: References: Content-Type: text/plain; charset="UTF-8" Content-Transfer-Encoding: quoted-printable X-Bugzilla-URL: https://bz.apache.org/bugzilla/ Auto-Submitted: auto-generated MIME-Version: 1.0 archived-at: Wed, 16 Nov 2016 17:04:12 -0000 https://bz.apache.org/bugzilla/show_bug.cgi?id=3D60382 thomas.peyrard@outlook.com changed: What |Removed |Added ---------------------------------------------------------------------------- OS| |All --- Comment #1 from thomas.peyrard@outlook.com --- I also noticed big issues with logging. Some runs might need more Xmx to av= oid issues. Below is a stack trace of a run of 10 minutes where executing the query tak= es 1% (not shown) and logging 36,47% and closing connection 43,64%! ___________________________________________________________________________= ___________________________________________________________________________= ____________________________ Stack Trace Sample Count Percentage(%) java.lang.Thread.run() 3 658 98,972 org.apache.jmeter.threads.JMeterThread.run() 3 658 98,972 org.apache.jmeter.threads.JMeterThread.processSampler(Sampler, Sample= r, JMeterContext) 3 541 95,806 org.apache.jmeter.threads.JMeterThread.executeSamplePackage(Sample= r, TransactionSampler, SamplePackage, JMeterContext) 3 541 95,806 org.apache.jmeter.threads.JMeterThread.runPreProcessors(List)= =20=20=20=20=20=20 1 889 51,109 =20=20=20=20=20=20=20=20=20=20=20=20=20=20 org.apache.jmeter.protocol.jdbc.processor.JDBCPreProcessor.process() 1 = 736=20 46,97 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20 org.apache.jmeter.protocol.jdbc.processor.AbstractJDBCProcessor.process() = 1 736 46,97 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20 org.apache.jmeter.protocol.jdbc.AbstractJDBCTestElement.close(Connection) 1 613 43,642 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20 org.apache.commons.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper.close= ()=20=20 1 613 43,642 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20 org.apache.commons.dbcp2.DelegatingConnection.close() 1 613 43,642 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20 org.apache.commons.dbcp2.DelegatingConnection.closeInternal() 1 612=20= =20 43,615 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20 org.apache.commons.dbcp2.PoolableConnection.close() 1 611 43,588 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20 org.apache.commons.pool2.impl.GenericObjectPool.returnObject(Object) = 1 607 43,479 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20 org.apache.commons.pool2.impl.BaseGenericObjectPool.swallowException(Except= ion) 1 348 36,472 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20 org.apache.commons.dbcp2.SwallowedExceptionLogger.onSwallowException(Except= ion) 1 348 36,472 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20 org.apache.commons.logging.impl.LogKitLogger.warn(Object, Throwable) 1 333 36,066 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20 org.apache.log.Logger.warn(String, Throwable) 1 333 36,066 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20 org.apache.log.Logger.output(Priority, String, Throwable) 1 332 36,039 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20 org.apache.log.Logger.output(LogEvent) 1 327 35,904 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20 org.apache.log.Logger.fireEvent(LogEvent, LogTarget[]) 1 327 35,904 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20 org.apache.log.output.AbstractTarget.processEvent(LogEvent) 1 327 35,904 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20 org.apache.log.output.AbstractOutputTarget.doProcessEvent(LogEvent) 1 = 327=20 35,904 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20 org.apache.log.output.io.WriterTarget.write(String) 753 20,373 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20 java.io.Writer.write(String) 732 19,805 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20 java.io.OutputStreamWriter.write(String, int, int) 732 19,805 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20 sun.nio.cs.StreamEncoder.write(String, int, int) 732 19,805 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20 sun.nio.cs.StreamEncoder.write(char[], int, int) 673 18,209 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20 sun.nio.cs.StreamEncoder.implWrite(char[], int, int) 668 18,074 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20 java.nio.charset.CharsetEncoder.encode(CharBuffer, ByteBuffer, boolean)= =20=20=20=20 666 18,019 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20 sun.nio.cs.SingleByte$Encoder.encodeLoop(CharBuffer, ByteBuffer) 666= =20=20=20=20 18,019 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20 sun.nio.cs.SingleByte$Encoder.encodeArrayLoop(CharBuffer, ByteBuf= fer) 655 17,722 =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20=20= =20=20=20=20 sun.nio.cs.SingleByte.access$000(CoderResult, Buffer, int, Buf= fer, int) 1 0,027 ___________________________________________________________________________= ___________________________________________________________________________= _______________________________ Looking at the logkit.xml file, I can see it still references excalibur, is expected? ...=20=20 ... --=20 You are receiving this mail because: You are the assignee for the bug.=