firethorn

changeset 4205:edf5bd3168f0 2.1.28-tap-issues

Added some more notes on Comparing OGSADAI logs
author Stelios <stv@roe.ac.uk>
date Sun Apr 21 15:45:44 2019 +0300 (2019-04-21)
parents d5d257db4923
children 55e7f3bc6fc4
files doc/notes/stv/20190415-Chaos-Monkey-Test.txt doc/notes/stv/20190421-Comparing-OGSA-logs.txt doc/notes/stv/20190421-OGSADAI-FAIL.txt doc/notes/stv/20190421-OGSADAI-Graceful-FAIL.txt
line diff
     1.1 --- a/doc/notes/stv/20190415-Chaos-Monkey-Test.txt	Fri Apr 19 21:24:27 2019 +0300
     1.2 +++ b/doc/notes/stv/20190415-Chaos-Monkey-Test.txt	Sun Apr 21 15:45:44 2019 +0300
     1.3 @@ -54,7 +54,7 @@
     1.4  # Select a specific resource by Identity (url)
     1.5  
     1.6  resource=firethorn.firethorn_engine.select_adql_resource_by_ident(
     1.7 -    "http://localhost:8081/firethorn/adql/resource/2103"
     1.8 +    "http://localhost:8081/firethorn/adql/resource/54"
     1.9      )
    1.10  
    1.11  
    1.12 @@ -64,7 +64,7 @@
    1.13      )
    1.14  
    1.15  # Select schema by Identity
    1.16 -schema=resource.select_schema_by_ident("http://localhost:8081/firethorn/adql/schema/2337")
    1.17 +schema=resource.select_schema_by_ident("http://localhost:8081/firethorn/adql/schema/437")
    1.18  
    1.19  
    1.20  # Import the TAP_schema into our workspace
     2.1 --- /dev/null	Thu Jan 01 00:00:00 1970 +0000
     2.2 +++ b/doc/notes/stv/20190421-Comparing-OGSA-logs.txt	Sun Apr 21 15:45:44 2019 +0300
     2.3 @@ -0,0 +1,322 @@
     2.4 +
     2.5 +#
     2.6 +# <meta:header>
     2.7 +#   <meta:licence>
     2.8 +#     Copyright (c) 2018, ROE (http://www.roe.ac.uk/)
     2.9 +#
    2.10 +#     This information is free software: you can redistribute it and/or modify
    2.11 +#     it under the terms of the GNU General Public License as published by
    2.12 +#     the Free Software Foundation, either version 3 of the License, or
    2.13 +#     (at your option) any later version.
    2.14 +#
    2.15 +#     This information is distributed in the hope that it will be useful,
    2.16 +#     but WITHOUT ANY WARRANTY; without even the implied warranty of
    2.17 +#     MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the
    2.18 +#     GNU General Public License for more details.
    2.19 +#  
    2.20 +#     You should have received a copy of the GNU General Public License
    2.21 +#     along with this program.  If not, see <http://www.gnu.org/licenses/>.
    2.22 +#   </meta:licence>
    2.23 +# </meta:header>
    2.24 +#
    2.25 +#
    2.26 +
    2.27 +### Same query run twice (with a ChaosMonkey param to cause it to fail) produces different behaviour
    2.28 +### On the same resource, the failing query was run first (B) and the propagated fail was run second (A)
    2.29 +
    2.30 +### Query run: 
    2.31 +
    2.32 +
    2.33 +
    2.34 +	params = {}
    2.35 +	params.update({"firethorn.monkey.name" : "uk.ac.roe.wfau.firethorn.ogsadai.activity.server.sql.SQLQueryActivity"})
    2.36 +	params.update({"firethorn.monkey.data" : "jG2hdk00"})
    2.37 +
    2.38 +	print(
    2.39 +	    params
    2.40 +	    )
    2.41 +	query_str = "SELECT TOP 10 * from ATLASDR1.Filter"
    2.42 +	query_obj = workspace.create_query(
    2.43 +	    query_str,
    2.44 +	    "COMPLETED",
    2.45 +	    None,
    2.46 +	    3000000,
    2.47 +	    params=params
    2.48 +	    )
    2.49 +	print(
    2.50 +	    query_obj
    2.51 +	    )
    2.52 +	print(
    2.53 +	    query_obj.table()
    2.54 +	    )
    2.55 +	print(
    2.56 +	    query_obj.table().count()
    2.57 +	    )
    2.58 +
    2.59 +
    2.60 +### Try to find differences in OGSA-DAI Logs between two cases, A & B listed below:
    2.61 +
    2.62 +### A) Graceful Failed query - Propagates correctly to Firethorn
    2.63 +### B) Non-Graceful Failed query - Does not propagate the FAIL, gets stuck in a "RUNNING" status
    2.64 +
    2.65 +
    2.66 +
    2.67 +
    2.68 +
    2.69 +###-----------------------------------------------------------------------------
    2.70 +
    2.71 +
    2.72 +
    2.73 +
    2.74 +### 1. Order is different in initial Workflow creation output
    2.75 +
    2.76 +
    2.77 +
    2.78 +
    2.79 +
    2.80 +### 2. 
    2.81 +
    2.82 +B
    2.83 +--
    2.84 +2019-04-21 11:13:33,223 DEBUG activity.OGSADAIActivityFramework [http-nio-8080-exec-6,debug:84] No WORKFLOW_AUTHORIZER in OGSA-DAI context
    2.85 +2019-04-21 11:13:33,225 DEBUG activity.OGSADAIActivityFramework [http-nio-8080-exec-6,debug:84] No MONITORING_FRAMEWORK in OGSA-DAI context
    2.86 +
    2.87 +Does not show up for A
    2.88 +
    2.89 +
    2.90 +
    2.91 +
    2.92 +
    2.93 +## 3. 
    2.94 +
    2.95 +A
    2.96 +--  
    2.97 +2019-04-21 11:13:34,772 DEBUG drer.SimpleDRER [http-nio-8080-exec-1,debug:84] Created engine.Request: uk.org.ogsadai.activity.request.WorkflowRequest@af22729
    2.98 +2019-04-21 11:13:34,781 DEBUG drer.SimpleDRER [http-nio-8080-exec-1,debug:84] About to submit request to engine: uk.org.ogsadai.engine.RequestQueueingEngine@177969b3
    2.99 +2019-04-21 11:13:34,782 DEBUG request.WorkflowRequest [pool-2-thread-1,debug:84] Did not get a workflow transformation from the context
   2.100 +
   2.101 +vs 
   2.102 +
   2.103 +B 
   2.104 +--
   2.105 +2019-04-21 11:15:54,164 DEBUG drer.SimpleDRER [http-nio-8080-exec-10,debug:84] Created engine.Request: uk.org.ogsadai.activity.request.WorkflowRequest@64f818a6
   2.106 +2019-04-21 11:15:54,164 DEBUG drer.SimpleDRER [http-nio-8080-exec-10,debug:84] About to submit request to engine: uk.org.ogsadai.engine.RequestQueueingEngine@177969b3
   2.107 +2019-04-21 11:15:54,165 DEBUG drer.SimpleDRER [http-nio-8080-exec-10,debug:84] Sucessfully submitted request to engine: uk.org.ogsadai.engine.RequestQueueingEngine@177969b3
   2.108 +2019-04-21 11:15:54,171 DEBUG request.WorkflowRequest [pool-2-thread-2,debug:84] Did not get a workflow transformation from the context
   2.109 +
   2.110 +Sucessfully submitted request to engine appears at different points in the logs
   2.111 +
   2.112 +
   2.113 +
   2.114 +
   2.115 +
   2.116 +### 4.
   2.117 +
   2.118 +
   2.119 +A
   2.120 +--  
   2.121 +2019-04-21 11:15:54,189 DEBUG event.LoggingActivityListener [pool-1-thread-8,debug:84] PENDING: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-4b7e7809-7597-41be-9ff1-87f7937073a0)
   2.122 +2019-04-21 11:15:54,190 DEBUG event.LoggingActivityListener [pool-1-thread-8,debug:84] PENDING: Activity uk.org.ogsadai.SQLQuery (instance name = uk.org.ogsadai.SQLQuery-ogsadai-3f0e5175-b6c9-4c1a-aadc-5e4cdf1bedda)
   2.123 +2019-04-21 11:15:54,190 DEBUG event.LoggingActivityListener [pool-1-thread-8,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.Limits (instance name = uk.ac.roe.wfau.firethorn.Limits-ogsadai-1fd04cd8-5d01-4785-8654-f3861b8af05f)
   2.124 +2019-04-21 11:15:54,190 DEBUG event.LoggingActivityListener [pool-1-thread-8,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.Delays (instance name = uk.ac.roe.wfau.firethorn.Delays-ogsadai-67368124-ee07-409d-a2d1-19b3888301d8)
   2.125 +2019-04-21 11:15:54,190 DEBUG event.LoggingActivityListener [pool-1-thread-8,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-245968b6-bc51-4de9-8215-fd6a91611510)
   2.126 +2019-04-21 11:15:54,191 DEBUG event.LoggingActivityListener [pool-1-thread-8,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.JdbcInsertData (instance name = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-b953b608-a1c5-417f-b767-516dd4426460)
   2.127 +
   2.128 +
   2.129 +vs 
   2.130 +
   2.131 +B 
   2.132 +--
   2.133 +2019-04-21 11:13:34,821 DEBUG event.LoggingActivityListener [pool-1-thread-1,debug:84] PENDING: Activity uk.org.ogsadai.SQLQuery (instance name = uk.org.ogsadai.SQLQuery-ogsadai-373dfd1d-3e3a-4f4f-855c-77a1cb640d3b)
   2.134 +2019-04-21 11:13:34,821 DEBUG event.LoggingActivityListener [pool-1-thread-1,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.JdbcInsertData (instance name = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-a3f304c6-b611-405b-865d-569603c435d4)
   2.135 +2019-04-21 11:13:34,821 DEBUG event.LoggingActivityListener [pool-1-thread-1,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.Limits (instance name = uk.ac.roe.wfau.firethorn.Limits-ogsadai-3bc71121-ae5a-4970-8c2c-c55d8306bdb5)
   2.136 +2019-04-21 11:13:34,821 DEBUG event.LoggingActivityListener [pool-1-thread-1,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-7d7668ff-d63e-4d15-835b-8317057e21e6)
   2.137 +2019-04-21 11:13:34,822 DEBUG event.LoggingActivityListener [pool-1-thread-1,debug:84] PENDING: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-ff255f1b-4fbd-4cf5-8510-75b92573f4b0)
   2.138 +2019-04-21 11:13:34,822 DEBUG event.LoggingActivityListener [pool-1-thread-1,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.Delays (instance name = uk.ac.roe.wfau.firethorn.Delays-ogsadai-92dea697-5a16-4bf1-aa17-db8f947796cd)
   2.139 +
   2.140 +
   2.141 +
   2.142 +
   2.143 +
   2.144 +
   2.145 +### 5.
   2.146 +
   2.147 +A
   2.148 +-- 
   2.149 +2019-04-21 11:15:54,196 DEBUG event.LoggingActivityListener [pool-1-thread-12,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.Limits (instance name = uk.ac.roe.wfau.firethorn.Limits-ogsadai-1fd04cd8-5d01-4785-8654-f3861b8af05f)
   2.150 +2019-04-21 11:15:54,204 DEBUG activity.MatchedIterativeActivity [pool-1-thread-12,debug:84] performing first stage of processing
   2.151 +2019-04-21 11:15:54,204 DEBUG data.LimitsActivity [pool-1-thread-12,preprocess:144] preprocess()
   2.152 +2019-04-21 11:15:54,204 DEBUG activity.MatchedIterativeActivity [pool-1-thread-12,debug:84] performing iterative stage of processing
   2.153 +2019-04-21 11:15:54,205 DEBUG event.LoggingActivityListener [pool-1-thread-9,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.Delays (instance name = uk.ac.roe.wfau.firethorn.Delays-ogsadai-67368124-ee07-409d-a2d1-19b3888301d8)
   2.154 +2019-04-21 11:15:54,209 DEBUG activity.MatchedIterativeActivity [pool-1-thread-9,debug:84] performing first stage of processing
   2.155 +2019-04-21 11:15:54,209 DEBUG event.LoggingActivityListener [pool-1-thread-14,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-245968b6-bc51-4de9-8215-fd6a91611510)
   2.156 +2019-04-21 11:15:54,209 DEBUG activity.MatchedIterativeActivity [pool-1-thread-14,debug:84] performing first stage of processing
   2.157 +2019-04-21 11:15:54,209 DEBUG data.DelaysActivity [pool-1-thread-9,preprocess:102] preprocess()
   2.158 +2019-04-21 11:15:54,212 DEBUG activity.MatchedIterativeActivity [pool-1-thread-9,debug:84] performing iterative stage of processing
   2.159 +2019-04-21 11:15:54,210 DEBUG event.LoggingActivityListener [pool-1-thread-10,debug:84] PROCESSING: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-4b7e7809-7597-41be-9ff1-87f7937073a0)
   2.160 +2019-04-21 11:15:54,213 DEBUG event.LoggingActivityListener [pool-1-thread-11,debug:84] PROCESSING: Activity uk.org.ogsadai.SQLQuery (instance name = uk.org.ogsadai.SQLQuery-ogsadai-3f0e5175-b6c9-4c1a-aadc-5e4cdf1bedda)
   2.161 +
   2.162 +
   2.163 +vs 
   2.164 +
   2.165 +
   2.166 +B 
   2.167 +--
   2.168 +2019-04-21 11:13:34,822 DEBUG event.LoggingActivityListener [pool-1-thread-3,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.Limits (instance name = uk.ac.roe.wfau.firethorn.Limits-ogsadai-3bc71121-ae5a-4970-8c2c-c55d8306bdb5)
   2.169 +2019-04-21 11:13:34,822 DEBUG event.LoggingActivityListener [pool-1-thread-2,debug:84] PROCESSING: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-ff255f1b-4fbd-4cf5-8510-75b92573f4b0)
   2.170 +2019-04-21 11:13:34,823 DEBUG event.LoggingActivityListener [pool-1-thread-4,debug:84] PROCESSING: Activity uk.org.ogsadai.SQLQuery (instance name = uk.org.ogsadai.SQLQuery-ogsadai-373dfd1d-3e3a-4f4f-855c-77a1cb640d3b)
   2.171 +2019-04-21 11:13:34,823 DEBUG activity.MatchedIterativeActivity [pool-1-thread-4,debug:84] performing first stage of processing
   2.172 +2019-04-21 11:13:34,824 DEBUG event.LoggingActivityListener [pool-1-thread-5,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.JdbcInsertData (instance name = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-a3f304c6-b611-405b-865d-569603c435d4)
   2.173 +2019-04-21 11:13:34,825 DEBUG event.LoggingActivityListener [pool-1-thread-6,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-7d7668ff-d63e-4d15-835b-8317057e21e6)
   2.174 +
   2.175 +
   2.176 +
   2.177 +
   2.178 +
   2.179 +### 6.
   2.180 +
   2.181 +A
   2.182 +--
   2.183 +## JdbcInsertData starts processing after first batch of activities has started,  
   2.184 +## and after the blue.ContextActivity debug output like:
   2.185 +   
   2.186 +   2019-04-21 11:15:54,216 DEBUG blue.ContextActivity [pool-1-thread-14,postprocess:227] postprocess()
   2.187 +
   2.188 +
   2.189 +2019-04-21 11:15:54,217 DEBUG event.LoggingActivityListener [pool-1-thread-13,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.JdbcInsertData (instance name = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-b953b608-a1c5-417f-b767-516dd4426460)
   2.190 +
   2.191 +
   2.192 +
   2.193 +B
   2.194 +--
   2.195 +
   2.196 +## In the failing query, the JdbcInsertData Activity has already started with the first batch.
   2.197 +## After the first batch, and after the last blue.ContextActivity output "postprocess()" we see:  
   2.198 +
   2.199 +    2019-04-21 11:13:34,826 DEBUG event.LoggingActivityListener [pool-1-thread-6,debug:84] COMPLETED: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-7d7668ff-d63e-4d15-835b-8317057e21e6)
   2.200 +    2019-04-21 11:13:34,827 DEBUG event.LoggingActivityListener [pool-1-thread-7,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.Delays (instance name = uk.ac.roe.wfau.firethorn.Delays-ogsadai-92dea697-5a16-4bf1-aa17-db8f947796cd)
   2.201 +
   2.202 +
   2.203 +
   2.204 +
   2.205 +
   2.206 +### 7. 
   2.207 +
   2.208 +A
   2.209 +--
   2.210 +## The Context Activity completes AFTER we see any JdbcInsertData Activity output
   2.211 +
   2.212 +
   2.213 +B
   2.214 +--
   2.215 +## The Context Activity completes BEFORE we see any JdbcInsertData Activity output
   2.216 +
   2.217 +
   2.218 +
   2.219 +
   2.220 +
   2.221 +
   2.222 +### 8. 
   2.223 +
   2.224 +A
   2.225 +--
   2.226 +##
   2.227 +- 
   2.228 +
   2.229 +
   2.230 +
   2.231 +B
   2.232 +--
   2.233 +##
   2.234 +
   2.235 +2019-04-21 11:13:35,119 DEBUG jdbc.JDBCDataResource [pool-1-thread-5,debug:84] Creating a connection pool for resource ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73 at jdbc:jtds:sqlserver://ramses2/LIVEFirethornUserdataULOV20180719
   2.236 +2019-04-21 11:13:35,119 DEBUG jdbc.JDBCDataResource [pool-1-thread-5,debug:84] DBCP connection pool settings: 
   2.237 +MaxActive = 8
   2.238 +MaxIdle = 8
   2.239 +WhenExhaustedAction = 1
   2.240 +TestOnBorrow = false
   2.241 +TestOnReturn = false
   2.242 +TimeBetweenEvictionRunsMillis = -1
   2.243 +MinEvictableIdleTimeMillis = 1800000
   2.244 +TestWhileIdle = false
   2.245 +SoftMinEvictableIdleTimeMillis = -1
   2.246 +NumTestsPerEvictionRun = 3
   2.247 +Lifo = true
   2.248 +MaxWait = -1
   2.249 +2019-04-21 11:13:35,130 DEBUG file.SimpleFileLoginProvider [pool-1-thread-4,debug:84] In SimpleFileLogin:getLogin
   2.250 +2019-04-21 11:13:35,131 DEBUG file.SimpleFileLoginProvider [pool-1-thread-4,debug:84] In getLogin: ResourceID:  User ID: null
   2.251 +2019-04-21 11:13:35,131 DEBUG file.SimpleFileLoginProvider [pool-1-thread-4,debug:84] Found 4 lines that match
   2.252 +2019-04-21 11:13:35,131 DEBUG file.SimpleFileLoginProvider [pool-1-thread-4,debug:84] Found a login for null
   2.253 +2019-04-21 11:13:35,133 DEBUG jdbc.JDBCDataResource [pool-1-thread-5,debug:84] Checked if a pool with a similar URL has not  already been registered.
   2.254 +2019-04-21 11:13:35,133 DEBUG jdbc.JDBCDataResource [pool-1-thread-5,debug:84] Using JDBC URL : jdbc:apache:commons:dbcp:ogsadai-jdbcpool-ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73
   2.255 +2019-04-21 11:13:35,131 DEBUG jdbc.JDBCDataResource [pool-1-thread-4,debug:84] Creating a connection pool for resource ogsadai-d2794a0d-d5ee-47d9-9e12-4e06d2148976 at jdbc:jtds:sqlserver://192.168.137.11/ATLASDR3
   2.256 +2019-04-21 11:13:35,135 DEBUG jdbc.JDBCDataResource [pool-1-thread-4,debug:84] DBCP connection pool settings: 
   2.257 +MaxActive = 8
   2.258 +MaxIdle = 8
   2.259 +WhenExhaustedAction = 1
   2.260 +TestOnBorrow = false
   2.261 +TestOnReturn = false
   2.262 +TimeBetweenEvictionRunsMillis = -1
   2.263 +MinEvictableIdleTimeMillis = 1800000
   2.264 +TestWhileIdle = false
   2.265 +SoftMinEvictableIdleTimeMillis = -1
   2.266 +NumTestsPerEvictionRun = 3
   2.267 +Lifo = true
   2.268 +MaxWait = -1
   2.269 +
   2.270 +
   2.271 +This is possibly related to the fact that B) was the first query run after creating the workspace and importing the Schema
   2.272 +
   2.273 +
   2.274 +
   2.275 +
   2.276 +
   2.277 +
   2.278 +### 9. Order of Activities
   2.279 +
   2.280 +	
   2.281 +A
   2.282 +--
   2.283 +PROCESSING: Activity uk.ac.roe.wfau.firethorn.Limits (instance name = uk.ac.roe.wfau.firethorn.Limits-ogsadai-1fd04cd8-5d01-4785-8654-f3861b8af05f)
   2.284 +PROCESSING: Activity uk.ac.roe.wfau.firethorn.Delays (instance name = uk.ac.roe.wfau.firethorn.Delays-ogsadai-67368124-ee07-409d-a2d1-19b3888301d8)
   2.285 +PROCESSING: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-245968b6-bc51-4de9-8215-fd6a91611510)
   2.286 +PROCESSING: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-4b7e7809-7597-41be-9ff1-87f7937073a0)
   2.287 +PROCESSING: Activity uk.org.ogsadai.SQLQuery (instance name = uk.org.ogsadai.SQLQuery-ogsadai-3f0e5175-b6c9-4c1a-aadc-5e4cdf1bedda)
   2.288 +PROCESSING: Activity uk.ac.roe.wfau.firethorn.JdbcInsertData (instance name = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-b953b608-a1c5-417f-b767-516dd4426460)
   2.289 +COMPLETED: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-245968b6-bc51-4de9-8215-fd6a91611510)
   2.290 +
   2.291 +B
   2.292 +--
   2.293 +PROCESSING: Activity uk.ac.roe.wfau.firethorn.Limits (instance name = uk.ac.roe.wfau.firethorn.Limits-ogsadai-3bc71121-ae5a-4970-8c2c-c55d8306bdb5)
   2.294 +PROCESSING: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-ff255f1b-4fbd-4cf5-8510-75b92573f4b0)
   2.295 +PROCESSING: Activity uk.org.ogsadai.SQLQuery (instance name = uk.org.ogsadai.SQLQuery-ogsadai-373dfd1d-3e3a-4f4f-855c-77a1cb640d3b)
   2.296 +PROCESSING: Activity uk.ac.roe.wfau.firethorn.JdbcInsertData (instance name = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-a3f304c6-b611-405b-865d-569603c435d4)
   2.297 +PROCESSING: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-7d7668ff-d63e-4d15-835b-8317057e21e6)
   2.298 +COMPLETED: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-7d7668ff-d63e-4d15-835b-8317057e21e6)
   2.299 +PROCESSING: Activity uk.ac.roe.wfau.firethorn.Delays (instance name = uk.ac.roe.wfau.firethorn.Delays-ogsadai-92dea697-5a16-4bf1-aa17-db8f947796cd)
   2.300 +
   2.301 +
   2.302 +
   2.303 +
   2.304 +### Just to compare, here is the order of activities from another two example queries, C) is a query that Failed gracefully, and D) a query that did not
   2.305 +
   2.306 +
   2.307 +C
   2.308 +--
   2.309 +PROCESSING: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-8775be3e-586b-4e04-9252-290e44bf668f)
   2.310 +PROCESSING: Activity uk.ac.roe.wfau.firethorn.Limits (instance name = uk.ac.roe.wfau.firethorn.Limits-ogsadai-907622ba-73d9-40c6-82d8-8281abb292e8)
   2.311 +PROCESSING: Activity uk.org.ogsadai.SQLQuery (instance name = uk.org.ogsadai.SQLQuery-ogsadai-06706101-43e6-4c60-9316-8983c0bfb240)
   2.312 +PROCESSING: Activity uk.ac.roe.wfau.firethorn.Delays (instance name = uk.ac.roe.wfau.firethorn.Delays-ogsadai-6334ea8a-6685-4233-83bd-b7ea735cf38d)
   2.313 +PROCESSING: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-de0ceeca-bad9-4cac-8589-7aa3a73d7f47)
   2.314 +PROCESSING: Activity uk.ac.roe.wfau.firethorn.JdbcInsertData (instance name = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-8117c5bf-7c4f-4048-8718-4f4350376758)
   2.315 +COMPLETED: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-de0ceeca-bad9-4cac-8589-7aa3a73d7f47)
   2.316 +
   2.317 +D
   2.318 +--
   2.319 +PROCESSING: Activity uk.ac.roe.wfau.firethorn.JdbcInsertData (instance name = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-47cc78c5-bfe7-49ab-91b9-2394abe5c3f7)
   2.320 +PROCESSING: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-0ef2e6b9-f063-49a9-860f-9f9ea2d481ad)
   2.321 +COMPLETED: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-0ef2e6b9-f063-49a9-860f-9f9ea2d481ad)
   2.322 +PROCESSING: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-15df7c00-a340-4e47-a7e7-ea8cb3c059c3)
   2.323 +PROCESSING: Activity uk.ac.roe.wfau.firethorn.Delays (instance name = uk.ac.roe.wfau.firethorn.Delays-ogsadai-1d1af8cd-2e4d-438c-8699-2dd8df45a2e5)
   2.324 +PROCESSING: Activity uk.org.ogsadai.SQLQuery (instance name = uk.org.ogsadai.SQLQuery-ogsadai-4c660365-75de-4e62-b96e-aff634cd2ede)
   2.325 +PROCESSING: Activity uk.ac.roe.wfau.firethorn.Limits (instance name = uk.ac.roe.wfau.firethorn.Limits-ogsadai-6cf18e79-eb0c-448b-87c9-8466a2505f48)
     3.1 --- /dev/null	Thu Jan 01 00:00:00 1970 +0000
     3.2 +++ b/doc/notes/stv/20190421-OGSADAI-FAIL.txt	Sun Apr 21 15:45:44 2019 +0300
     3.3 @@ -0,0 +1,571 @@
     3.4 +2019-04-21 11:13:33,223 DEBUG activity.OGSADAIActivityFramework [http-nio-8080-exec-6,debug:84] No WORKFLOW_AUTHORIZER in OGSA-DAI context
     3.5 +2019-04-21 11:13:33,225 DEBUG activity.OGSADAIActivityFramework [http-nio-8080-exec-6,debug:84] No MONITORING_FRAMEWORK in OGSA-DAI context
     3.6 +2019-04-21 11:13:34,102 DEBUG context.RequestContextFactory [http-nio-8080-exec-10,createContext:62] createContext(HttpServletRequest)
     3.7 +2019-04-21 11:13:34,102 DEBUG context.RequestContextFactory [http-nio-8080-exec-10,createContext:64] remote [172.18.0.5]
     3.8 +2019-04-21 11:13:34,122 DEBUG drer.SimpleDRER [http-nio-8080-exec-10,debug:84] Entered execute(CandidateRequestDescriptor)
     3.9 +2019-04-21 11:13:34,134 DEBUG drer.SimpleDRER [http-nio-8080-exec-10,debug:84] Workflow: Activity:
    3.10 +    activity name   = uk.org.ogsadai.DeliverToRequestStatus
    3.11 +    instance name   = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-55cd9f7e-a349-48e8-ad4f-f19da0bc0513
    3.12 +    inputs:
    3.13 +        ActivityInputStream[name=input,pipeName=ogsadai-d8d347ee-bc2d-462d-8232-9d7f251bf247]
    3.14 +    outputs:
    3.15 +
    3.16 +Activity:
    3.17 +    activity name   = uk.ac.roe.wfau.firethorn.JdbcCreateResource
    3.18 +    instance name   = uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-661fc375-28e7-4d1d-ae9e-9d19d28a6962
    3.19 +    inputs:
    3.20 +        Literal: jdbc.database.url : jdbc:jtds:sqlserver://192.168.137.11/ATLASDR3
    3.21 +        Literal: jdbc.database.username : 
    3.22 +        Literal: jdbc.database.password : 
    3.23 +        Literal: jdbc.database.driver : net.sourceforge.jtds.jdbc.Driver
    3.24 +    outputs:
    3.25 +        ActivityOutputStream[name=jdbc.create.resource.result,pipeName=ogsadai-d8d347ee-bc2d-462d-8232-9d7f251bf247,source=uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-661fc375-28e7-4d1d-ae9e-9d19d28a6962
    3.26 +
    3.27 +
    3.28 +2019-04-21 11:13:34,134 DEBUG drer.SimpleDRER [http-nio-8080-exec-10,debug:84] Entered execute(activity.RequestDescriptor)
    3.29 +2019-04-21 11:13:34,170 DEBUG activity.OGSADAIActivityFramework [http-nio-8080-exec-10,debug:84] Found Workflow in RequestDescriptor: Activity:
    3.30 +    activity name   = uk.org.ogsadai.DeliverToRequestStatus
    3.31 +    instance name   = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-55cd9f7e-a349-48e8-ad4f-f19da0bc0513
    3.32 +    inputs:
    3.33 +        ActivityInputStream[name=input,pipeName=ogsadai-d8d347ee-bc2d-462d-8232-9d7f251bf247]
    3.34 +    outputs:
    3.35 +
    3.36 +Activity:
    3.37 +    activity name   = uk.ac.roe.wfau.firethorn.JdbcCreateResource
    3.38 +    instance name   = uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-661fc375-28e7-4d1d-ae9e-9d19d28a6962
    3.39 +    inputs:
    3.40 +        Literal: jdbc.database.url : jdbc:jtds:sqlserver://192.168.137.11/ATLASDR3
    3.41 +        Literal: jdbc.database.username : 
    3.42 +        Literal: jdbc.database.password : 
    3.43 +        Literal: jdbc.database.driver : net.sourceforge.jtds.jdbc.Driver
    3.44 +    outputs:
    3.45 +        ActivityOutputStream[name=jdbc.create.resource.result,pipeName=ogsadai-d8d347ee-bc2d-462d-8232-9d7f251bf247,source=uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-661fc375-28e7-4d1d-ae9e-9d19d28a6962
    3.46 +
    3.47 +
    3.48 +2019-04-21 11:13:34,172 DEBUG drer.SimpleDRER [http-nio-8080-exec-10,debug:84] Created engine.Request: uk.org.ogsadai.activity.request.WorkflowRequest@34735727
    3.49 +2019-04-21 11:13:34,176 DEBUG drer.SimpleDRER [http-nio-8080-exec-10,debug:84] About to submit request to engine: uk.org.ogsadai.engine.RequestQueueingEngine@177969b3
    3.50 +2019-04-21 11:13:34,179 DEBUG drer.SimpleDRER [http-nio-8080-exec-10,debug:84] Sucessfully submitted request to engine: uk.org.ogsadai.engine.RequestQueueingEngine@177969b3
    3.51 +2019-04-21 11:13:34,180 DEBUG request.WorkflowRequest [pool-2-thread-1,debug:84] Did not get a workflow transformation from the context
    3.52 +2019-04-21 11:13:34,182 DEBUG pipeline.AutomaticTee [pool-2-thread-1,debug:84] Graph before transformation: 
    3.53 +2019-04-21 11:13:34,182 DEBUG pipeline.AutomaticTee [pool-2-thread-1,debug:84] Activity:
    3.54 +    activity name   = uk.org.ogsadai.DeliverToRequestStatus
    3.55 +    instance name   = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-55cd9f7e-a349-48e8-ad4f-f19da0bc0513
    3.56 +    inputs:
    3.57 +        ActivityInputStream[name=input,pipeName=ogsadai-d8d347ee-bc2d-462d-8232-9d7f251bf247]
    3.58 +    outputs:
    3.59 +
    3.60 +Activity:
    3.61 +    activity name   = uk.ac.roe.wfau.firethorn.JdbcCreateResource
    3.62 +    instance name   = uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-661fc375-28e7-4d1d-ae9e-9d19d28a6962
    3.63 +    inputs:
    3.64 +        Literal: jdbc.database.url : jdbc:jtds:sqlserver://192.168.137.11/ATLASDR3
    3.65 +        Literal: jdbc.database.username : 
    3.66 +        Literal: jdbc.database.password : 
    3.67 +        Literal: jdbc.database.driver : net.sourceforge.jtds.jdbc.Driver
    3.68 +    outputs:
    3.69 +        ActivityOutputStream[name=jdbc.create.resource.result,pipeName=ogsadai-d8d347ee-bc2d-462d-8232-9d7f251bf247,source=uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-661fc375-28e7-4d1d-ae9e-9d19d28a6962
    3.70 +
    3.71 +
    3.72 +2019-04-21 11:13:34,184 DEBUG pipeline.AutomaticTee [pool-2-thread-1,debug:84] Transformed graph: 
    3.73 +2019-04-21 11:13:34,185 DEBUG pipeline.AutomaticTee [pool-2-thread-1,debug:84] Activity:
    3.74 +    activity name   = uk.org.ogsadai.DeliverToRequestStatus
    3.75 +    instance name   = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-55cd9f7e-a349-48e8-ad4f-f19da0bc0513
    3.76 +    inputs:
    3.77 +        ActivityInputStream[name=input,pipeName=ogsadai-d8d347ee-bc2d-462d-8232-9d7f251bf247]
    3.78 +    outputs:
    3.79 +
    3.80 +Activity:
    3.81 +    activity name   = uk.ac.roe.wfau.firethorn.JdbcCreateResource
    3.82 +    instance name   = uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-661fc375-28e7-4d1d-ae9e-9d19d28a6962
    3.83 +    inputs:
    3.84 +        Literal: jdbc.database.url : jdbc:jtds:sqlserver://192.168.137.11/ATLASDR3
    3.85 +        Literal: jdbc.database.username : 
    3.86 +        Literal: jdbc.database.password : 
    3.87 +        Literal: jdbc.database.driver : net.sourceforge.jtds.jdbc.Driver
    3.88 +    outputs:
    3.89 +        ActivityOutputStream[name=jdbc.create.resource.result,pipeName=ogsadai-d8d347ee-bc2d-462d-8232-9d7f251bf247,source=uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-661fc375-28e7-4d1d-ae9e-9d19d28a6962
    3.90 +
    3.91 +
    3.92 +2019-04-21 11:13:34,233 DEBUG extension.InputsAndOutputsActivityInitialiser [pool-1-thread-1,debug:84] Activity uk.org.ogsadai.DeliverToRequestStatus hasInputAttributes: false
    3.93 +2019-04-21 11:13:34,235 DEBUG io.BufferedPipe [pool-1-thread-1,debug:84] Created a BufferedPipe: ogsadai-d8d347ee-bc2d-462d-8232-9d7f251bf247
    3.94 +2019-04-21 11:13:34,239 DEBUG extension.InputsAndOutputsActivityInitialiser [pool-1-thread-1,debug:84] Activity uk.ac.roe.wfau.firethorn.JdbcCreateResource hasInputAttributes: false
    3.95 +2019-04-21 11:13:34,243 DEBUG event.LoggingActivityListener [pool-1-thread-1,debug:84] PENDING: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-55cd9f7e-a349-48e8-ad4f-f19da0bc0513)
    3.96 +2019-04-21 11:13:34,243 DEBUG event.LoggingActivityListener [pool-1-thread-1,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.JdbcCreateResource (instance name = uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-661fc375-28e7-4d1d-ae9e-9d19d28a6962)
    3.97 +2019-04-21 11:13:34,246 DEBUG event.LoggingActivityListener [pool-1-thread-2,debug:84] PROCESSING: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-55cd9f7e-a349-48e8-ad4f-f19da0bc0513)
    3.98 +2019-04-21 11:13:34,247 DEBUG event.LoggingActivityListener [pool-1-thread-3,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.JdbcCreateResource (instance name = uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-661fc375-28e7-4d1d-ae9e-9d19d28a6962)
    3.99 +2019-04-21 11:13:34,250 DEBUG activity.MatchedIterativeActivity [pool-1-thread-3,debug:84] performing first stage of processing
   3.100 +2019-04-21 11:13:34,250 DEBUG activity.MatchedIterativeActivity [pool-1-thread-3,debug:84] performing iterative stage of processing
   3.101 +2019-04-21 11:13:34,251 DEBUG jdbc.JdbcCreateResourceActivity [pool-1-thread-3,processIteration:185] Resource [ogsadai-d2794a0d-d5ee-47d9-9e12-4e06d2148976]
   3.102 +2019-04-21 11:13:34,252 DEBUG jdbc.JdbcCreateResourceActivity [pool-1-thread-3,processIteration:186] Template [uk.ac.roe.wfau.firethorn.JDBC_RESOURCE_TEMPLATE]
   3.103 +2019-04-21 11:13:34,252 DEBUG jdbc.JdbcCreateResourceActivity [pool-1-thread-3,processIteration:188] Database [jdbc:jtds:sqlserver://192.168.137.11/ATLASDR3]
   3.104 +2019-04-21 11:13:34,252 DEBUG jdbc.JdbcCreateResourceActivity [pool-1-thread-3,processIteration:189] Username []
   3.105 +2019-04-21 11:13:34,252 DEBUG jdbc.JdbcCreateResourceActivity [pool-1-thread-3,processIteration:190] Password []
   3.106 +2019-04-21 11:13:34,252 DEBUG jdbc.JdbcCreateResourceActivity [pool-1-thread-3,processIteration:191] Driver   [net.sourceforge.jtds.jdbc.Driver]
   3.107 +2019-04-21 11:13:34,266 DEBUG jdbc.JdbcCreateResourceActivity [pool-1-thread-3,processIteration:277] Adding Resource to Factory [ogsadai-d2794a0d-d5ee-47d9-9e12-4e06d2148976][ogsadai-d2794a0d-d5ee-47d9-9e12-4e06d2148976]
   3.108 +2019-04-21 11:13:34,268 DEBUG jdbc.JdbcCreateResourceActivity [pool-1-thread-3,processIteration:282] Writing ResourceID to results [ogsadai-d2794a0d-d5ee-47d9-9e12-4e06d2148976]
   3.109 +2019-04-21 11:13:34,269 DEBUG activity.MatchedIterativeActivity [pool-1-thread-3,debug:84] performing last stage of processing
   3.110 +2019-04-21 11:13:34,269 DEBUG activity.MatchedIterativeActivity [pool-1-thread-3,debug:84] performing clean-up
   3.111 +2019-04-21 11:13:34,269 DEBUG event.LoggingActivityListener [pool-1-thread-3,debug:84] COMPLETED: Activity uk.ac.roe.wfau.firethorn.JdbcCreateResource (instance name = uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-661fc375-28e7-4d1d-ae9e-9d19d28a6962)
   3.112 +2019-04-21 11:13:34,272 DEBUG event.LoggingActivityListener [pool-1-thread-2,debug:84] COMPLETED: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-55cd9f7e-a349-48e8-ad4f-f19da0bc0513)
   3.113 +2019-04-21 11:13:34,590 DEBUG context.RequestContextFactory [http-nio-8080-exec-2,createContext:62] createContext(HttpServletRequest)
   3.114 +2019-04-21 11:13:34,591 DEBUG context.RequestContextFactory [http-nio-8080-exec-2,createContext:64] remote [172.18.0.5]
   3.115 +2019-04-21 11:13:34,591 DEBUG drer.SimpleDRER [http-nio-8080-exec-2,debug:84] Entered execute(CandidateRequestDescriptor)
   3.116 +2019-04-21 11:13:34,593 DEBUG drer.SimpleDRER [http-nio-8080-exec-2,debug:84] Workflow: Activity:
   3.117 +    activity name   = uk.ac.roe.wfau.firethorn.JdbcCreateResource
   3.118 +    instance name   = uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-e563712b-f26e-420c-a042-786b2ebb9044
   3.119 +    inputs:
   3.120 +        Literal: jdbc.database.url : jdbc:jtds:sqlserver://ramses2/LIVEFirethornUserdataULOV20180719
   3.121 +        Literal: jdbc.database.username : 
   3.122 +        Literal: jdbc.database.password : 
   3.123 +        Literal: jdbc.database.driver : net.sourceforge.jtds.jdbc.Driver
   3.124 +    outputs:
   3.125 +        ActivityOutputStream[name=jdbc.create.resource.result,pipeName=ogsadai-06fa9d7a-dc1f-46ac-80eb-832a661cbac1,source=uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-e563712b-f26e-420c-a042-786b2ebb9044
   3.126 +
   3.127 +Activity:
   3.128 +    activity name   = uk.org.ogsadai.DeliverToRequestStatus
   3.129 +    instance name   = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-58e56b73-17c7-4928-85b9-9884a3fc53ee
   3.130 +    inputs:
   3.131 +        ActivityInputStream[name=input,pipeName=ogsadai-06fa9d7a-dc1f-46ac-80eb-832a661cbac1]
   3.132 +    outputs:
   3.133 +
   3.134 +
   3.135 +2019-04-21 11:13:34,594 DEBUG drer.SimpleDRER [http-nio-8080-exec-2,debug:84] Entered execute(activity.RequestDescriptor)
   3.136 +2019-04-21 11:13:34,595 DEBUG activity.OGSADAIActivityFramework [http-nio-8080-exec-2,debug:84] Found Workflow in RequestDescriptor: Activity:
   3.137 +    activity name   = uk.ac.roe.wfau.firethorn.JdbcCreateResource
   3.138 +    instance name   = uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-e563712b-f26e-420c-a042-786b2ebb9044
   3.139 +    inputs:
   3.140 +        Literal: jdbc.database.url : jdbc:jtds:sqlserver://ramses2/LIVEFirethornUserdataULOV20180719
   3.141 +        Literal: jdbc.database.username : 
   3.142 +        Literal: jdbc.database.password : 
   3.143 +        Literal: jdbc.database.driver : net.sourceforge.jtds.jdbc.Driver
   3.144 +    outputs:
   3.145 +        ActivityOutputStream[name=jdbc.create.resource.result,pipeName=ogsadai-06fa9d7a-dc1f-46ac-80eb-832a661cbac1,source=uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-e563712b-f26e-420c-a042-786b2ebb9044
   3.146 +
   3.147 +Activity:
   3.148 +    activity name   = uk.org.ogsadai.DeliverToRequestStatus
   3.149 +    instance name   = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-58e56b73-17c7-4928-85b9-9884a3fc53ee
   3.150 +    inputs:
   3.151 +        ActivityInputStream[name=input,pipeName=ogsadai-06fa9d7a-dc1f-46ac-80eb-832a661cbac1]
   3.152 +    outputs:
   3.153 +
   3.154 +
   3.155 +2019-04-21 11:13:34,595 DEBUG drer.SimpleDRER [http-nio-8080-exec-2,debug:84] Created engine.Request: uk.org.ogsadai.activity.request.WorkflowRequest@6b3b9b99
   3.156 +2019-04-21 11:13:34,596 DEBUG drer.SimpleDRER [http-nio-8080-exec-2,debug:84] About to submit request to engine: uk.org.ogsadai.engine.RequestQueueingEngine@177969b3
   3.157 +2019-04-21 11:13:34,604 DEBUG request.WorkflowRequest [pool-2-thread-1,debug:84] Did not get a workflow transformation from the context
   3.158 +2019-04-21 11:13:34,604 DEBUG pipeline.AutomaticTee [pool-2-thread-1,debug:84] Graph before transformation: 
   3.159 +2019-04-21 11:13:34,604 DEBUG pipeline.AutomaticTee [pool-2-thread-1,debug:84] Activity:
   3.160 +    activity name   = uk.ac.roe.wfau.firethorn.JdbcCreateResource
   3.161 +    instance name   = uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-e563712b-f26e-420c-a042-786b2ebb9044
   3.162 +    inputs:
   3.163 +        Literal: jdbc.database.url : jdbc:jtds:sqlserver://ramses2/LIVEFirethornUserdataULOV20180719
   3.164 +        Literal: jdbc.database.username : 
   3.165 +        Literal: jdbc.database.password : 
   3.166 +        Literal: jdbc.database.driver : net.sourceforge.jtds.jdbc.Driver
   3.167 +    outputs:
   3.168 +        ActivityOutputStream[name=jdbc.create.resource.result,pipeName=ogsadai-06fa9d7a-dc1f-46ac-80eb-832a661cbac1,source=uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-e563712b-f26e-420c-a042-786b2ebb9044
   3.169 +
   3.170 +Activity:
   3.171 +    activity name   = uk.org.ogsadai.DeliverToRequestStatus
   3.172 +    instance name   = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-58e56b73-17c7-4928-85b9-9884a3fc53ee
   3.173 +    inputs:
   3.174 +        ActivityInputStream[name=input,pipeName=ogsadai-06fa9d7a-dc1f-46ac-80eb-832a661cbac1]
   3.175 +    outputs:
   3.176 +
   3.177 +
   3.178 +2019-04-21 11:13:34,605 DEBUG pipeline.AutomaticTee [pool-2-thread-1,debug:84] Transformed graph: 
   3.179 +2019-04-21 11:13:34,605 DEBUG pipeline.AutomaticTee [pool-2-thread-1,debug:84] Activity:
   3.180 +    activity name   = uk.ac.roe.wfau.firethorn.JdbcCreateResource
   3.181 +    instance name   = uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-e563712b-f26e-420c-a042-786b2ebb9044
   3.182 +    inputs:
   3.183 +        Literal: jdbc.database.url : jdbc:jtds:sqlserver://ramses2/LIVEFirethornUserdataULOV20180719
   3.184 +        Literal: jdbc.database.username : 
   3.185 +        Literal: jdbc.database.password : 
   3.186 +        Literal: jdbc.database.driver : net.sourceforge.jtds.jdbc.Driver
   3.187 +    outputs:
   3.188 +        ActivityOutputStream[name=jdbc.create.resource.result,pipeName=ogsadai-06fa9d7a-dc1f-46ac-80eb-832a661cbac1,source=uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-e563712b-f26e-420c-a042-786b2ebb9044
   3.189 +
   3.190 +Activity:
   3.191 +    activity name   = uk.org.ogsadai.DeliverToRequestStatus
   3.192 +    instance name   = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-58e56b73-17c7-4928-85b9-9884a3fc53ee
   3.193 +    inputs:
   3.194 +        ActivityInputStream[name=input,pipeName=ogsadai-06fa9d7a-dc1f-46ac-80eb-832a661cbac1]
   3.195 +    outputs:
   3.196 +
   3.197 +
   3.198 +2019-04-21 11:13:34,606 DEBUG extension.InputsAndOutputsActivityInitialiser [pool-1-thread-1,debug:84] Activity uk.ac.roe.wfau.firethorn.JdbcCreateResource hasInputAttributes: false
   3.199 +2019-04-21 11:13:34,608 DEBUG extension.InputsAndOutputsActivityInitialiser [pool-1-thread-1,debug:84] Activity uk.org.ogsadai.DeliverToRequestStatus hasInputAttributes: false
   3.200 +2019-04-21 11:13:34,608 DEBUG io.BufferedPipe [pool-1-thread-1,debug:84] Created a BufferedPipe: ogsadai-06fa9d7a-dc1f-46ac-80eb-832a661cbac1
   3.201 +2019-04-21 11:13:34,609 DEBUG event.LoggingActivityListener [pool-1-thread-1,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.JdbcCreateResource (instance name = uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-e563712b-f26e-420c-a042-786b2ebb9044)
   3.202 +2019-04-21 11:13:34,609 DEBUG event.LoggingActivityListener [pool-1-thread-1,debug:84] PENDING: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-58e56b73-17c7-4928-85b9-9884a3fc53ee)
   3.203 +2019-04-21 11:13:34,609 DEBUG event.LoggingActivityListener [pool-1-thread-2,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.JdbcCreateResource (instance name = uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-e563712b-f26e-420c-a042-786b2ebb9044)
   3.204 +2019-04-21 11:13:34,610 DEBUG event.LoggingActivityListener [pool-1-thread-3,debug:84] PROCESSING: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-58e56b73-17c7-4928-85b9-9884a3fc53ee)
   3.205 +2019-04-21 11:13:34,609 DEBUG drer.SimpleDRER [http-nio-8080-exec-2,debug:84] Sucessfully submitted request to engine: uk.org.ogsadai.engine.RequestQueueingEngine@177969b3
   3.206 +2019-04-21 11:13:34,610 DEBUG activity.MatchedIterativeActivity [pool-1-thread-2,debug:84] performing first stage of processing
   3.207 +2019-04-21 11:13:34,611 DEBUG activity.MatchedIterativeActivity [pool-1-thread-2,debug:84] performing iterative stage of processing
   3.208 +2019-04-21 11:13:34,611 DEBUG jdbc.JdbcCreateResourceActivity [pool-1-thread-2,processIteration:185] Resource [ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73]
   3.209 +2019-04-21 11:13:34,612 DEBUG jdbc.JdbcCreateResourceActivity [pool-1-thread-2,processIteration:186] Template [uk.ac.roe.wfau.firethorn.JDBC_RESOURCE_TEMPLATE]
   3.210 +2019-04-21 11:13:34,612 DEBUG jdbc.JdbcCreateResourceActivity [pool-1-thread-2,processIteration:188] Database [jdbc:jtds:sqlserver://ramses2/LIVEFirethornUserdataULOV20180719]
   3.211 +2019-04-21 11:13:34,612 DEBUG jdbc.JdbcCreateResourceActivity [pool-1-thread-2,processIteration:189] Username []
   3.212 +2019-04-21 11:13:34,612 DEBUG jdbc.JdbcCreateResourceActivity [pool-1-thread-2,processIteration:190] Password []
   3.213 +2019-04-21 11:13:34,612 DEBUG jdbc.JdbcCreateResourceActivity [pool-1-thread-2,processIteration:191] Driver   [net.sourceforge.jtds.jdbc.Driver]
   3.214 +2019-04-21 11:13:34,614 DEBUG jdbc.JdbcCreateResourceActivity [pool-1-thread-2,processIteration:277] Adding Resource to Factory [ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73][ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73]
   3.215 +2019-04-21 11:13:34,614 DEBUG jdbc.JdbcCreateResourceActivity [pool-1-thread-2,processIteration:282] Writing ResourceID to results [ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73]
   3.216 +2019-04-21 11:13:34,615 DEBUG activity.MatchedIterativeActivity [pool-1-thread-2,debug:84] performing last stage of processing
   3.217 +2019-04-21 11:13:34,615 DEBUG activity.MatchedIterativeActivity [pool-1-thread-2,debug:84] performing clean-up
   3.218 +2019-04-21 11:13:34,615 DEBUG event.LoggingActivityListener [pool-1-thread-2,debug:84] COMPLETED: Activity uk.ac.roe.wfau.firethorn.JdbcCreateResource (instance name = uk.ac.roe.wfau.firethorn.JdbcCreateResource-ogsadai-e563712b-f26e-420c-a042-786b2ebb9044)
   3.219 +2019-04-21 11:13:34,616 DEBUG event.LoggingActivityListener [pool-1-thread-3,debug:84] COMPLETED: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-58e56b73-17c7-4928-85b9-9884a3fc53ee)
   3.220 +2019-04-21 11:13:34,768 DEBUG context.RequestContextFactory [http-nio-8080-exec-1,createContext:62] createContext(HttpServletRequest)
   3.221 +2019-04-21 11:13:34,769 DEBUG context.RequestContextFactory [http-nio-8080-exec-1,createContext:64] remote [172.18.0.5]
   3.222 +2019-04-21 11:13:34,769 DEBUG drer.SimpleDRER [http-nio-8080-exec-1,debug:84] Entered execute(CandidateRequestDescriptor)
   3.223 +2019-04-21 11:13:34,770 DEBUG drer.SimpleDRER [http-nio-8080-exec-1,debug:84] Workflow: Activity:
   3.224 +    activity name   = uk.org.ogsadai.DeliverToRequestStatus
   3.225 +    instance name   = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-ff255f1b-4fbd-4cf5-8510-75b92573f4b0
   3.226 +    inputs:
   3.227 +        ActivityInputStream[name=input,pipeName=ogsadai-ff9b6f58-0dee-4d3f-8611-6420d4831ad8]
   3.228 +    outputs:
   3.229 +
   3.230 +Activity:
   3.231 +    activity name   = uk.ac.roe.wfau.firethorn.JdbcInsertData
   3.232 +    instance name   = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-a3f304c6-b611-405b-865d-569603c435d4
   3.233 +    target resource = ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73
   3.234 +    inputs:
   3.235 +        ActivityInputStream[name=tuples,pipeName=ogsadai-23f9194e-5b5e-4607-ab73-dbbb970337fe]
   3.236 +        Literal: jdbc.insert.table.name : [LIVEFirethornUserdataULOV20180719].[dbo].[XX_CDOTZRSB2G7H2AAAAFVD7GPJZE]
   3.237 +    outputs:
   3.238 +        ActivityOutputStream[name=jdbc.insert.results,pipeName=ogsadai-ff9b6f58-0dee-4d3f-8611-6420d4831ad8,source=uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-a3f304c6-b611-405b-865d-569603c435d4
   3.239 +
   3.240 +Activity:
   3.241 +    activity name   = uk.ac.roe.wfau.firethorn.Limits
   3.242 +    instance name   = uk.ac.roe.wfau.firethorn.Limits-ogsadai-3bc71121-ae5a-4970-8c2c-c55d8306bdb5
   3.243 +    inputs:
   3.244 +        Literal: limit.rows : 1000000
   3.245 +        ActivityInputStream[name=tuples,pipeName=ogsadai-508b154c-f0e2-4c4a-bd07-9490c2472d8f]
   3.246 +    outputs:
   3.247 +        ActivityOutputStream[name=tuples,pipeName=ogsadai-23f9194e-5b5e-4607-ab73-dbbb970337fe,source=uk.ac.roe.wfau.firethorn.Limits-ogsadai-3bc71121-ae5a-4970-8c2c-c55d8306bdb5
   3.248 +
   3.249 +Activity:
   3.250 +    activity name   = uk.ac.roe.wfau.firethorn.Delays
   3.251 +    instance name   = uk.ac.roe.wfau.firethorn.Delays-ogsadai-92dea697-5a16-4bf1-aa17-db8f947796cd
   3.252 +    inputs:
   3.253 +        ActivityInputStream[name=tuples,pipeName=ogsadai-1c0762b4-3280-4c2b-85b4-008d3a496703]
   3.254 +    outputs:
   3.255 +        ActivityOutputStream[name=tuples,pipeName=ogsadai-508b154c-f0e2-4c4a-bd07-9490c2472d8f,source=uk.ac.roe.wfau.firethorn.Delays-ogsadai-92dea697-5a16-4bf1-aa17-db8f947796cd
   3.256 +
   3.257 +Activity:
   3.258 +    activity name   = uk.ac.roe.wfau.firethorn.Context
   3.259 +    instance name   = uk.ac.roe.wfau.firethorn.Context-ogsadai-7d7668ff-d63e-4d15-835b-8317057e21e6
   3.260 +    inputs:
   3.261 +        Literal: callback.port.input : 8081
   3.262 +        Literal: context.ident.input : 33202
   3.263 +        Literal: context.pipeline.input : SELECT TOP 10 [ATLASDR1].[dbo].[Filter].[filterID] AS "filterID",[ATLASDR1].[dbo].[Filter].[shortName] AS "shortName",[ATLASDR1].[dbo].[Filter].[name] AS "name",[ATLASDR1].[dbo].[Filter].[description] AS "description",[ATLASDR1].[dbo].[Filter].[cutOn] AS "cutOn",[ATLASDR1].[dbo].[Filter].[cutOff] AS "cutOff",[ATLASDR1].[dbo].[Filter].[aebv] AS "aebv",[ATLASDR1].[dbo].[Filter].[vegaToAB] AS "vegaToAB",[ATLASDR1].[dbo].[Filter].[oneSecMLVg] AS "oneSecMLVg",[ATLASDR1].[dbo].[Filter].[isSectioned] AS "isSectioned"
   3.264 +FROM [ATLASDR1].[dbo].[Filter]
   3.265 +        Literal: monkey.param.name : uk.ac.roe.wfau.firethorn.ogsadai.activity.server.sql.SQLQueryActivity
   3.266 +        Literal: monkey.param.data : jG2hdk00
   3.267 +    outputs:
   3.268 +        ActivityOutputStream[name=context.pipeline.output,pipeName=ogsadai-4eceedd1-69de-4be0-9531-37004f9714f1,source=uk.ac.roe.wfau.firethorn.Context-ogsadai-7d7668ff-d63e-4d15-835b-8317057e21e6
   3.269 +
   3.270 +Activity:
   3.271 +    activity name   = uk.org.ogsadai.SQLQuery
   3.272 +    instance name   = uk.org.ogsadai.SQLQuery-ogsadai-373dfd1d-3e3a-4f4f-855c-77a1cb640d3b
   3.273 +    target resource = ogsadai-d2794a0d-d5ee-47d9-9e12-4e06d2148976
   3.274 +    inputs:
   3.275 +        ActivityInputStream[name=expression,pipeName=ogsadai-4eceedd1-69de-4be0-9531-37004f9714f1]
   3.276 +    outputs:
   3.277 +        ActivityOutputStream[name=data,pipeName=ogsadai-1c0762b4-3280-4c2b-85b4-008d3a496703,source=uk.org.ogsadai.SQLQuery-ogsadai-373dfd1d-3e3a-4f4f-855c-77a1cb640d3b
   3.278 +
   3.279 +
   3.280 +2019-04-21 11:13:34,771 DEBUG drer.SimpleDRER [http-nio-8080-exec-1,debug:84] Entered execute(activity.RequestDescriptor)
   3.281 +2019-04-21 11:13:34,772 DEBUG activity.OGSADAIActivityFramework [http-nio-8080-exec-1,debug:84] Found Workflow in RequestDescriptor: Activity:
   3.282 +    activity name   = uk.org.ogsadai.DeliverToRequestStatus
   3.283 +    instance name   = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-ff255f1b-4fbd-4cf5-8510-75b92573f4b0
   3.284 +    inputs:
   3.285 +        ActivityInputStream[name=input,pipeName=ogsadai-ff9b6f58-0dee-4d3f-8611-6420d4831ad8]
   3.286 +    outputs:
   3.287 +
   3.288 +Activity:
   3.289 +    activity name   = uk.ac.roe.wfau.firethorn.JdbcInsertData
   3.290 +    instance name   = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-a3f304c6-b611-405b-865d-569603c435d4
   3.291 +    target resource = ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73
   3.292 +    inputs:
   3.293 +        ActivityInputStream[name=tuples,pipeName=ogsadai-23f9194e-5b5e-4607-ab73-dbbb970337fe]
   3.294 +        Literal: jdbc.insert.table.name : [LIVEFirethornUserdataULOV20180719].[dbo].[XX_CDOTZRSB2G7H2AAAAFVD7GPJZE]
   3.295 +    outputs:
   3.296 +        ActivityOutputStream[name=jdbc.insert.results,pipeName=ogsadai-ff9b6f58-0dee-4d3f-8611-6420d4831ad8,source=uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-a3f304c6-b611-405b-865d-569603c435d4
   3.297 +
   3.298 +Activity:
   3.299 +    activity name   = uk.ac.roe.wfau.firethorn.Limits
   3.300 +    instance name   = uk.ac.roe.wfau.firethorn.Limits-ogsadai-3bc71121-ae5a-4970-8c2c-c55d8306bdb5
   3.301 +    inputs:
   3.302 +        Literal: limit.rows : 1000000
   3.303 +        ActivityInputStream[name=tuples,pipeName=ogsadai-508b154c-f0e2-4c4a-bd07-9490c2472d8f]
   3.304 +    outputs:
   3.305 +        ActivityOutputStream[name=tuples,pipeName=ogsadai-23f9194e-5b5e-4607-ab73-dbbb970337fe,source=uk.ac.roe.wfau.firethorn.Limits-ogsadai-3bc71121-ae5a-4970-8c2c-c55d8306bdb5
   3.306 +
   3.307 +Activity:
   3.308 +    activity name   = uk.ac.roe.wfau.firethorn.Delays
   3.309 +    instance name   = uk.ac.roe.wfau.firethorn.Delays-ogsadai-92dea697-5a16-4bf1-aa17-db8f947796cd
   3.310 +    inputs:
   3.311 +        ActivityInputStream[name=tuples,pipeName=ogsadai-1c0762b4-3280-4c2b-85b4-008d3a496703]
   3.312 +    outputs:
   3.313 +        ActivityOutputStream[name=tuples,pipeName=ogsadai-508b154c-f0e2-4c4a-bd07-9490c2472d8f,source=uk.ac.roe.wfau.firethorn.Delays-ogsadai-92dea697-5a16-4bf1-aa17-db8f947796cd
   3.314 +
   3.315 +Activity:
   3.316 +    activity name   = uk.ac.roe.wfau.firethorn.Context
   3.317 +    instance name   = uk.ac.roe.wfau.firethorn.Context-ogsadai-7d7668ff-d63e-4d15-835b-8317057e21e6
   3.318 +    inputs:
   3.319 +        Literal: callback.port.input : 8081
   3.320 +        Literal: context.ident.input : 33202
   3.321 +        Literal: context.pipeline.input : SELECT TOP 10 [ATLASDR1].[dbo].[Filter].[filterID] AS "filterID",[ATLASDR1].[dbo].[Filter].[shortName] AS "shortName",[ATLASDR1].[dbo].[Filter].[name] AS "name",[ATLASDR1].[dbo].[Filter].[description] AS "description",[ATLASDR1].[dbo].[Filter].[cutOn] AS "cutOn",[ATLASDR1].[dbo].[Filter].[cutOff] AS "cutOff",[ATLASDR1].[dbo].[Filter].[aebv] AS "aebv",[ATLASDR1].[dbo].[Filter].[vegaToAB] AS "vegaToAB",[ATLASDR1].[dbo].[Filter].[oneSecMLVg] AS "oneSecMLVg",[ATLASDR1].[dbo].[Filter].[isSectioned] AS "isSectioned"
   3.322 +FROM [ATLASDR1].[dbo].[Filter]
   3.323 +        Literal: monkey.param.name : uk.ac.roe.wfau.firethorn.ogsadai.activity.server.sql.SQLQueryActivity
   3.324 +        Literal: monkey.param.data : jG2hdk00
   3.325 +    outputs:
   3.326 +        ActivityOutputStream[name=context.pipeline.output,pipeName=ogsadai-4eceedd1-69de-4be0-9531-37004f9714f1,source=uk.ac.roe.wfau.firethorn.Context-ogsadai-7d7668ff-d63e-4d15-835b-8317057e21e6
   3.327 +
   3.328 +Activity:
   3.329 +    activity name   = uk.org.ogsadai.SQLQuery
   3.330 +    instance name   = uk.org.ogsadai.SQLQuery-ogsadai-373dfd1d-3e3a-4f4f-855c-77a1cb640d3b
   3.331 +    target resource = ogsadai-d2794a0d-d5ee-47d9-9e12-4e06d2148976
   3.332 +    inputs:
   3.333 +        ActivityInputStream[name=expression,pipeName=ogsadai-4eceedd1-69de-4be0-9531-37004f9714f1]
   3.334 +    outputs:
   3.335 +        ActivityOutputStream[name=data,pipeName=ogsadai-1c0762b4-3280-4c2b-85b4-008d3a496703,source=uk.org.ogsadai.SQLQuery-ogsadai-373dfd1d-3e3a-4f4f-855c-77a1cb640d3b
   3.336 +
   3.337 +
   3.338 +2019-04-21 11:13:34,772 DEBUG drer.SimpleDRER [http-nio-8080-exec-1,debug:84] Created engine.Request: uk.org.ogsadai.activity.request.WorkflowRequest@af22729
   3.339 +2019-04-21 11:13:34,781 DEBUG drer.SimpleDRER [http-nio-8080-exec-1,debug:84] About to submit request to engine: uk.org.ogsadai.engine.RequestQueueingEngine@177969b3
   3.340 +2019-04-21 11:13:34,782 DEBUG request.WorkflowRequest [pool-2-thread-1,debug:84] Did not get a workflow transformation from the context
   3.341 +2019-04-21 11:13:34,782 DEBUG pipeline.AutomaticTee [pool-2-thread-1,debug:84] Graph before transformation: 
   3.342 +2019-04-21 11:13:34,783 DEBUG pipeline.AutomaticTee [pool-2-thread-1,debug:84] Activity:
   3.343 +    activity name   = uk.org.ogsadai.DeliverToRequestStatus
   3.344 +    instance name   = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-ff255f1b-4fbd-4cf5-8510-75b92573f4b0
   3.345 +    inputs:
   3.346 +        ActivityInputStream[name=input,pipeName=ogsadai-ff9b6f58-0dee-4d3f-8611-6420d4831ad8]
   3.347 +    outputs:
   3.348 +
   3.349 +Activity:
   3.350 +    activity name   = uk.ac.roe.wfau.firethorn.JdbcInsertData
   3.351 +    instance name   = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-a3f304c6-b611-405b-865d-569603c435d4
   3.352 +    target resource = ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73
   3.353 +    inputs:
   3.354 +        ActivityInputStream[name=tuples,pipeName=ogsadai-23f9194e-5b5e-4607-ab73-dbbb970337fe]
   3.355 +        Literal: jdbc.insert.table.name : [LIVEFirethornUserdataULOV20180719].[dbo].[XX_CDOTZRSB2G7H2AAAAFVD7GPJZE]
   3.356 +    outputs:
   3.357 +        ActivityOutputStream[name=jdbc.insert.results,pipeName=ogsadai-ff9b6f58-0dee-4d3f-8611-6420d4831ad8,source=uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-a3f304c6-b611-405b-865d-569603c435d4
   3.358 +
   3.359 +Activity:
   3.360 +    activity name   = uk.ac.roe.wfau.firethorn.Limits
   3.361 +    instance name   = uk.ac.roe.wfau.firethorn.Limits-ogsadai-3bc71121-ae5a-4970-8c2c-c55d8306bdb5
   3.362 +    inputs:
   3.363 +        Literal: limit.rows : 1000000
   3.364 +        ActivityInputStream[name=tuples,pipeName=ogsadai-508b154c-f0e2-4c4a-bd07-9490c2472d8f]
   3.365 +    outputs:
   3.366 +        ActivityOutputStream[name=tuples,pipeName=ogsadai-23f9194e-5b5e-4607-ab73-dbbb970337fe,source=uk.ac.roe.wfau.firethorn.Limits-ogsadai-3bc71121-ae5a-4970-8c2c-c55d8306bdb5
   3.367 +
   3.368 +Activity:
   3.369 +    activity name   = uk.ac.roe.wfau.firethorn.Delays
   3.370 +    instance name   = uk.ac.roe.wfau.firethorn.Delays-ogsadai-92dea697-5a16-4bf1-aa17-db8f947796cd
   3.371 +    inputs:
   3.372 +        ActivityInputStream[name=tuples,pipeName=ogsadai-1c0762b4-3280-4c2b-85b4-008d3a496703]
   3.373 +    outputs:
   3.374 +        ActivityOutputStream[name=tuples,pipeName=ogsadai-508b154c-f0e2-4c4a-bd07-9490c2472d8f,source=uk.ac.roe.wfau.firethorn.Delays-ogsadai-92dea697-5a16-4bf1-aa17-db8f947796cd
   3.375 +
   3.376 +Activity:
   3.377 +    activity name   = uk.ac.roe.wfau.firethorn.Context
   3.378 +    instance name   = uk.ac.roe.wfau.firethorn.Context-ogsadai-7d7668ff-d63e-4d15-835b-8317057e21e6
   3.379 +    inputs:
   3.380 +        Literal: callback.port.input : 8081
   3.381 +        Literal: context.ident.input : 33202
   3.382 +        Literal: context.pipeline.input : SELECT TOP 10 [ATLASDR1].[dbo].[Filter].[filterID] AS "filterID",[ATLASDR1].[dbo].[Filter].[shortName] AS "shortName",[ATLASDR1].[dbo].[Filter].[name] AS "name",[ATLASDR1].[dbo].[Filter].[description] AS "description",[ATLASDR1].[dbo].[Filter].[cutOn] AS "cutOn",[ATLASDR1].[dbo].[Filter].[cutOff] AS "cutOff",[ATLASDR1].[dbo].[Filter].[aebv] AS "aebv",[ATLASDR1].[dbo].[Filter].[vegaToAB] AS "vegaToAB",[ATLASDR1].[dbo].[Filter].[oneSecMLVg] AS "oneSecMLVg",[ATLASDR1].[dbo].[Filter].[isSectioned] AS "isSectioned"
   3.383 +FROM [ATLASDR1].[dbo].[Filter]
   3.384 +        Literal: monkey.param.name : uk.ac.roe.wfau.firethorn.ogsadai.activity.server.sql.SQLQueryActivity
   3.385 +        Literal: monkey.param.data : jG2hdk00
   3.386 +    outputs:
   3.387 +        ActivityOutputStream[name=context.pipeline.output,pipeName=ogsadai-4eceedd1-69de-4be0-9531-37004f9714f1,source=uk.ac.roe.wfau.firethorn.Context-ogsadai-7d7668ff-d63e-4d15-835b-8317057e21e6
   3.388 +
   3.389 +Activity:
   3.390 +    activity name   = uk.org.ogsadai.SQLQuery
   3.391 +    instance name   = uk.org.ogsadai.SQLQuery-ogsadai-373dfd1d-3e3a-4f4f-855c-77a1cb640d3b
   3.392 +    target resource = ogsadai-d2794a0d-d5ee-47d9-9e12-4e06d2148976
   3.393 +    inputs:
   3.394 +        ActivityInputStream[name=expression,pipeName=ogsadai-4eceedd1-69de-4be0-9531-37004f9714f1]
   3.395 +    outputs:
   3.396 +        ActivityOutputStream[name=data,pipeName=ogsadai-1c0762b4-3280-4c2b-85b4-008d3a496703,source=uk.org.ogsadai.SQLQuery-ogsadai-373dfd1d-3e3a-4f4f-855c-77a1cb640d3b
   3.397 +
   3.398 +
   3.399 +2019-04-21 11:13:34,783 DEBUG pipeline.AutomaticTee [pool-2-thread-1,debug:84] Transformed graph: 
   3.400 +2019-04-21 11:13:34,783 DEBUG pipeline.AutomaticTee [pool-2-thread-1,debug:84] Activity:
   3.401 +    activity name   = uk.org.ogsadai.DeliverToRequestStatus
   3.402 +    instance name   = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-ff255f1b-4fbd-4cf5-8510-75b92573f4b0
   3.403 +    inputs:
   3.404 +        ActivityInputStream[name=input,pipeName=ogsadai-ff9b6f58-0dee-4d3f-8611-6420d4831ad8]
   3.405 +    outputs:
   3.406 +
   3.407 +Activity:
   3.408 +    activity name   = uk.ac.roe.wfau.firethorn.JdbcInsertData
   3.409 +    instance name   = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-a3f304c6-b611-405b-865d-569603c435d4
   3.410 +    target resource = ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73
   3.411 +    inputs:
   3.412 +        ActivityInputStream[name=tuples,pipeName=ogsadai-23f9194e-5b5e-4607-ab73-dbbb970337fe]
   3.413 +        Literal: jdbc.insert.table.name : [LIVEFirethornUserdataULOV20180719].[dbo].[XX_CDOTZRSB2G7H2AAAAFVD7GPJZE]
   3.414 +    outputs:
   3.415 +        ActivityOutputStream[name=jdbc.insert.results,pipeName=ogsadai-ff9b6f58-0dee-4d3f-8611-6420d4831ad8,source=uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-a3f304c6-b611-405b-865d-569603c435d4
   3.416 +
   3.417 +Activity:
   3.418 +    activity name   = uk.ac.roe.wfau.firethorn.Limits
   3.419 +    instance name   = uk.ac.roe.wfau.firethorn.Limits-ogsadai-3bc71121-ae5a-4970-8c2c-c55d8306bdb5
   3.420 +    inputs:
   3.421 +        Literal: limit.rows : 1000000
   3.422 +        ActivityInputStream[name=tuples,pipeName=ogsadai-508b154c-f0e2-4c4a-bd07-9490c2472d8f]
   3.423 +    outputs:
   3.424 +        ActivityOutputStream[name=tuples,pipeName=ogsadai-23f9194e-5b5e-4607-ab73-dbbb970337fe,source=uk.ac.roe.wfau.firethorn.Limits-ogsadai-3bc71121-ae5a-4970-8c2c-c55d8306bdb5
   3.425 +
   3.426 +Activity:
   3.427 +    activity name   = uk.ac.roe.wfau.firethorn.Delays
   3.428 +    instance name   = uk.ac.roe.wfau.firethorn.Delays-ogsadai-92dea697-5a16-4bf1-aa17-db8f947796cd
   3.429 +    inputs:
   3.430 +        ActivityInputStream[name=tuples,pipeName=ogsadai-1c0762b4-3280-4c2b-85b4-008d3a496703]
   3.431 +    outputs:
   3.432 +        ActivityOutputStream[name=tuples,pipeName=ogsadai-508b154c-f0e2-4c4a-bd07-9490c2472d8f,source=uk.ac.roe.wfau.firethorn.Delays-ogsadai-92dea697-5a16-4bf1-aa17-db8f947796cd
   3.433 +
   3.434 +Activity:
   3.435 +    activity name   = uk.ac.roe.wfau.firethorn.Context
   3.436 +    instance name   = uk.ac.roe.wfau.firethorn.Context-ogsadai-7d7668ff-d63e-4d15-835b-8317057e21e6
   3.437 +    inputs:
   3.438 +        Literal: callback.port.input : 8081
   3.439 +        Literal: context.ident.input : 33202
   3.440 +        Literal: context.pipeline.input : SELECT TOP 10 [ATLASDR1].[dbo].[Filter].[filterID] AS "filterID",[ATLASDR1].[dbo].[Filter].[shortName] AS "shortName",[ATLASDR1].[dbo].[Filter].[name] AS "name",[ATLASDR1].[dbo].[Filter].[description] AS "description",[ATLASDR1].[dbo].[Filter].[cutOn] AS "cutOn",[ATLASDR1].[dbo].[Filter].[cutOff] AS "cutOff",[ATLASDR1].[dbo].[Filter].[aebv] AS "aebv",[ATLASDR1].[dbo].[Filter].[vegaToAB] AS "vegaToAB",[ATLASDR1].[dbo].[Filter].[oneSecMLVg] AS "oneSecMLVg",[ATLASDR1].[dbo].[Filter].[isSectioned] AS "isSectioned"
   3.441 +FROM [ATLASDR1].[dbo].[Filter]
   3.442 +        Literal: monkey.param.name : uk.ac.roe.wfau.firethorn.ogsadai.activity.server.sql.SQLQueryActivity
   3.443 +        Literal: monkey.param.data : jG2hdk00
   3.444 +    outputs:
   3.445 +        ActivityOutputStream[name=context.pipeline.output,pipeName=ogsadai-4eceedd1-69de-4be0-9531-37004f9714f1,source=uk.ac.roe.wfau.firethorn.Context-ogsadai-7d7668ff-d63e-4d15-835b-8317057e21e6
   3.446 +
   3.447 +Activity:
   3.448 +    activity name   = uk.org.ogsadai.SQLQuery
   3.449 +    instance name   = uk.org.ogsadai.SQLQuery-ogsadai-373dfd1d-3e3a-4f4f-855c-77a1cb640d3b
   3.450 +    target resource = ogsadai-d2794a0d-d5ee-47d9-9e12-4e06d2148976
   3.451 +    inputs:
   3.452 +        ActivityInputStream[name=expression,pipeName=ogsadai-4eceedd1-69de-4be0-9531-37004f9714f1]
   3.453 +    outputs:
   3.454 +        ActivityOutputStream[name=data,pipeName=ogsadai-1c0762b4-3280-4c2b-85b4-008d3a496703,source=uk.org.ogsadai.SQLQuery-ogsadai-373dfd1d-3e3a-4f4f-855c-77a1cb640d3b
   3.455 +
   3.456 +
   3.457 +2019-04-21 11:13:34,781 DEBUG drer.SimpleDRER [http-nio-8080-exec-1,debug:84] Sucessfully submitted request to engine: uk.org.ogsadai.engine.RequestQueueingEngine@177969b3
   3.458 +2019-04-21 11:13:34,802 DEBUG extension.InputsAndOutputsActivityInitialiser [pool-1-thread-1,debug:84] Activity uk.org.ogsadai.SQLQuery hasInputAttributes: false
   3.459 +2019-04-21 11:13:34,808 DEBUG io.BufferedPipe [pool-1-thread-1,debug:84] Created a BufferedPipe: ogsadai-4eceedd1-69de-4be0-9531-37004f9714f1
   3.460 +2019-04-21 11:13:34,810 ERROR extension.ServiceAddressesActivityInitialiser [pool-1-thread-1,errorExceptionAndChildren:401] #1555845214810:26# java.net.MalformedURLException: no protocol: ${ogsadai.endpoint}
   3.461 +2019-04-21 11:13:34,816 DEBUG extension.InputsAndOutputsActivityInitialiser [pool-1-thread-1,debug:84] Activity uk.ac.roe.wfau.firethorn.JdbcInsertData hasInputAttributes: false
   3.462 +2019-04-21 11:13:34,817 DEBUG io.BufferedPipe [pool-1-thread-1,debug:84] Created a BufferedPipe: ogsadai-23f9194e-5b5e-4607-ab73-dbbb970337fe
   3.463 +2019-04-21 11:13:34,817 DEBUG extension.InputsAndOutputsActivityInitialiser [pool-1-thread-1,debug:84] Activity uk.ac.roe.wfau.firethorn.Limits hasInputAttributes: false
   3.464 +2019-04-21 11:13:34,817 DEBUG io.BufferedPipe [pool-1-thread-1,debug:84] Created a BufferedPipe: ogsadai-508b154c-f0e2-4c4a-bd07-9490c2472d8f
   3.465 +2019-04-21 11:13:34,818 DEBUG extension.InputsAndOutputsActivityInitialiser [pool-1-thread-1,debug:84] Activity uk.ac.roe.wfau.firethorn.Context hasInputAttributes: false
   3.466 +2019-04-21 11:13:34,819 DEBUG extension.InputsAndOutputsActivityInitialiser [pool-1-thread-1,debug:84] Activity uk.org.ogsadai.DeliverToRequestStatus hasInputAttributes: false
   3.467 +2019-04-21 11:13:34,820 DEBUG io.BufferedPipe [pool-1-thread-1,debug:84] Created a BufferedPipe: ogsadai-ff9b6f58-0dee-4d3f-8611-6420d4831ad8
   3.468 +2019-04-21 11:13:34,820 DEBUG extension.InputsAndOutputsActivityInitialiser [pool-1-thread-1,debug:84] Activity uk.ac.roe.wfau.firethorn.Delays hasInputAttributes: false
   3.469 +2019-04-21 11:13:34,820 DEBUG io.BufferedPipe [pool-1-thread-1,debug:84] Created a BufferedPipe: ogsadai-1c0762b4-3280-4c2b-85b4-008d3a496703
   3.470 +2019-04-21 11:13:34,821 DEBUG event.LoggingActivityListener [pool-1-thread-1,debug:84] PENDING: Activity uk.org.ogsadai.SQLQuery (instance name = uk.org.ogsadai.SQLQuery-ogsadai-373dfd1d-3e3a-4f4f-855c-77a1cb640d3b)
   3.471 +2019-04-21 11:13:34,821 DEBUG event.LoggingActivityListener [pool-1-thread-1,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.JdbcInsertData (instance name = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-a3f304c6-b611-405b-865d-569603c435d4)
   3.472 +2019-04-21 11:13:34,821 DEBUG event.LoggingActivityListener [pool-1-thread-1,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.Limits (instance name = uk.ac.roe.wfau.firethorn.Limits-ogsadai-3bc71121-ae5a-4970-8c2c-c55d8306bdb5)
   3.473 +2019-04-21 11:13:34,821 DEBUG event.LoggingActivityListener [pool-1-thread-1,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-7d7668ff-d63e-4d15-835b-8317057e21e6)
   3.474 +2019-04-21 11:13:34,822 DEBUG event.LoggingActivityListener [pool-1-thread-1,debug:84] PENDING: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-ff255f1b-4fbd-4cf5-8510-75b92573f4b0)
   3.475 +2019-04-21 11:13:34,822 DEBUG event.LoggingActivityListener [pool-1-thread-1,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.Delays (instance name = uk.ac.roe.wfau.firethorn.Delays-ogsadai-92dea697-5a16-4bf1-aa17-db8f947796cd)
   3.476 +2019-04-21 11:13:34,822 DEBUG event.LoggingActivityListener [pool-1-thread-3,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.Limits (instance name = uk.ac.roe.wfau.firethorn.Limits-ogsadai-3bc71121-ae5a-4970-8c2c-c55d8306bdb5)
   3.477 +2019-04-21 11:13:34,822 DEBUG event.LoggingActivityListener [pool-1-thread-2,debug:84] PROCESSING: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-ff255f1b-4fbd-4cf5-8510-75b92573f4b0)
   3.478 +2019-04-21 11:13:34,823 DEBUG event.LoggingActivityListener [pool-1-thread-4,debug:84] PROCESSING: Activity uk.org.ogsadai.SQLQuery (instance name = uk.org.ogsadai.SQLQuery-ogsadai-373dfd1d-3e3a-4f4f-855c-77a1cb640d3b)
   3.479 +2019-04-21 11:13:34,823 DEBUG activity.MatchedIterativeActivity [pool-1-thread-4,debug:84] performing first stage of processing
   3.480 +2019-04-21 11:13:34,824 DEBUG event.LoggingActivityListener [pool-1-thread-5,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.JdbcInsertData (instance name = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-a3f304c6-b611-405b-865d-569603c435d4)
   3.481 +2019-04-21 11:13:34,825 DEBUG event.LoggingActivityListener [pool-1-thread-6,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-7d7668ff-d63e-4d15-835b-8317057e21e6)
   3.482 +2019-04-21 11:13:34,825 DEBUG activity.MatchedIterativeActivity [pool-1-thread-6,debug:84] performing first stage of processing
   3.483 +2019-04-21 11:13:34,825 DEBUG blue.ContextActivity [pool-1-thread-6,preprocess:126] preprocess()
   3.484 +2019-04-21 11:13:34,825 DEBUG activity.MatchedIterativeActivity [pool-1-thread-6,debug:84] performing iterative stage of processing
   3.485 +2019-04-21 11:13:34,825 DEBUG blue.ContextActivity [pool-1-thread-6,processIteration:150] processIteration(Object[])
   3.486 +2019-04-21 11:13:34,826 DEBUG blue.ContextActivity [pool-1-thread-6,processIteration:194]  value [SELECT TOP 10 [ATLASDR1].[dbo].[Filter].[filterID] AS "filterID",[ATLASDR1].[dbo].[Filter].[shortName] AS "shortName",[ATLASDR1].[dbo].[Filter].[name] AS "name",[ATLASDR1].[dbo].[Filter].[description] AS "description",[ATLASDR1].[dbo].[Filter].[cutOn] AS "cutOn",[ATLASDR1].[dbo].[Filter].[cutOff] AS "cutOff",[ATLASDR1].[dbo].[Filter].[aebv] AS "aebv",[ATLASDR1].[dbo].[Filter].[vegaToAB] AS "vegaToAB",[ATLASDR1].[dbo].[Filter].[oneSecMLVg] AS "oneSecMLVg",[ATLASDR1].[dbo].[Filter].[isSectioned] AS "isSectioned"
   3.487 +FROM [ATLASDR1].[dbo].[Filter]]
   3.488 +2019-04-21 11:13:34,826 DEBUG activity.MatchedIterativeActivity [pool-1-thread-6,debug:84] performing last stage of processing
   3.489 +2019-04-21 11:13:34,826 DEBUG blue.ContextActivity [pool-1-thread-6,postprocess:227] postprocess()
   3.490 +2019-04-21 11:13:34,826 DEBUG activity.MatchedIterativeActivity [pool-1-thread-6,debug:84] performing clean-up
   3.491 +2019-04-21 11:13:34,826 DEBUG event.LoggingActivityListener [pool-1-thread-6,debug:84] COMPLETED: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-7d7668ff-d63e-4d15-835b-8317057e21e6)
   3.492 +2019-04-21 11:13:34,827 DEBUG event.LoggingActivityListener [pool-1-thread-7,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.Delays (instance name = uk.ac.roe.wfau.firethorn.Delays-ogsadai-92dea697-5a16-4bf1-aa17-db8f947796cd)
   3.493 +2019-04-21 11:13:34,845 DEBUG activity.MatchedIterativeActivity [pool-1-thread-5,debug:84] performing first stage of processing
   3.494 +2019-04-21 11:13:34,846 WARN  jdbc.JdbcInsertDataActivity [pool-1-thread-5,preprocess:202] ** preprocess *** Line 201 - Before callback
   3.495 +2019-04-21 11:13:34,845 DEBUG activity.MatchedIterativeActivity [pool-1-thread-3,debug:84] performing first stage of processing
   3.496 +2019-04-21 11:13:34,846 DEBUG data.LimitsActivity [pool-1-thread-3,preprocess:144] preprocess()
   3.497 +2019-04-21 11:13:34,840 DEBUG context.RequestContextFactory [http-nio-8080-exec-4,createContext:62] createContext(HttpServletRequest)
   3.498 +2019-04-21 11:13:34,847 DEBUG context.RequestContextFactory [http-nio-8080-exec-4,createContext:64] remote [172.18.0.5]
   3.499 +2019-04-21 11:13:34,856 DEBUG activity.MatchedIterativeActivity [pool-1-thread-7,debug:84] performing first stage of processing
   3.500 +2019-04-21 11:13:34,856 DEBUG data.DelaysActivity [pool-1-thread-7,preprocess:102] preprocess()
   3.501 +2019-04-21 11:13:34,856 DEBUG activity.MatchedIterativeActivity [pool-1-thread-7,debug:84] performing iterative stage of processing
   3.502 +2019-04-21 11:13:35,111 DEBUG activity.MatchedIterativeActivity [pool-1-thread-3,debug:84] performing iterative stage of processing
   3.503 +2019-04-21 11:13:35,111 DEBUG file.SimpleFileLoginProvider [pool-1-thread-5,debug:84] In SimpleFileLogin:getLogin
   3.504 +2019-04-21 11:13:35,112 DEBUG file.SimpleFileLoginProvider [pool-1-thread-5,debug:84] In getLogin: ResourceID:  User ID: null
   3.505 +2019-04-21 11:13:35,112 DEBUG file.SimpleFileLoginProvider [pool-1-thread-5,debug:84] Found 4 lines that match
   3.506 +2019-04-21 11:13:35,112 DEBUG file.SimpleFileLoginProvider [pool-1-thread-5,debug:84] Found a login for null
   3.507 +2019-04-21 11:13:35,119 DEBUG jdbc.JDBCDataResource [pool-1-thread-5,debug:84] Creating a connection pool for resource ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73 at jdbc:jtds:sqlserver://ramses2/LIVEFirethornUserdataULOV20180719
   3.508 +2019-04-21 11:13:35,119 DEBUG jdbc.JDBCDataResource [pool-1-thread-5,debug:84] DBCP connection pool settings: 
   3.509 +MaxActive = 8
   3.510 +MaxIdle = 8
   3.511 +WhenExhaustedAction = 1
   3.512 +TestOnBorrow = false
   3.513 +TestOnReturn = false
   3.514 +TimeBetweenEvictionRunsMillis = -1
   3.515 +MinEvictableIdleTimeMillis = 1800000
   3.516 +TestWhileIdle = false
   3.517 +SoftMinEvictableIdleTimeMillis = -1
   3.518 +NumTestsPerEvictionRun = 3
   3.519 +Lifo = true
   3.520 +MaxWait = -1
   3.521 +2019-04-21 11:13:35,130 DEBUG file.SimpleFileLoginProvider [pool-1-thread-4,debug:84] In SimpleFileLogin:getLogin
   3.522 +2019-04-21 11:13:35,131 DEBUG file.SimpleFileLoginProvider [pool-1-thread-4,debug:84] In getLogin: ResourceID:  User ID: null
   3.523 +2019-04-21 11:13:35,131 DEBUG file.SimpleFileLoginProvider [pool-1-thread-4,debug:84] Found 4 lines that match
   3.524 +2019-04-21 11:13:35,131 DEBUG file.SimpleFileLoginProvider [pool-1-thread-4,debug:84] Found a login for null
   3.525 +2019-04-21 11:13:35,133 DEBUG jdbc.JDBCDataResource [pool-1-thread-5,debug:84] Checked if a pool with a similar URL has not  already been registered.
   3.526 +2019-04-21 11:13:35,133 DEBUG jdbc.JDBCDataResource [pool-1-thread-5,debug:84] Using JDBC URL : jdbc:apache:commons:dbcp:ogsadai-jdbcpool-ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73
   3.527 +2019-04-21 11:13:35,131 DEBUG jdbc.JDBCDataResource [pool-1-thread-4,debug:84] Creating a connection pool for resource ogsadai-d2794a0d-d5ee-47d9-9e12-4e06d2148976 at jdbc:jtds:sqlserver://192.168.137.11/ATLASDR3
   3.528 +2019-04-21 11:13:35,135 DEBUG jdbc.JDBCDataResource [pool-1-thread-4,debug:84] DBCP connection pool settings: 
   3.529 +MaxActive = 8
   3.530 +MaxIdle = 8
   3.531 +WhenExhaustedAction = 1
   3.532 +TestOnBorrow = false
   3.533 +TestOnReturn = false
   3.534 +TimeBetweenEvictionRunsMillis = -1
   3.535 +MinEvictableIdleTimeMillis = 1800000
   3.536 +TestWhileIdle = false
   3.537 +SoftMinEvictableIdleTimeMillis = -1
   3.538 +NumTestsPerEvictionRun = 3
   3.539 +Lifo = true
   3.540 +MaxWait = -1
   3.541 +2019-04-21 11:13:35,136 DEBUG jdbc.JDBCDataResource [pool-1-thread-4,debug:84] Checked if a pool with a similar URL has not  already been registered.
   3.542 +2019-04-21 11:13:35,136 DEBUG jdbc.JDBCDataResource [pool-1-thread-4,debug:84] Using JDBC URL : jdbc:apache:commons:dbcp:ogsadai-jdbcpool-ogsadai-d2794a0d-d5ee-47d9-9e12-4e06d2148976
   3.543 +2019-04-21 11:13:35,242 DEBUG activity.MatchedIterativeActivity [pool-1-thread-5,debug:84] performing iterative stage of processing
   3.544 +2019-04-21 11:13:35,242 DEBUG activity.MatchedIterativeActivity [pool-1-thread-4,debug:84] performing iterative stage of processing
   3.545 +2019-04-21 11:13:35,243 DEBUG sql.SQLQueryActivity [pool-1-thread-4,processIteration:259] SQL QUERY: SELECT TOP 10 [ATLASDR1].[dbo].[Filter].[filterID] AS "filterID",[ATLASDR1].[dbo].[Filter].[shortName] AS "shortName",[ATLASDR1].[dbo].[Filter].[name] AS "name",[ATLASDR1].[dbo].[Filter].[description] AS "description",[ATLASDR1].[dbo].[Filter].[cutOn] AS "cutOn",[ATLASDR1].[dbo].[Filter].[cutOff] AS "cutOff",[ATLASDR1].[dbo].[Filter].[aebv] AS "aebv",[ATLASDR1].[dbo].[Filter].[vegaToAB] AS "vegaToAB",[ATLASDR1].[dbo].[Filter].[oneSecMLVg] AS "oneSecMLVg",[ATLASDR1].[dbo].[Filter].[isSectioned] AS "isSectioned"
   3.546 +FROM [ATLASDR1].[dbo].[Filter]
   3.547 +2019-04-21 11:13:35,243 DEBUG sql.SQLQueryActivity [pool-1-thread-4,processIteration:262] Initialising query
   3.548 +2019-04-21 11:13:35,245 DEBUG sql.SQLQueryActivity [pool-1-thread-4,processIteration:274] Executing query
   3.549 +2019-04-21 11:13:35,245 DEBUG sql.SQLQueryActivity [pool-1-thread-4,executeQuery:364] Creating CallableStatement for query
   3.550 +2019-04-21 11:13:35,247 DEBUG sql.SQLQueryActivity [pool-1-thread-4,executeQuery:369] Submitting CallableStatement to ExecutorService
   3.551 +2019-04-21 11:13:35,248 DEBUG sql.SQLQueryActivity [pool-1-thread-4,executeQuery:376] Initiating CallableStatement and starting background execution
   3.552 +2019-04-21 11:13:35,248 DEBUG sql.SQLQueryActivity [pool-1-thread-4,processIteration:323] Caught a SQLException, wrapping it in an ActivitySQLUserException
   3.553 +2019-04-21 11:13:35,249 DEBUG activity.MatchedIterativeActivity [pool-1-thread-4,debug:84] performing clean-up
   3.554 +2019-04-21 11:13:35,250 DEBUG sql.SQLQueryActivity [pool-1-thread-4,cleanUp:466] Shutting down ExecutorService
   3.555 +2019-04-21 11:13:35,250 DEBUG sql.SQLQueryActivity [pool-1-thread-4,cleanUp:471] Closing Statement
   3.556 +2019-04-21 11:13:35,251 DEBUG sql.SQLQueryActivity [pool-1-thread-4,cleanUp:478] Releasing Connection
   3.557 +2019-04-21 11:13:35,251 DEBUG event.LoggingActivityListener [pool-1-thread-4,debug:84] uk.org.ogsadai.activity.sql.ActivitySQLUserException: The activity encountered a problem while interacting with a relational database.
   3.558 +	at uk.ac.roe.wfau.firethorn.ogsadai.activity.server.sql.SQLQueryActivity.processIteration(SQLQueryActivity.java:324)
   3.559 +	at uk.org.ogsadai.activity.MatchedIterativeActivity.process(MatchedIterativeActivity.java:90)
   3.560 +	at uk.org.ogsadai.activity.event.EventfulActivity.process(EventfulActivity.java:78)
   3.561 +	at uk.org.ogsadai.activity.concurrency.ActivityProcessingTask.call(ActivityProcessingTask.java:81)
   3.562 +	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
   3.563 +	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   3.564 +	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
   3.565 +	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   3.566 +	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   3.567 +	at java.lang.Thread.run(Thread.java:748)
   3.568 +Caused by: java.sql.SQLException: Warning: Fatal error 823 occurred at Mar 19 2019 12:57PM. Note the error and time, and contact your system administrator.
   3.569 +	at uk.ac.roe.wfau.firethorn.ogsadai.activity.common.chaos.MonkeyParamBase.sqlException(MonkeyParamBase.java:116)
   3.570 +	at uk.ac.roe.wfau.firethorn.ogsadai.activity.server.sql.SQLQueryActivity.executeQuery(SQLQueryActivity.java:383)
   3.571 +	at uk.ac.roe.wfau.firethorn.ogsadai.activity.server.sql.SQLQueryActivity.processIteration(SQLQueryActivity.java:277)
   3.572 +	... 9 more
   3.573 +
   3.574 +
     4.1 --- /dev/null	Thu Jan 01 00:00:00 1970 +0000
     4.2 +++ b/doc/notes/stv/20190421-OGSADAI-Graceful-FAIL.txt	Sun Apr 21 15:45:44 2019 +0300
     4.3 @@ -0,0 +1,324 @@
     4.4 +2019-04-21 11:15:53,963 DEBUG context.RequestContextFactory [http-nio-8080-exec-8,createContext:62] createContext(HttpServletRequest)
     4.5 +2019-04-21 11:15:53,964 DEBUG context.RequestContextFactory [http-nio-8080-exec-8,createContext:64] remote [172.18.0.5]
     4.6 +2019-04-21 11:15:54,154 DEBUG context.RequestContextFactory [http-nio-8080-exec-10,createContext:62] createContext(HttpServletRequest)
     4.7 +2019-04-21 11:15:54,154 DEBUG context.RequestContextFactory [http-nio-8080-exec-10,createContext:64] remote [172.18.0.5]
     4.8 +2019-04-21 11:15:54,157 DEBUG drer.SimpleDRER [http-nio-8080-exec-10,debug:84] Entered execute(CandidateRequestDescriptor)
     4.9 +2019-04-21 11:15:54,158 DEBUG drer.SimpleDRER [http-nio-8080-exec-10,debug:84] Workflow: Activity:
    4.10 +    activity name   = uk.ac.roe.wfau.firethorn.Delays
    4.11 +    instance name   = uk.ac.roe.wfau.firethorn.Delays-ogsadai-67368124-ee07-409d-a2d1-19b3888301d8
    4.12 +    inputs:
    4.13 +        ActivityInputStream[name=tuples,pipeName=ogsadai-4f87da5f-a11c-4ced-bfb4-6278cc96ed53]
    4.14 +    outputs:
    4.15 +        ActivityOutputStream[name=tuples,pipeName=ogsadai-f7ce20f3-f8ec-47f9-9dbf-05099958cf1d,source=uk.ac.roe.wfau.firethorn.Delays-ogsadai-67368124-ee07-409d-a2d1-19b3888301d8
    4.16 +
    4.17 +Activity:
    4.18 +    activity name   = uk.ac.roe.wfau.firethorn.Limits
    4.19 +    instance name   = uk.ac.roe.wfau.firethorn.Limits-ogsadai-1fd04cd8-5d01-4785-8654-f3861b8af05f
    4.20 +    inputs:
    4.21 +        Literal: limit.rows : 1000000
    4.22 +        ActivityInputStream[name=tuples,pipeName=ogsadai-f7ce20f3-f8ec-47f9-9dbf-05099958cf1d]
    4.23 +    outputs:
    4.24 +        ActivityOutputStream[name=tuples,pipeName=ogsadai-6f3dbcc5-6f5f-4482-a8fa-61d24b79bab6,source=uk.ac.roe.wfau.firethorn.Limits-ogsadai-1fd04cd8-5d01-4785-8654-f3861b8af05f
    4.25 +
    4.26 +Activity:
    4.27 +    activity name   = uk.ac.roe.wfau.firethorn.JdbcInsertData
    4.28 +    instance name   = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-b953b608-a1c5-417f-b767-516dd4426460
    4.29 +    target resource = ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73
    4.30 +    inputs:
    4.31 +        ActivityInputStream[name=tuples,pipeName=ogsadai-6f3dbcc5-6f5f-4482-a8fa-61d24b79bab6]
    4.32 +        Literal: jdbc.insert.table.name : [LIVEFirethornUserdataULOV20180719].[dbo].[XX_LFNK5CBJNSBISAAAAFVD7HAQ6M]
    4.33 +    outputs:
    4.34 +        ActivityOutputStream[name=jdbc.insert.results,pipeName=ogsadai-0b91b152-99d4-458c-bd37-857be5478a25,source=uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-b953b608-a1c5-417f-b767-516dd4426460
    4.35 +
    4.36 +Activity:
    4.37 +    activity name   = uk.org.ogsadai.SQLQuery
    4.38 +    instance name   = uk.org.ogsadai.SQLQuery-ogsadai-3f0e5175-b6c9-4c1a-aadc-5e4cdf1bedda
    4.39 +    target resource = ogsadai-d2794a0d-d5ee-47d9-9e12-4e06d2148976
    4.40 +    inputs:
    4.41 +        ActivityInputStream[name=expression,pipeName=ogsadai-5c9ef17d-dad0-48c1-a325-6fea08fbe9e5]
    4.42 +    outputs:
    4.43 +        ActivityOutputStream[name=data,pipeName=ogsadai-4f87da5f-a11c-4ced-bfb4-6278cc96ed53,source=uk.org.ogsadai.SQLQuery-ogsadai-3f0e5175-b6c9-4c1a-aadc-5e4cdf1bedda
    4.44 +
    4.45 +Activity:
    4.46 +    activity name   = uk.ac.roe.wfau.firethorn.Context
    4.47 +    instance name   = uk.ac.roe.wfau.firethorn.Context-ogsadai-245968b6-bc51-4de9-8215-fd6a91611510
    4.48 +    inputs:
    4.49 +        Literal: callback.port.input : 8081
    4.50 +        Literal: context.ident.input : 33203
    4.51 +        Literal: context.pipeline.input : SELECT TOP 10 [ATLASDR1].[dbo].[Filter].[filterID] AS "filterID",[ATLASDR1].[dbo].[Filter].[shortName] AS "shortName",[ATLASDR1].[dbo].[Filter].[name] AS "name",[ATLASDR1].[dbo].[Filter].[description] AS "description",[ATLASDR1].[dbo].[Filter].[cutOn] AS "cutOn",[ATLASDR1].[dbo].[Filter].[cutOff] AS "cutOff",[ATLASDR1].[dbo].[Filter].[aebv] AS "aebv",[ATLASDR1].[dbo].[Filter].[vegaToAB] AS "vegaToAB",[ATLASDR1].[dbo].[Filter].[oneSecMLVg] AS "oneSecMLVg",[ATLASDR1].[dbo].[Filter].[isSectioned] AS "isSectioned"
    4.52 +FROM [ATLASDR1].[dbo].[Filter]
    4.53 +        Literal: monkey.param.name : uk.ac.roe.wfau.firethorn.ogsadai.activity.server.sql.SQLQueryActivity
    4.54 +        Literal: monkey.param.data : jG2hdk00
    4.55 +    outputs:
    4.56 +        ActivityOutputStream[name=context.pipeline.output,pipeName=ogsadai-5c9ef17d-dad0-48c1-a325-6fea08fbe9e5,source=uk.ac.roe.wfau.firethorn.Context-ogsadai-245968b6-bc51-4de9-8215-fd6a91611510
    4.57 +
    4.58 +Activity:
    4.59 +    activity name   = uk.org.ogsadai.DeliverToRequestStatus
    4.60 +    instance name   = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-4b7e7809-7597-41be-9ff1-87f7937073a0
    4.61 +    inputs:
    4.62 +        ActivityInputStream[name=input,pipeName=ogsadai-0b91b152-99d4-458c-bd37-857be5478a25]
    4.63 +    outputs:
    4.64 +
    4.65 +
    4.66 +2019-04-21 11:15:54,158 DEBUG drer.SimpleDRER [http-nio-8080-exec-10,debug:84] Entered execute(activity.RequestDescriptor)
    4.67 +2019-04-21 11:15:54,163 DEBUG activity.OGSADAIActivityFramework [http-nio-8080-exec-10,debug:84] Found Workflow in RequestDescriptor: Activity:
    4.68 +    activity name   = uk.ac.roe.wfau.firethorn.Delays
    4.69 +    instance name   = uk.ac.roe.wfau.firethorn.Delays-ogsadai-67368124-ee07-409d-a2d1-19b3888301d8
    4.70 +    inputs:
    4.71 +        ActivityInputStream[name=tuples,pipeName=ogsadai-4f87da5f-a11c-4ced-bfb4-6278cc96ed53]
    4.72 +    outputs:
    4.73 +        ActivityOutputStream[name=tuples,pipeName=ogsadai-f7ce20f3-f8ec-47f9-9dbf-05099958cf1d,source=uk.ac.roe.wfau.firethorn.Delays-ogsadai-67368124-ee07-409d-a2d1-19b3888301d8
    4.74 +
    4.75 +Activity:
    4.76 +    activity name   = uk.ac.roe.wfau.firethorn.Limits
    4.77 +    instance name   = uk.ac.roe.wfau.firethorn.Limits-ogsadai-1fd04cd8-5d01-4785-8654-f3861b8af05f
    4.78 +    inputs:
    4.79 +        Literal: limit.rows : 1000000
    4.80 +        ActivityInputStream[name=tuples,pipeName=ogsadai-f7ce20f3-f8ec-47f9-9dbf-05099958cf1d]
    4.81 +    outputs:
    4.82 +        ActivityOutputStream[name=tuples,pipeName=ogsadai-6f3dbcc5-6f5f-4482-a8fa-61d24b79bab6,source=uk.ac.roe.wfau.firethorn.Limits-ogsadai-1fd04cd8-5d01-4785-8654-f3861b8af05f
    4.83 +
    4.84 +Activity:
    4.85 +    activity name   = uk.ac.roe.wfau.firethorn.JdbcInsertData
    4.86 +    instance name   = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-b953b608-a1c5-417f-b767-516dd4426460
    4.87 +    target resource = ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73
    4.88 +    inputs:
    4.89 +        ActivityInputStream[name=tuples,pipeName=ogsadai-6f3dbcc5-6f5f-4482-a8fa-61d24b79bab6]
    4.90 +        Literal: jdbc.insert.table.name : [LIVEFirethornUserdataULOV20180719].[dbo].[XX_LFNK5CBJNSBISAAAAFVD7HAQ6M]
    4.91 +    outputs:
    4.92 +        ActivityOutputStream[name=jdbc.insert.results,pipeName=ogsadai-0b91b152-99d4-458c-bd37-857be5478a25,source=uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-b953b608-a1c5-417f-b767-516dd4426460
    4.93 +
    4.94 +Activity:
    4.95 +    activity name   = uk.org.ogsadai.SQLQuery
    4.96 +    instance name   = uk.org.ogsadai.SQLQuery-ogsadai-3f0e5175-b6c9-4c1a-aadc-5e4cdf1bedda
    4.97 +    target resource = ogsadai-d2794a0d-d5ee-47d9-9e12-4e06d2148976
    4.98 +    inputs:
    4.99 +        ActivityInputStream[name=expression,pipeName=ogsadai-5c9ef17d-dad0-48c1-a325-6fea08fbe9e5]
   4.100 +    outputs:
   4.101 +        ActivityOutputStream[name=data,pipeName=ogsadai-4f87da5f-a11c-4ced-bfb4-6278cc96ed53,source=uk.org.ogsadai.SQLQuery-ogsadai-3f0e5175-b6c9-4c1a-aadc-5e4cdf1bedda
   4.102 +
   4.103 +Activity:
   4.104 +    activity name   = uk.ac.roe.wfau.firethorn.Context
   4.105 +    instance name   = uk.ac.roe.wfau.firethorn.Context-ogsadai-245968b6-bc51-4de9-8215-fd6a91611510
   4.106 +    inputs:
   4.107 +        Literal: callback.port.input : 8081
   4.108 +        Literal: context.ident.input : 33203
   4.109 +        Literal: context.pipeline.input : SELECT TOP 10 [ATLASDR1].[dbo].[Filter].[filterID] AS "filterID",[ATLASDR1].[dbo].[Filter].[shortName] AS "shortName",[ATLASDR1].[dbo].[Filter].[name] AS "name",[ATLASDR1].[dbo].[Filter].[description] AS "description",[ATLASDR1].[dbo].[Filter].[cutOn] AS "cutOn",[ATLASDR1].[dbo].[Filter].[cutOff] AS "cutOff",[ATLASDR1].[dbo].[Filter].[aebv] AS "aebv",[ATLASDR1].[dbo].[Filter].[vegaToAB] AS "vegaToAB",[ATLASDR1].[dbo].[Filter].[oneSecMLVg] AS "oneSecMLVg",[ATLASDR1].[dbo].[Filter].[isSectioned] AS "isSectioned"
   4.110 +FROM [ATLASDR1].[dbo].[Filter]
   4.111 +        Literal: monkey.param.name : uk.ac.roe.wfau.firethorn.ogsadai.activity.server.sql.SQLQueryActivity
   4.112 +        Literal: monkey.param.data : jG2hdk00
   4.113 +    outputs:
   4.114 +        ActivityOutputStream[name=context.pipeline.output,pipeName=ogsadai-5c9ef17d-dad0-48c1-a325-6fea08fbe9e5,source=uk.ac.roe.wfau.firethorn.Context-ogsadai-245968b6-bc51-4de9-8215-fd6a91611510
   4.115 +
   4.116 +Activity:
   4.117 +    activity name   = uk.org.ogsadai.DeliverToRequestStatus
   4.118 +    instance name   = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-4b7e7809-7597-41be-9ff1-87f7937073a0
   4.119 +    inputs:
   4.120 +        ActivityInputStream[name=input,pipeName=ogsadai-0b91b152-99d4-458c-bd37-857be5478a25]
   4.121 +    outputs:
   4.122 +
   4.123 +
   4.124 +2019-04-21 11:15:54,164 DEBUG drer.SimpleDRER [http-nio-8080-exec-10,debug:84] Created engine.Request: uk.org.ogsadai.activity.request.WorkflowRequest@64f818a6
   4.125 +2019-04-21 11:15:54,164 DEBUG drer.SimpleDRER [http-nio-8080-exec-10,debug:84] About to submit request to engine: uk.org.ogsadai.engine.RequestQueueingEngine@177969b3
   4.126 +2019-04-21 11:15:54,165 DEBUG drer.SimpleDRER [http-nio-8080-exec-10,debug:84] Sucessfully submitted request to engine: uk.org.ogsadai.engine.RequestQueueingEngine@177969b3
   4.127 +2019-04-21 11:15:54,171 DEBUG request.WorkflowRequest [pool-2-thread-2,debug:84] Did not get a workflow transformation from the context
   4.128 +2019-04-21 11:15:54,175 DEBUG pipeline.AutomaticTee [pool-2-thread-2,debug:84] Graph before transformation: 
   4.129 +2019-04-21 11:15:54,176 DEBUG pipeline.AutomaticTee [pool-2-thread-2,debug:84] Activity:
   4.130 +    activity name   = uk.ac.roe.wfau.firethorn.Delays
   4.131 +    instance name   = uk.ac.roe.wfau.firethorn.Delays-ogsadai-67368124-ee07-409d-a2d1-19b3888301d8
   4.132 +    inputs:
   4.133 +        ActivityInputStream[name=tuples,pipeName=ogsadai-4f87da5f-a11c-4ced-bfb4-6278cc96ed53]
   4.134 +    outputs:
   4.135 +        ActivityOutputStream[name=tuples,pipeName=ogsadai-f7ce20f3-f8ec-47f9-9dbf-05099958cf1d,source=uk.ac.roe.wfau.firethorn.Delays-ogsadai-67368124-ee07-409d-a2d1-19b3888301d8
   4.136 +
   4.137 +Activity:
   4.138 +    activity name   = uk.ac.roe.wfau.firethorn.Limits
   4.139 +    instance name   = uk.ac.roe.wfau.firethorn.Limits-ogsadai-1fd04cd8-5d01-4785-8654-f3861b8af05f
   4.140 +    inputs:
   4.141 +        Literal: limit.rows : 1000000
   4.142 +        ActivityInputStream[name=tuples,pipeName=ogsadai-f7ce20f3-f8ec-47f9-9dbf-05099958cf1d]
   4.143 +    outputs:
   4.144 +        ActivityOutputStream[name=tuples,pipeName=ogsadai-6f3dbcc5-6f5f-4482-a8fa-61d24b79bab6,source=uk.ac.roe.wfau.firethorn.Limits-ogsadai-1fd04cd8-5d01-4785-8654-f3861b8af05f
   4.145 +
   4.146 +Activity:
   4.147 +    activity name   = uk.ac.roe.wfau.firethorn.JdbcInsertData
   4.148 +    instance name   = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-b953b608-a1c5-417f-b767-516dd4426460
   4.149 +    target resource = ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73
   4.150 +    inputs:
   4.151 +        ActivityInputStream[name=tuples,pipeName=ogsadai-6f3dbcc5-6f5f-4482-a8fa-61d24b79bab6]
   4.152 +        Literal: jdbc.insert.table.name : [LIVEFirethornUserdataULOV20180719].[dbo].[XX_LFNK5CBJNSBISAAAAFVD7HAQ6M]
   4.153 +    outputs:
   4.154 +        ActivityOutputStream[name=jdbc.insert.results,pipeName=ogsadai-0b91b152-99d4-458c-bd37-857be5478a25,source=uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-b953b608-a1c5-417f-b767-516dd4426460
   4.155 +
   4.156 +Activity:
   4.157 +    activity name   = uk.org.ogsadai.SQLQuery
   4.158 +    instance name   = uk.org.ogsadai.SQLQuery-ogsadai-3f0e5175-b6c9-4c1a-aadc-5e4cdf1bedda
   4.159 +    target resource = ogsadai-d2794a0d-d5ee-47d9-9e12-4e06d2148976
   4.160 +    inputs:
   4.161 +        ActivityInputStream[name=expression,pipeName=ogsadai-5c9ef17d-dad0-48c1-a325-6fea08fbe9e5]
   4.162 +    outputs:
   4.163 +        ActivityOutputStream[name=data,pipeName=ogsadai-4f87da5f-a11c-4ced-bfb4-6278cc96ed53,source=uk.org.ogsadai.SQLQuery-ogsadai-3f0e5175-b6c9-4c1a-aadc-5e4cdf1bedda
   4.164 +
   4.165 +Activity:
   4.166 +    activity name   = uk.ac.roe.wfau.firethorn.Context
   4.167 +    instance name   = uk.ac.roe.wfau.firethorn.Context-ogsadai-245968b6-bc51-4de9-8215-fd6a91611510
   4.168 +    inputs:
   4.169 +        Literal: callback.port.input : 8081
   4.170 +        Literal: context.ident.input : 33203
   4.171 +        Literal: context.pipeline.input : SELECT TOP 10 [ATLASDR1].[dbo].[Filter].[filterID] AS "filterID",[ATLASDR1].[dbo].[Filter].[shortName] AS "shortName",[ATLASDR1].[dbo].[Filter].[name] AS "name",[ATLASDR1].[dbo].[Filter].[description] AS "description",[ATLASDR1].[dbo].[Filter].[cutOn] AS "cutOn",[ATLASDR1].[dbo].[Filter].[cutOff] AS "cutOff",[ATLASDR1].[dbo].[Filter].[aebv] AS "aebv",[ATLASDR1].[dbo].[Filter].[vegaToAB] AS "vegaToAB",[ATLASDR1].[dbo].[Filter].[oneSecMLVg] AS "oneSecMLVg",[ATLASDR1].[dbo].[Filter].[isSectioned] AS "isSectioned"
   4.172 +FROM [ATLASDR1].[dbo].[Filter]
   4.173 +        Literal: monkey.param.name : uk.ac.roe.wfau.firethorn.ogsadai.activity.server.sql.SQLQueryActivity
   4.174 +        Literal: monkey.param.data : jG2hdk00
   4.175 +    outputs:
   4.176 +        ActivityOutputStream[name=context.pipeline.output,pipeName=ogsadai-5c9ef17d-dad0-48c1-a325-6fea08fbe9e5,source=uk.ac.roe.wfau.firethorn.Context-ogsadai-245968b6-bc51-4de9-8215-fd6a91611510
   4.177 +
   4.178 +Activity:
   4.179 +    activity name   = uk.org.ogsadai.DeliverToRequestStatus
   4.180 +    instance name   = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-4b7e7809-7597-41be-9ff1-87f7937073a0
   4.181 +    inputs:
   4.182 +        ActivityInputStream[name=input,pipeName=ogsadai-0b91b152-99d4-458c-bd37-857be5478a25]
   4.183 +    outputs:
   4.184 +
   4.185 +
   4.186 +2019-04-21 11:15:54,177 DEBUG pipeline.AutomaticTee [pool-2-thread-2,debug:84] Transformed graph: 
   4.187 +2019-04-21 11:15:54,178 DEBUG pipeline.AutomaticTee [pool-2-thread-2,debug:84] Activity:
   4.188 +    activity name   = uk.ac.roe.wfau.firethorn.Delays
   4.189 +    instance name   = uk.ac.roe.wfau.firethorn.Delays-ogsadai-67368124-ee07-409d-a2d1-19b3888301d8
   4.190 +    inputs:
   4.191 +        ActivityInputStream[name=tuples,pipeName=ogsadai-4f87da5f-a11c-4ced-bfb4-6278cc96ed53]
   4.192 +    outputs:
   4.193 +        ActivityOutputStream[name=tuples,pipeName=ogsadai-f7ce20f3-f8ec-47f9-9dbf-05099958cf1d,source=uk.ac.roe.wfau.firethorn.Delays-ogsadai-67368124-ee07-409d-a2d1-19b3888301d8
   4.194 +
   4.195 +Activity:
   4.196 +    activity name   = uk.ac.roe.wfau.firethorn.Limits
   4.197 +    instance name   = uk.ac.roe.wfau.firethorn.Limits-ogsadai-1fd04cd8-5d01-4785-8654-f3861b8af05f
   4.198 +    inputs:
   4.199 +        Literal: limit.rows : 1000000
   4.200 +        ActivityInputStream[name=tuples,pipeName=ogsadai-f7ce20f3-f8ec-47f9-9dbf-05099958cf1d]
   4.201 +    outputs:
   4.202 +        ActivityOutputStream[name=tuples,pipeName=ogsadai-6f3dbcc5-6f5f-4482-a8fa-61d24b79bab6,source=uk.ac.roe.wfau.firethorn.Limits-ogsadai-1fd04cd8-5d01-4785-8654-f3861b8af05f
   4.203 +
   4.204 +Activity:
   4.205 +    activity name   = uk.ac.roe.wfau.firethorn.JdbcInsertData
   4.206 +    instance name   = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-b953b608-a1c5-417f-b767-516dd4426460
   4.207 +    target resource = ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73
   4.208 +    inputs:
   4.209 +        ActivityInputStream[name=tuples,pipeName=ogsadai-6f3dbcc5-6f5f-4482-a8fa-61d24b79bab6]
   4.210 +        Literal: jdbc.insert.table.name : [LIVEFirethornUserdataULOV20180719].[dbo].[XX_LFNK5CBJNSBISAAAAFVD7HAQ6M]
   4.211 +    outputs:
   4.212 +        ActivityOutputStream[name=jdbc.insert.results,pipeName=ogsadai-0b91b152-99d4-458c-bd37-857be5478a25,source=uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-b953b608-a1c5-417f-b767-516dd4426460
   4.213 +
   4.214 +Activity:
   4.215 +    activity name   = uk.org.ogsadai.SQLQuery
   4.216 +    instance name   = uk.org.ogsadai.SQLQuery-ogsadai-3f0e5175-b6c9-4c1a-aadc-5e4cdf1bedda
   4.217 +    target resource = ogsadai-d2794a0d-d5ee-47d9-9e12-4e06d2148976
   4.218 +    inputs:
   4.219 +        ActivityInputStream[name=expression,pipeName=ogsadai-5c9ef17d-dad0-48c1-a325-6fea08fbe9e5]
   4.220 +    outputs:
   4.221 +        ActivityOutputStream[name=data,pipeName=ogsadai-4f87da5f-a11c-4ced-bfb4-6278cc96ed53,source=uk.org.ogsadai.SQLQuery-ogsadai-3f0e5175-b6c9-4c1a-aadc-5e4cdf1bedda
   4.222 +
   4.223 +Activity:
   4.224 +    activity name   = uk.ac.roe.wfau.firethorn.Context
   4.225 +    instance name   = uk.ac.roe.wfau.firethorn.Context-ogsadai-245968b6-bc51-4de9-8215-fd6a91611510
   4.226 +    inputs:
   4.227 +        Literal: callback.port.input : 8081
   4.228 +        Literal: context.ident.input : 33203
   4.229 +        Literal: context.pipeline.input : SELECT TOP 10 [ATLASDR1].[dbo].[Filter].[filterID] AS "filterID",[ATLASDR1].[dbo].[Filter].[shortName] AS "shortName",[ATLASDR1].[dbo].[Filter].[name] AS "name",[ATLASDR1].[dbo].[Filter].[description] AS "description",[ATLASDR1].[dbo].[Filter].[cutOn] AS "cutOn",[ATLASDR1].[dbo].[Filter].[cutOff] AS "cutOff",[ATLASDR1].[dbo].[Filter].[aebv] AS "aebv",[ATLASDR1].[dbo].[Filter].[vegaToAB] AS "vegaToAB",[ATLASDR1].[dbo].[Filter].[oneSecMLVg] AS "oneSecMLVg",[ATLASDR1].[dbo].[Filter].[isSectioned] AS "isSectioned"
   4.230 +FROM [ATLASDR1].[dbo].[Filter]
   4.231 +        Literal: monkey.param.name : uk.ac.roe.wfau.firethorn.ogsadai.activity.server.sql.SQLQueryActivity
   4.232 +        Literal: monkey.param.data : jG2hdk00
   4.233 +    outputs:
   4.234 +        ActivityOutputStream[name=context.pipeline.output,pipeName=ogsadai-5c9ef17d-dad0-48c1-a325-6fea08fbe9e5,source=uk.ac.roe.wfau.firethorn.Context-ogsadai-245968b6-bc51-4de9-8215-fd6a91611510
   4.235 +
   4.236 +Activity:
   4.237 +    activity name   = uk.org.ogsadai.DeliverToRequestStatus
   4.238 +    instance name   = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-4b7e7809-7597-41be-9ff1-87f7937073a0
   4.239 +    inputs:
   4.240 +        ActivityInputStream[name=input,pipeName=ogsadai-0b91b152-99d4-458c-bd37-857be5478a25]
   4.241 +    outputs:
   4.242 +
   4.243 +
   4.244 +2019-04-21 11:15:54,183 DEBUG extension.InputsAndOutputsActivityInitialiser [pool-1-thread-8,debug:84] Activity uk.org.ogsadai.DeliverToRequestStatus hasInputAttributes: false
   4.245 +2019-04-21 11:15:54,184 DEBUG io.BufferedPipe [pool-1-thread-8,debug:84] Created a BufferedPipe: ogsadai-0b91b152-99d4-458c-bd37-857be5478a25
   4.246 +2019-04-21 11:15:54,185 DEBUG extension.InputsAndOutputsActivityInitialiser [pool-1-thread-8,debug:84] Activity uk.org.ogsadai.SQLQuery hasInputAttributes: false
   4.247 +2019-04-21 11:15:54,185 DEBUG io.BufferedPipe [pool-1-thread-8,debug:84] Created a BufferedPipe: ogsadai-5c9ef17d-dad0-48c1-a325-6fea08fbe9e5
   4.248 +2019-04-21 11:15:54,186 ERROR extension.ServiceAddressesActivityInitialiser [pool-1-thread-8,errorExceptionAndChildren:401] #1555845354186:111# java.net.MalformedURLException: no protocol: ${ogsadai.endpoint}
   4.249 +2019-04-21 11:15:54,187 DEBUG extension.InputsAndOutputsActivityInitialiser [pool-1-thread-8,debug:84] Activity uk.ac.roe.wfau.firethorn.Limits hasInputAttributes: false
   4.250 +2019-04-21 11:15:54,187 DEBUG io.BufferedPipe [pool-1-thread-8,debug:84] Created a BufferedPipe: ogsadai-f7ce20f3-f8ec-47f9-9dbf-05099958cf1d
   4.251 +2019-04-21 11:15:54,187 DEBUG extension.InputsAndOutputsActivityInitialiser [pool-1-thread-8,debug:84] Activity uk.ac.roe.wfau.firethorn.Delays hasInputAttributes: false
   4.252 +2019-04-21 11:15:54,188 DEBUG io.BufferedPipe [pool-1-thread-8,debug:84] Created a BufferedPipe: ogsadai-4f87da5f-a11c-4ced-bfb4-6278cc96ed53
   4.253 +2019-04-21 11:15:54,188 DEBUG extension.InputsAndOutputsActivityInitialiser [pool-1-thread-8,debug:84] Activity uk.ac.roe.wfau.firethorn.Context hasInputAttributes: false
   4.254 +2019-04-21 11:15:54,188 DEBUG extension.InputsAndOutputsActivityInitialiser [pool-1-thread-8,debug:84] Activity uk.ac.roe.wfau.firethorn.JdbcInsertData hasInputAttributes: false
   4.255 +2019-04-21 11:15:54,188 DEBUG io.BufferedPipe [pool-1-thread-8,debug:84] Created a BufferedPipe: ogsadai-6f3dbcc5-6f5f-4482-a8fa-61d24b79bab6
   4.256 +2019-04-21 11:15:54,189 DEBUG event.LoggingActivityListener [pool-1-thread-8,debug:84] PENDING: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-4b7e7809-7597-41be-9ff1-87f7937073a0)
   4.257 +2019-04-21 11:15:54,190 DEBUG event.LoggingActivityListener [pool-1-thread-8,debug:84] PENDING: Activity uk.org.ogsadai.SQLQuery (instance name = uk.org.ogsadai.SQLQuery-ogsadai-3f0e5175-b6c9-4c1a-aadc-5e4cdf1bedda)
   4.258 +2019-04-21 11:15:54,190 DEBUG event.LoggingActivityListener [pool-1-thread-8,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.Limits (instance name = uk.ac.roe.wfau.firethorn.Limits-ogsadai-1fd04cd8-5d01-4785-8654-f3861b8af05f)
   4.259 +2019-04-21 11:15:54,190 DEBUG event.LoggingActivityListener [pool-1-thread-8,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.Delays (instance name = uk.ac.roe.wfau.firethorn.Delays-ogsadai-67368124-ee07-409d-a2d1-19b3888301d8)
   4.260 +2019-04-21 11:15:54,190 DEBUG event.LoggingActivityListener [pool-1-thread-8,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-245968b6-bc51-4de9-8215-fd6a91611510)
   4.261 +2019-04-21 11:15:54,191 DEBUG event.LoggingActivityListener [pool-1-thread-8,debug:84] PENDING: Activity uk.ac.roe.wfau.firethorn.JdbcInsertData (instance name = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-b953b608-a1c5-417f-b767-516dd4426460)
   4.262 +2019-04-21 11:15:54,196 DEBUG event.LoggingActivityListener [pool-1-thread-12,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.Limits (instance name = uk.ac.roe.wfau.firethorn.Limits-ogsadai-1fd04cd8-5d01-4785-8654-f3861b8af05f)
   4.263 +2019-04-21 11:15:54,204 DEBUG activity.MatchedIterativeActivity [pool-1-thread-12,debug:84] performing first stage of processing
   4.264 +2019-04-21 11:15:54,204 DEBUG data.LimitsActivity [pool-1-thread-12,preprocess:144] preprocess()
   4.265 +2019-04-21 11:15:54,204 DEBUG activity.MatchedIterativeActivity [pool-1-thread-12,debug:84] performing iterative stage of processing
   4.266 +2019-04-21 11:15:54,205 DEBUG event.LoggingActivityListener [pool-1-thread-9,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.Delays (instance name = uk.ac.roe.wfau.firethorn.Delays-ogsadai-67368124-ee07-409d-a2d1-19b3888301d8)
   4.267 +2019-04-21 11:15:54,209 DEBUG activity.MatchedIterativeActivity [pool-1-thread-9,debug:84] performing first stage of processing
   4.268 +2019-04-21 11:15:54,209 DEBUG event.LoggingActivityListener [pool-1-thread-14,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-245968b6-bc51-4de9-8215-fd6a91611510)
   4.269 +2019-04-21 11:15:54,209 DEBUG activity.MatchedIterativeActivity [pool-1-thread-14,debug:84] performing first stage of processing
   4.270 +2019-04-21 11:15:54,209 DEBUG data.DelaysActivity [pool-1-thread-9,preprocess:102] preprocess()
   4.271 +2019-04-21 11:15:54,212 DEBUG activity.MatchedIterativeActivity [pool-1-thread-9,debug:84] performing iterative stage of processing
   4.272 +2019-04-21 11:15:54,210 DEBUG event.LoggingActivityListener [pool-1-thread-10,debug:84] PROCESSING: Activity uk.org.ogsadai.DeliverToRequestStatus (instance name = uk.org.ogsadai.DeliverToRequestStatus-ogsadai-4b7e7809-7597-41be-9ff1-87f7937073a0)
   4.273 +2019-04-21 11:15:54,213 DEBUG event.LoggingActivityListener [pool-1-thread-11,debug:84] PROCESSING: Activity uk.org.ogsadai.SQLQuery (instance name = uk.org.ogsadai.SQLQuery-ogsadai-3f0e5175-b6c9-4c1a-aadc-5e4cdf1bedda)
   4.274 +2019-04-21 11:15:54,213 DEBUG activity.MatchedIterativeActivity [pool-1-thread-11,debug:84] performing first stage of processing
   4.275 +2019-04-21 11:15:54,213 DEBUG file.SimpleFileLoginProvider [pool-1-thread-11,debug:84] In SimpleFileLogin:getLogin
   4.276 +2019-04-21 11:15:54,214 DEBUG file.SimpleFileLoginProvider [pool-1-thread-11,debug:84] In getLogin: ResourceID:  User ID: null
   4.277 +2019-04-21 11:15:54,215 DEBUG file.SimpleFileLoginProvider [pool-1-thread-11,debug:84] Found 4 lines that match
   4.278 +2019-04-21 11:15:54,215 DEBUG file.SimpleFileLoginProvider [pool-1-thread-11,debug:84] Found a login for null
   4.279 +2019-04-21 11:15:54,212 DEBUG blue.ContextActivity [pool-1-thread-14,preprocess:126] preprocess()
   4.280 +2019-04-21 11:15:54,216 DEBUG activity.MatchedIterativeActivity [pool-1-thread-14,debug:84] performing iterative stage of processing
   4.281 +2019-04-21 11:15:54,216 DEBUG blue.ContextActivity [pool-1-thread-14,processIteration:150] processIteration(Object[])
   4.282 +2019-04-21 11:15:54,216 DEBUG blue.ContextActivity [pool-1-thread-14,processIteration:194]  value [SELECT TOP 10 [ATLASDR1].[dbo].[Filter].[filterID] AS "filterID",[ATLASDR1].[dbo].[Filter].[shortName] AS "shortName",[ATLASDR1].[dbo].[Filter].[name] AS "name",[ATLASDR1].[dbo].[Filter].[description] AS "description",[ATLASDR1].[dbo].[Filter].[cutOn] AS "cutOn",[ATLASDR1].[dbo].[Filter].[cutOff] AS "cutOff",[ATLASDR1].[dbo].[Filter].[aebv] AS "aebv",[ATLASDR1].[dbo].[Filter].[vegaToAB] AS "vegaToAB",[ATLASDR1].[dbo].[Filter].[oneSecMLVg] AS "oneSecMLVg",[ATLASDR1].[dbo].[Filter].[isSectioned] AS "isSectioned"
   4.283 +FROM [ATLASDR1].[dbo].[Filter]]
   4.284 +2019-04-21 11:15:54,216 DEBUG activity.MatchedIterativeActivity [pool-1-thread-14,debug:84] performing last stage of processing
   4.285 +2019-04-21 11:15:54,216 DEBUG blue.ContextActivity [pool-1-thread-14,postprocess:227] postprocess()
   4.286 +2019-04-21 11:15:54,217 DEBUG activity.MatchedIterativeActivity [pool-1-thread-14,debug:84] performing clean-up
   4.287 +2019-04-21 11:15:54,217 DEBUG event.LoggingActivityListener [pool-1-thread-13,debug:84] PROCESSING: Activity uk.ac.roe.wfau.firethorn.JdbcInsertData (instance name = uk.ac.roe.wfau.firethorn.JdbcInsertData-ogsadai-b953b608-a1c5-417f-b767-516dd4426460)
   4.288 +2019-04-21 11:15:54,219 DEBUG activity.MatchedIterativeActivity [pool-1-thread-13,debug:84] performing first stage of processing
   4.289 +2019-04-21 11:15:54,220 WARN  jdbc.JdbcInsertDataActivity [pool-1-thread-13,preprocess:202] ** preprocess *** Line 201 - Before callback
   4.290 +2019-04-21 11:15:54,220 DEBUG file.SimpleFileLoginProvider [pool-1-thread-13,debug:84] In SimpleFileLogin:getLogin
   4.291 +2019-04-21 11:15:54,219 DEBUG jdbc.JDBCDataResource [pool-1-thread-11,debug:84] Using JDBC URL : jdbc:apache:commons:dbcp:ogsadai-jdbcpool-ogsadai-d2794a0d-d5ee-47d9-9e12-4e06d2148976
   4.292 +2019-04-21 11:15:54,221 DEBUG event.LoggingActivityListener [pool-1-thread-14,debug:84] COMPLETED: Activity uk.ac.roe.wfau.firethorn.Context (instance name = uk.ac.roe.wfau.firethorn.Context-ogsadai-245968b6-bc51-4de9-8215-fd6a91611510)
   4.293 +2019-04-21 11:15:54,222 DEBUG file.SimpleFileLoginProvider [pool-1-thread-13,debug:84] In getLogin: ResourceID:  User ID: null
   4.294 +2019-04-21 11:15:54,223 DEBUG activity.MatchedIterativeActivity [pool-1-thread-11,debug:84] performing iterative stage of processing
   4.295 +2019-04-21 11:15:54,224 DEBUG sql.SQLQueryActivity [pool-1-thread-11,processIteration:259] SQL QUERY: SELECT TOP 10 [ATLASDR1].[dbo].[Filter].[filterID] AS "filterID",[ATLASDR1].[dbo].[Filter].[shortName] AS "shortName",[ATLASDR1].[dbo].[Filter].[name] AS "name",[ATLASDR1].[dbo].[Filter].[description] AS "description",[ATLASDR1].[dbo].[Filter].[cutOn] AS "cutOn",[ATLASDR1].[dbo].[Filter].[cutOff] AS "cutOff",[ATLASDR1].[dbo].[Filter].[aebv] AS "aebv",[ATLASDR1].[dbo].[Filter].[vegaToAB] AS "vegaToAB",[ATLASDR1].[dbo].[Filter].[oneSecMLVg] AS "oneSecMLVg",[ATLASDR1].[dbo].[Filter].[isSectioned] AS "isSectioned"
   4.296 +FROM [ATLASDR1].[dbo].[Filter]
   4.297 +2019-04-21 11:15:54,225 DEBUG sql.SQLQueryActivity [pool-1-thread-11,processIteration:262] Initialising query
   4.298 +2019-04-21 11:15:54,225 DEBUG file.SimpleFileLoginProvider [pool-1-thread-13,debug:84] Found 4 lines that match
   4.299 +2019-04-21 11:15:54,231 DEBUG file.SimpleFileLoginProvider [pool-1-thread-13,debug:84] Found a login for null
   4.300 +2019-04-21 11:15:54,232 DEBUG sql.SQLQueryActivity [pool-1-thread-11,processIteration:274] Executing query
   4.301 +2019-04-21 11:15:54,235 DEBUG sql.SQLQueryActivity [pool-1-thread-11,executeQuery:364] Creating CallableStatement for query
   4.302 +2019-04-21 11:15:54,235 DEBUG sql.SQLQueryActivity [pool-1-thread-11,executeQuery:369] Submitting CallableStatement to ExecutorService
   4.303 +2019-04-21 11:15:54,235 DEBUG jdbc.JDBCDataResource [pool-1-thread-13,debug:84] Using JDBC URL : jdbc:apache:commons:dbcp:ogsadai-jdbcpool-ogsadai-5f913864-d792-4227-9bfc-ca03a4950f73
   4.304 +2019-04-21 11:15:54,237 DEBUG sql.SQLQueryActivity [pool-1-thread-11,executeQuery:376] Initiating CallableStatement and starting background execution
   4.305 +2019-04-21 11:15:54,237 DEBUG sql.SQLQueryActivity [pool-1-thread-11,processIteration:323] Caught a SQLException, wrapping it in an ActivitySQLUserException
   4.306 +2019-04-21 11:15:54,237 DEBUG activity.MatchedIterativeActivity [pool-1-thread-11,debug:84] performing clean-up
   4.307 +2019-04-21 11:15:54,237 DEBUG sql.SQLQueryActivity [pool-1-thread-11,cleanUp:466] Shutting down ExecutorService
   4.308 +2019-04-21 11:15:54,237 DEBUG sql.SQLQueryActivity [pool-1-thread-11,cleanUp:471] Closing Statement
   4.309 +2019-04-21 11:15:54,239 DEBUG sql.SQLQueryActivity [pool-1-thread-11,cleanUp:478] Releasing Connection
   4.310 +2019-04-21 11:15:54,246 DEBUG event.LoggingActivityListener [pool-1-thread-11,debug:84] uk.org.ogsadai.activity.sql.ActivitySQLUserException: The activity encountered a problem while interacting with a relational database.
   4.311 +	at uk.ac.roe.wfau.firethorn.ogsadai.activity.server.sql.SQLQueryActivity.processIteration(SQLQueryActivity.java:324)
   4.312 +	at uk.org.ogsadai.activity.MatchedIterativeActivity.process(MatchedIterativeActivity.java:90)
   4.313 +	at uk.org.ogsadai.activity.event.EventfulActivity.process(EventfulActivity.java:78)
   4.314 +	at uk.org.ogsadai.activity.concurrency.ActivityProcessingTask.call(ActivityProcessingTask.java:81)
   4.315 +	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
   4.316 +	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   4.317 +	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
   4.318 +	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   4.319 +	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   4.320 +	at java.lang.Thread.run(Thread.java:748)
   4.321 +Caused by: java.sql.SQLException: Warning: Fatal error 823 occurred at Mar 19 2019 12:57PM. Note the error and time, and contact your system administrator.
   4.322 +	at uk.ac.roe.wfau.firethorn.ogsadai.activity.common.chaos.MonkeyParamBase.sqlException(MonkeyParamBase.java:116)
   4.323 +	at uk.ac.roe.wfau.firethorn.ogsadai.activity.server.sql.SQLQueryActivity.executeQuery(SQLQueryActivity.java:383)
   4.324 +	at uk.ac.roe.wfau.firethorn.ogsadai.activity.server.sql.SQLQueryActivity.processIteration(SQLQueryActivity.java:277)
   4.325 +	... 9 more
   4.326 +
   4.327 +