hbase-user mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Mesika, Asaf" <asaf.mes...@gmail.com>
Subject Re: HBase insert slow
Date Fri, 08 Feb 2013 16:38:29 GMT
What's the rate you're getting (MB/sec or lines/sec)?
What's your cluster setup?

On Feb 8, 2013, at 6:23 PM, 徐歷盛 wrote:

> Hi,
>    I recently suffered HBase insert slow issue (read from mysql, process
> and then insert into HBase). The partial DEBUG log as below. It looks that
> most of the time were spent near "Cached location" and "Got ping response
> for sessionid". Is there any way to improve the performance ? Thanks
>    I tried HTable and HTablePool, both showed the similiar result.
> 
> [13/02/08 08:59:07:106][][DEBUG]Call: next 2
> [13/02/08 08:59:11:619][][DEBUG]Cached location for
> TABLE2_IDX01,,1355799947553.1a08e9f6c702b56d3dc839b83ce86124. is
> 10.39.128.126:60020
> ...
> ...
> [13/02/08 08:59:16:180][][DEBUG]Call: next 2
> [13/02/08 08:59:19:298][][DEBUG]Got ping response for sessionid:
> 0x13c4449defa5228 after 0ms
> 
> 
> Detail Log as below
> ===============================================================================================================
> 
> [13/02/08 08:58:25:863][][INFO]Initiating client connection,
> connectString=SERVER1:2181 sessionTimeout=180000 watcher=hconnection
> [13/02/08 08:58:25:866][][DEBUG]zookeeper.disableAutoWatchReset is false
> [13/02/08 08:58:25:907][][INFO]Opening socket connection to server SERVER1/
> 10.39.128.115:2181
> [13/02/08 08:58:25:910][][INFO]Socket connection established to SERVER1/
> 10.39.128.115:2181, initiating session
> [13/02/08 08:58:25:912][][DEBUG]Session establishment request sent on
> SERVER1/10.39.128.115:2181
> [13/02/08 08:58:25:917][][INFO]Session establishment complete on server
> SERVER1/10.39.128.115:2181, sessionid = 0x13c4449defa5228, negotiated
> timeout = 40000
> 
> [13/02/08 08:58:30:581][][DEBUG]Lookedup root region location,
> connection=org.apache.hadoop.hbase.client.HConnectionManager$HConnectionImplementation@1df2964;
> hsa=10.39.128.152:60020
> [13/02/08 08:58:30:664][][DEBUG]The ping interval is60000ms.
> [13/02/08 08:58:30:729][][DEBUG]Connecting to
> 10.39.128.152/10.39.128.152:60020
> [13/02/08 08:58:30:757][][DEBUG]IPC Client (47) connection to
> 10.39.128.152/10.39.128.152:60020 from an unknown user sending #0
> [13/02/08 08:58:30:827][][DEBUG]IPC Client (47) connection to
> 10.39.128.152/10.39.128.152:60020 from an unknown user: starting, having
> connections 1
> [13/02/08 08:58:30:836][][DEBUG]IPC Client (47) connection to
> 10.39.128.152/10.39.128.152:60020 from an unknown user got value #0
> [13/02/08 08:58:30:837][][DEBUG]Call: getProtocolVersion 117
> [13/02/08 08:58:30:838][][DEBUG]IPC Client (47) connection to
> 10.39.128.152/10.39.128.152:60020 from an unknown user sending #1
> [13/02/08 08:58:30:846][][DEBUG]IPC Client (47) connection to
> 10.39.128.152/10.39.128.152:60020 from an unknown user got value #1
> [13/02/08 08:58:30:848][][DEBUG]Call: getClosestRowBefore 10
> [13/02/08 08:58:35:396][][DEBUG]Cached location for .META.,,1.1028785192 is
> 10.39.128.158:60020
> [13/02/08 08:58:35:398][][DEBUG]Connecting to
> 10.39.128.158/10.39.128.158:60020
> [13/02/08 08:58:35:399][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #2
> [13/02/08 08:58:35:399][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user: starting, having
> connections 2
> [13/02/08 08:58:35:401][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #2
> [13/02/08 08:58:35:401][][DEBUG]Call: getProtocolVersion 3
> [13/02/08 08:58:35:417][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #3
> [13/02/08 08:58:35:420][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #3
> [13/02/08 08:58:35:420][][DEBUG]Call: getClosestRowBefore 3
> [13/02/08 08:58:35:422][][DEBUG]Scanning .META. starting at
> row=TABLE1,,00000000000000 for max=10 rows
> [13/02/08 08:58:35:423][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #4
> [13/02/08 08:58:35:425][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #4
> [13/02/08 08:58:35:425][][DEBUG]Call: openScanner 2
> [13/02/08 08:58:35:426][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #5
> [13/02/08 08:58:35:428][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #5
> [13/02/08 08:58:35:429][][DEBUG]Call: next 3
> [13/02/08 08:58:39:298][][DEBUG]Got ping response for sessionid:
> 0x13c4449defa5228 after 1ms
> [13/02/08 08:58:39:944][][DEBUG]Cached location for
> TABLE1,,1355799993197.a450d2725873a369896e123b57d30394. is
> 10.39.128.157:60020
> [13/02/08 08:58:39:945][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #6
> [13/02/08 08:58:39:947][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #6
> [13/02/08 08:58:39:947][][DEBUG]Call: close 2
> [13/02/08 08:58:39:948][][DEBUG]The connection to
> hconnection-0x13c4449defa5228 has been closed.
> [13/02/08 08:58:39:948][][INFO]|||TABLE1 table connectted|||
> 
> [13/02/08 08:58:39:950][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #7
> [13/02/08 08:58:39:952][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #7
> [13/02/08 08:58:39:952][][DEBUG]Call: getClosestRowBefore 2
> [13/02/08 08:58:39:952][][DEBUG]Scanning .META. starting at
> row=TABLE1_IDX01,,00000000000000 for max=10 rows
> [13/02/08 08:58:39:953][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #8
> [13/02/08 08:58:39:954][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #8
> [13/02/08 08:58:39:955][][DEBUG]Call: openScanner 2
> [13/02/08 08:58:39:955][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #9
> [13/02/08 08:58:39:958][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #9
> [13/02/08 08:58:39:959][][DEBUG]Call: next 4
> [13/02/08 08:58:40:842][][DEBUG]IPC Client (47) connection to
> 10.39.128.152/10.39.128.152:60020 from an unknown user: closed
> [13/02/08 08:58:40:842][][DEBUG]IPC Client (47) connection to
> 10.39.128.152/10.39.128.152:60020 from an unknown user: stopped, remaining
> connections 1
> [13/02/08 08:58:44:473][][DEBUG]Cached location for
> TABLE1_IDX01,,1355800019797.8e17ef64357c7161ee3f18bc270d7080. is
> 10.39.128.154:60020
> [13/02/08 08:58:44:474][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #10
> [13/02/08 08:58:44:477][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #10
> [13/02/08 08:58:44:477][][DEBUG]Call: close 3
> [13/02/08 08:58:44:477][][DEBUG]The connection to
> hconnection-0x13c4449defa5228 has been closed.
> [13/02/08 08:58:44:478][][INFO]|||TABLE1_IDX01 table connectted|||
> 
> [13/02/08 08:58:44:478][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #11
> [13/02/08 08:58:44:481][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #11
> [13/02/08 08:58:44:482][][DEBUG]Call: getClosestRowBefore 4
> [13/02/08 08:58:44:483][][DEBUG]Scanning .META. starting at
> row=TABLE1_IDX02,,00000000000000 for max=10 rows
> [13/02/08 08:58:44:483][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #12
> [13/02/08 08:58:44:485][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #12
> [13/02/08 08:58:44:485][][DEBUG]Call: openScanner 2
> [13/02/08 08:58:44:486][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #13
> [13/02/08 08:58:44:488][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #13
> [13/02/08 08:58:44:488][][DEBUG]Call: next 2
> [13/02/08 08:58:48:990][][DEBUG]Cached location for
> TABLE1_IDX02,,1355800053647.aead27ec424ab5f51a357b84911e0083. is
> 10.39.128.158:60020
> [13/02/08 08:58:48:991][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #14
> [13/02/08 08:58:48:993][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #14
> [13/02/08 08:58:48:994][][DEBUG]Call: close 3
> [13/02/08 08:58:48:994][][DEBUG]The connection to
> hconnection-0x13c4449defa5228 has been closed.
> [13/02/08 08:58:48:994][][INFO]|||TABLE1_IDX02 table connectted|||
> 
> [13/02/08 08:58:48:995][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #15
> [13/02/08 08:58:48:997][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #15
> [13/02/08 08:58:48:998][][DEBUG]Call: getClosestRowBefore 3
> [13/02/08 08:58:48:999][][DEBUG]Scanning .META. starting at
> row=TABLE1_ETL01,,00000000000000 for max=10 rows
> [13/02/08 08:58:48:999][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #16
> [13/02/08 08:58:49:001][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #16
> [13/02/08 08:58:49:002][][DEBUG]Call: openScanner 3
> [13/02/08 08:58:49:002][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #17
> [13/02/08 08:58:49:004][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #17
> [13/02/08 08:58:49:004][][DEBUG]Call: next 2
> [13/02/08 08:58:52:630][][DEBUG]Got ping response for sessionid:
> 0x13c4449defa5228 after 0ms
> [13/02/08 08:58:53:519][][DEBUG]Cached location for
> TABLE1_ETL01,,1355800075109.cda29c57df059ae43aea28d9db2bc804. is
> 10.39.128.181:60020
> [13/02/08 08:58:53:520][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #18
> [13/02/08 08:58:53:524][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #18
> [13/02/08 08:58:53:524][][DEBUG]Call: close 4
> [13/02/08 08:58:53:525][][DEBUG]The connection to
> hconnection-0x13c4449defa5228 has been closed.
> [13/02/08 08:58:53:525][][INFO]|||TABLE1_ETL01 table connectted|||
> 
> [13/02/08 08:58:53:526][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #19
> [13/02/08 08:58:53:528][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #19
> [13/02/08 08:58:53:528][][DEBUG]Call: getClosestRowBefore 2
> [13/02/08 08:58:53:529][][DEBUG]Scanning .META. starting at
> row=TABLE2,,00000000000000 for max=10 rows
> [13/02/08 08:58:53:529][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #20
> [13/02/08 08:58:53:531][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #20
> [13/02/08 08:58:53:531][][DEBUG]Call: openScanner 2
> [13/02/08 08:58:53:531][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #21
> [13/02/08 08:58:53:533][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #21
> [13/02/08 08:58:53:533][][DEBUG]Call: next 2
> [13/02/08 08:58:58:046][][DEBUG]Cached location for
> TABLE2,,1358205765301.c39f34efaf160e4a880433a29e581965. is
> 10.39.128.155:60020
> [13/02/08 08:59:02:549][][DEBUG]Cached location for
> TABLE2,PNV430.06~|0885.120~|EECRDJ~|T7~|2013010612525660~|,1359580390186.7e17ffb2d989b7394f3ef73ece99e7ea.
> is 10.39.128.158:60020
> [13/02/08 08:59:03:529][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user: closed
> [13/02/08 08:59:03:529][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user: stopped, remaining
> connections 0
> [13/02/08 08:59:05:965][][DEBUG]Got ping response for sessionid:
> 0x13c4449defa5228 after 0ms
> [13/02/08 08:59:07:067][][DEBUG]Cached location for
> TABLE2,PNX540.08~|2545.060~|EECRDJ~|T3~|2013011117012443~|,1359580390186.17f558f32541fd86d6ab7a96540215e4.
> is 10.39.128.158:60020
> [13/02/08 08:59:07:068][][DEBUG]Connecting to
> 10.39.128.158/10.39.128.158:60020
> [13/02/08 08:59:07:070][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #22
> [13/02/08 08:59:07:076][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user: starting, having
> connections 1
> [13/02/08 08:59:07:077][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #22
> [13/02/08 08:59:07:078][][DEBUG]Call: close 10
> [13/02/08 08:59:07:078][][DEBUG]The connection to
> hconnection-0x13c4449defa5228 has been closed.
> [13/02/08 08:59:07:083][][INFO]|||TABLE2 table connectted|||
> 
> [13/02/08 08:59:07:098][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #23
> [13/02/08 08:59:07:101][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #23
> [13/02/08 08:59:07:101][][DEBUG]Call: getClosestRowBefore 3
> [13/02/08 08:59:07:101][][DEBUG]Scanning .META. starting at
> row=TABLE2_IDX01,,00000000000000 for max=10 rows
> [13/02/08 08:59:07:102][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #24
> [13/02/08 08:59:07:103][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #24
> [13/02/08 08:59:07:104][][DEBUG]Call: openScanner 2
> [13/02/08 08:59:07:104][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #25
> [13/02/08 08:59:07:105][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #25
> [13/02/08 08:59:07:106][][DEBUG]Call: next 2
> [13/02/08 08:59:11:619][][DEBUG]Cached location for
> TABLE2_IDX01,,1355799947553.1a08e9f6c702b56d3dc839b83ce86124. is
> 10.39.128.126:60020
> [13/02/08 08:59:11:622][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #26
> [13/02/08 08:59:11:624][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #26
> [13/02/08 08:59:11:625][][DEBUG]Call: close 3
> [13/02/08 08:59:11:625][][DEBUG]The connection to
> hconnection-0x13c4449defa5228 has been closed.
> [13/02/08 08:59:11:626][][INFO]|||TABLE2_IDX01 table connectted|||
> 
> [13/02/08 08:59:11:627][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #27
> [13/02/08 08:59:11:629][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #27
> [13/02/08 08:59:11:629][][DEBUG]Call: getClosestRowBefore 2
> [13/02/08 08:59:11:630][][DEBUG]Scanning .META. starting at
> row=TABLE2_ETL01,,00000000000000 for max=10 rows
> [13/02/08 08:59:11:630][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #28
> [13/02/08 08:59:11:632][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #28
> [13/02/08 08:59:11:633][][DEBUG]Call: openScanner 2
> [13/02/08 08:59:11:633][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #29
> [13/02/08 08:59:11:635][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #29
> [13/02/08 08:59:11:635][][DEBUG]Call: next 2
> [13/02/08 08:59:16:147][][DEBUG]Cached location for
> TABLE2_ETL01,,1355799966042.7811665d93bf6fe3ab301dc31f1804b9. is
> 10.39.128.157:60020
> [13/02/08 08:59:16:148][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #30
> [13/02/08 08:59:16:150][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #30
> [13/02/08 08:59:16:151][][DEBUG]Call: close 3
> [13/02/08 08:59:16:151][][DEBUG]The connection to
> hconnection-0x13c4449defa5228 has been closed.
> [13/02/08 08:59:16:171][][INFO]|||TABLE2_ETL01 table connectted|||
> 
> [13/02/08 08:59:16:172][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #31
> [13/02/08 08:59:16:174][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #31
> [13/02/08 08:59:16:174][][DEBUG]Call: getClosestRowBefore 2
> [13/02/08 08:59:16:175][][DEBUG]Scanning .META. starting at
> row=TABLE3_LOG,,00000000000000 for max=10 rows
> [13/02/08 08:59:16:175][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #32
> [13/02/08 08:59:16:177][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #32
> [13/02/08 08:59:16:177][][DEBUG]Call: openScanner 2
> [13/02/08 08:59:16:178][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #33
> [13/02/08 08:59:16:180][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #33
> [13/02/08 08:59:16:180][][DEBUG]Call: next 2
> [13/02/08 08:59:19:298][][DEBUG]Got ping response for sessionid:
> 0x13c4449defa5228 after 0ms
> [13/02/08 08:59:20:696][][DEBUG]Cached location for
> TABLE3_LOG,,1355800084011.0a1003305c755e8d1c6c0b98f9a56214. is
> 10.39.128.128:60020
> [13/02/08 08:59:20:697][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user sending #34
> [13/02/08 08:59:20:699][][DEBUG]IPC Client (47) connection to
> 10.39.128.158/10.39.128.158:60020 from an unknown user got value #34
> [13/02/08 08:59:20:699][][DEBUG]Call: close 2
> [13/02/08 08:59:20:700][][DEBUG]The connection to
> hconnection-0x13c4449defa5228 has been closed.
> [13/02/08 08:59:20:700][][INFO]|||TABLE3_LOG table connectted|||
> 
> [13/02/08 08:59:20:730][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:20:874][][DEBUG]incremented pending_acquires: 1
> [13/02/08 08:59:20:874][][DEBUG]Starting acquisition series. Incremented
> pending_acquires [1],  attempts_remaining: 10
> [13/02/08
> 08:59:20:875][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$ScatteredAcquireTask@24c414
> [13/02/08 08:59:20:875][][DEBUG]incremented pending_acquires: 2
> [13/02/08 08:59:20:876][][DEBUG]Starting acquisition series. Incremented
> pending_acquires [2],  attempts_remaining: 10
> [13/02/08
> 08:59:20:876][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$ScatteredAcquireTask@1c4ff2c
> [13/02/08 08:59:20:877][][DEBUG]incremented pending_acquires: 3
> [13/02/08 08:59:20:877][][DEBUG]Starting acquisition series. Incremented
> pending_acquires [3],  attempts_remaining: 10
> [13/02/08
> 08:59:20:878][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$ScatteredAcquireTask@4ca6b6
> [13/02/08
> 08:59:20:879][][DEBUG]com.mchange.v2.resourcepool.BasicResourcePool@552a2bconfig:
> [start -> 3; min -> 3; max -> 2; inc -> 3; num_acq_attempts -> 10;
> acq_attempt_delay -> 1000; check_idle_resources_delay -> 10000;
> mox_resource_age -> 0; max_idle_time -> 0; excess_max_idle_time -> 0;
> destroy_unreturned_resc_time -> 0; expiration_enforcement_delay -> 0;
> break_on_acquisition_failure -> false; debug_store_checkout_exceptions ->
> false]
> [13/02/08 08:59:20:880][][DEBUG]acquire test -- pool size: 0;
> target_pool_size: 3; desired target? 1
> [13/02/08 08:59:20:880][][DEBUG]awaitAvailable(): [unknown]
> [13/02/08 08:59:20:881][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 0, unused:
> 0, excluded: 0]
> [13/02/08 08:59:21:442][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:443][][DEBUG]com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager@166c114.acquireResource()
> returning.
> [13/02/08 08:59:21:444][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 1, unused:
> 1, excluded: 0]
> [13/02/08 08:59:21:445][][DEBUG]decremented pending_acquires: 2
> [13/02/08 08:59:21:445][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 1, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:468][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:477][][DEBUG]Acquisition series terminated successfully.
> Decremented pending_acquires [2],  attempts_remaining: 10
> [13/02/08 08:59:21:443][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:478][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:480][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@490342
> [13/02/08 08:59:21:480][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 1, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:481][][INFO]|||Start  data pumping for context ID:122592
> in threadID: 0|||
> 
> [13/02/08 08:59:21:482][][DEBUG]acquire test -- pool size: 1;
> target_pool_size: 3; desired target? 2
> [13/02/08 08:59:21:482][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:483][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 1, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08
> 08:59:21:483][][DEBUG]com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager@166c114.acquireResource()
> returning.
> [13/02/08 08:59:21:484][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:484][][DEBUG]decremented pending_acquires: 1
> [13/02/08 08:59:21:485][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:487][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:487][][DEBUG]Acquisition series terminated successfully.
> Decremented pending_acquires [1],  attempts_remaining: 10
> [13/02/08 08:59:21:484][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:488][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:489][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@1de0c09
> [13/02/08 08:59:21:490][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:490][][DEBUG]Executing SQL query [SELECT m.MESContextID,
> m.TechnologyName,     m.StageName, m.RouteName, m.ProductName, m.OperationName,
> m.Aux1, m.Aux2, m.LotName, m.WaferName  FROM pcb.contexthistory c,
> pcb.mesassociation a, pcb.mescontexthistory m  WHERE c.ContextID =
> a.ContextID    AND a.MESContextID = m.MESContextID    AND c.ContextID =
> 122592   AND c.EquipmentID = 'TSI2' ]
> [13/02/08 08:59:21:491][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:491][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:492][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:492][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:492][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:494][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:494][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:495][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@6c5356
> [13/02/08 08:59:21:495][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:496][][DEBUG]Executing SQL query [SELECT i.Label,
> t.Value, i.IndicatorID  FROM pcb.treateddatahistory t, pcb.indicator i
> WHERE t.IndicatorID = i.IndicatorID    AND t.ContextID = 122592   AND
> i.StrategyID = '40E4231013817AA7-61C3A408' ]
> [13/02/08 08:59:21:497][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:497][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:497][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:497][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:498][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:498][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:544][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:545][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:545][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@1b83048
> [13/02/08 08:59:21:546][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:547][][DEBUG]Executing SQL query [SELECT l.AlarmLevel,
> l.Label, l.Value  FROM pcb.limits l  WHERE l.IndicatorID = 28707]
> [13/02/08 08:59:21:547][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:548][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:548][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:548][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:549][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:549][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:551][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:551][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:552][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@9bc984
> [13/02/08 08:59:21:552][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:553][][DEBUG]Executing SQL query [SELECT t.AlarmLevel
> FROM `apchistory` t  where t.contextid = '122592' and t.label = 'Max(CH_A
> Recipe Loop Number)']
> [13/02/08 08:59:21:553][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:553][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:554][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:554][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:554][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:555][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:556][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:557][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:557][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@1429cb2
> [13/02/08 08:59:21:558][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:558][][DEBUG]Executing SQL query [SELECT l.AlarmLevel,
> l.Label, l.Value  FROM pcb.limits l  WHERE l.IndicatorID = 28708]
> [13/02/08 08:59:21:558][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:559][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:559][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:560][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:560][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:560][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:561][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:561][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:561][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@1257687
> [13/02/08 08:59:21:562][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:562][][DEBUG]Executing SQL query [SELECT t.AlarmLevel
> FROM `apchistory` t  where t.contextid = '122592' and t.label = 'Max(CH_A
> Recipe Loop Number)-(1)']
> [13/02/08 08:59:21:563][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:563][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:563][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:563][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:564][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:564][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:564][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:564][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:565][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@1e8fa70
> [13/02/08 08:59:21:565][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:566][][DEBUG]Executing SQL query [SELECT l.AlarmLevel,
> l.Label, l.Value  FROM pcb.limits l  WHERE l.IndicatorID = 28709]
> [13/02/08 08:59:21:566][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:567][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:567][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:573][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:573][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:574][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:575][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:575][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:575][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@58213c
> [13/02/08 08:59:21:576][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:576][][DEBUG]Executing SQL query [SELECT t.AlarmLevel
> FROM `apchistory` t  where t.contextid = '122592' and t.label =
> 'Max(CH_A1:AI_17 UV lamp1-1 Gen power)']
> [13/02/08 08:59:21:576][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:577][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:577][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:577][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:578][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:578][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:579][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:579][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:580][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@63f09e
> [13/02/08 08:59:21:580][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:581][][DEBUG]Executing SQL query [SELECT l.AlarmLevel,
> l.Label, l.Value  FROM pcb.limits l  WHERE l.IndicatorID = 28710]
> [13/02/08 08:59:21:581][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:581][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:581][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:582][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:582][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:582][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:583][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:584][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:584][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@1c19919
> [13/02/08 08:59:21:584][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:585][][DEBUG]Executing SQL query [SELECT t.AlarmLevel
> FROM `apchistory` t  where t.contextid = '122592' and t.label =
> 'Max(CH_A1:AI_18 UV lamp1-2 Gen power)']
> [13/02/08 08:59:21:585][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:585][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:585][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:586][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:586][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:587][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:587][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:588][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:588][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@1e2a069
> [13/02/08 08:59:21:588][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:589][][DEBUG]Executing SQL query [SELECT l.AlarmLevel,
> l.Label, l.Value  FROM pcb.limits l  WHERE l.IndicatorID = 28711]
> [13/02/08 08:59:21:589][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:589][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:589][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:590][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:591][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:591][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:591][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:592][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:592][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@2a2cc
> [13/02/08 08:59:21:592][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:593][][DEBUG]Executing SQL query [SELECT t.AlarmLevel
> FROM `apchistory` t  where t.contextid = '122592' and t.label =
> 'Max(CH_A1:AO_3 UV lamp1-1 setpoint)']
> [13/02/08 08:59:21:593][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:593][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:594][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:594][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:594][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:595][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:595][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:596][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:596][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@c4afc4
> [13/02/08 08:59:21:597][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:597][][DEBUG]Executing SQL query [SELECT l.AlarmLevel,
> l.Label, l.Value  FROM pcb.limits l  WHERE l.IndicatorID = 28712]
> [13/02/08 08:59:21:597][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:599][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:599][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:599][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:600][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:600][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:601][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:601][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:601][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@1e2c841
> [13/02/08 08:59:21:602][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:602][][DEBUG]Executing SQL query [SELECT t.AlarmLevel
> FROM `apchistory` t  where t.contextid = '122592' and t.label =
> 'Max(CH_A1:AO_6 UV lamp1-2 setpoint)']
> [13/02/08 08:59:21:603][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:603][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:603][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:603][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:604][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:604][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:605][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:605][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:605][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@b83be0
> [13/02/08 08:59:21:605][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:606][][DEBUG]Executing SQL query [SELECT l.AlarmLevel,
> l.Label, l.Value  FROM pcb.limits l  WHERE l.IndicatorID = 28713]
> [13/02/08 08:59:21:606][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:606][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:606][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:607][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:607][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:607][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:608][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:608][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:609][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@ffc3eb
> [13/02/08 08:59:21:609][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:609][][DEBUG]Executing SQL query [SELECT t.AlarmLevel
> FROM `apchistory` t  where t.contextid = '122592' and t.label =
> 'Max(CH_A1:AO_8 blower speed)']
> [13/02/08 08:59:21:610][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:610][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:610][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:611][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:611][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:611][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:612][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:612][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:613][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@1e9f5cc
> [13/02/08 08:59:21:613][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:613][][DEBUG]Executing SQL query [SELECT l.AlarmLevel,
> l.Label, l.Value  FROM pcb.limits l  WHERE l.IndicatorID = 28714]
> [13/02/08 08:59:21:614][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:614][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:614][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:615][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:615][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:615][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:616][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:616][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:617][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@a166bd
> [13/02/08 08:59:21:617][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:617][][DEBUG]Executing SQL query [SELECT t.AlarmLevel
> FROM `apchistory` t  where t.contextid = '122592' and t.label =
> 'Max(CH_A1:Current Heater Lift condition)']
> [13/02/08 08:59:21:618][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:618][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:618][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:619][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:619][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:619][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:620][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:621][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:622][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@d1b72d
> [13/02/08 08:59:21:622][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:622][][DEBUG]Executing SQL query [SELECT l.AlarmLevel,
> l.Label, l.Value  FROM pcb.limits l  WHERE l.IndicatorID = 28715]
> [13/02/08 08:59:21:622][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:622][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:623][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:623][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:624][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:624][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:625][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:625][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:625][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@1db0da1
> [13/02/08 08:59:21:626][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:626][][DEBUG]Executing SQL query [SELECT t.AlarmLevel
> FROM `apchistory` t  where t.contextid = '122592' and t.label =
> 'Max(CH_A1:Currentdistanceinmilsfromshowerhead)']
> [13/02/08 08:59:21:626][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:627][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:627][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:627][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:628][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:628][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:629][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:629][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:629][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@e5ace9
> [13/02/08 08:59:21:630][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:630][][DEBUG]Executing SQL query [SELECT l.AlarmLevel,
> l.Label, l.Value  FROM pcb.limits l  WHERE l.IndicatorID = 28716]
> [13/02/08 08:59:21:630][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:631][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:631][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:631][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:632][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:632][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:633][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:633][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:634][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@1b7edd3
> [13/02/08 08:59:21:634][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:634][][DEBUG]Executing SQL query [SELECT t.AlarmLevel
> FROM `apchistory` t  where t.contextid = '122592' and t.label =
> 'Max(CH_A1:Endpoint Voltage (AT/OpticalEndpoint))']
> [13/02/08 08:59:21:635][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:635][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:635][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:636][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:636][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:636][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:638][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:638][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:638][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@dafa2c
> [13/02/08 08:59:21:638][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:639][][DEBUG]Executing SQL query [SELECT l.AlarmLevel,
> l.Label, l.Value  FROM pcb.limits l  WHERE l.IndicatorID = 28717]
> [13/02/08 08:59:21:639][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:639][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:639][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:640][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:640][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:641][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:641][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:642][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:642][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@d2b918
> [13/02/08 08:59:21:642][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:644][][DEBUG]Executing SQL query [SELECT t.AlarmLevel
> FROM `apchistory` t  where t.contextid = '122592' and t.label =
> 'Max(CH_A1:Heater Temp. Setpoint)']
> [13/02/08 08:59:21:645][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:645][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:645][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:645][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:646][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:646][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:647][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:647][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:647][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@1b493c6
> [13/02/08 08:59:21:647][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:647][][DEBUG]Executing SQL query [SELECT l.AlarmLevel,
> l.Label, l.Value  FROM pcb.limits l  WHERE l.IndicatorID = 28718]
> [13/02/08 08:59:21:648][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:648][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:648][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:648][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:649][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:649][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:651][][DEBUG]Returning JDBC Connection to DataSource
> [13/02/08 08:59:21:652][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08
> 08:59:21:653][][DEBUG]com.mchange.v2.async.ThreadPoolAsynchronousRunner@6b62d1:
> Adding task to queue --
> com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@8ef177
> [13/02/08 08:59:21:653][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:653][][DEBUG]Executing SQL query [SELECT t.AlarmLevel
> FROM `apchistory` t  where t.contextid = '122592' and t.label =
> 'Max(CH_A1:HF/Impedance-I offset)']
> [13/02/08 08:59:21:653][][DEBUG]Fetching JDBC Connection from DataSource
> [13/02/08 08:59:21:654][][DEBUG]acquire test -- pool is already maxed out.
> [managed: 2; max: 2]
> [13/02/08 08:59:21:654][][DEBUG]awaitAvailable():
> com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c
> [13/02/08 08:59:21:654][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)
> [13/02/08 08:59:21:655][][DEBUG]checkinAll():
> com.mchange.v2.c3p0.stmt.DoubleMaxStatementCache stats -- total size: 0;
> checked out: 0; num connections: 0; num keys: 0
> [13/02/08 08:59:21:655][][DEBUG]trace
> com.mchange.v2.resourcepool.BasicResourcePool@552a2b [managed: 2, unused:
> 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b31a7c)


Mime
View raw message