firethorn

changeset 4201:ac3a19756ace 2.1.28-tap-issues

Firethorn Logs, FAILED vs RUNNING, same query
author Stelios <stv@roe.ac.uk>
date Thu Apr 18 18:34:50 2019 +0300 (2019-04-18)
parents c8b3054c8e83
children a1390050bf66
files doc/notes/stv/20190418-Firethorn-Logs-Non-Propagating.txt doc/notes/stv/20190418-Firethorn-Logs-Propagating.txt
line diff
     1.1 --- a/doc/notes/stv/20190418-Firethorn-Logs-Non-Propagating.txt	Thu Apr 18 18:06:52 2019 +0300
     1.2 +++ b/doc/notes/stv/20190418-Firethorn-Logs-Non-Propagating.txt	Thu Apr 18 18:34:50 2019 +0300
     1.3 @@ -1,64 +1,3 @@
     1.4 -2019-04-18 14:07:52,819 DEBUG [FireThornTaskExecutor-4] [AdqlQueryTimings] AdqlQueryTimings() 
     1.5 -2019-04-18 14:07:52,828 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] Create JdbcColumn [XX_E3QN4P5W6LAWMAAAAFVDBRTRT4] 
     1.6 -2019-04-18 14:07:52,829 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] sqltype() 
     1.7 -2019-04-18 14:07:52,830 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   name [XX_E3QN4P5W6LAWMAAAAFVDBRTRT4] 
     1.8 -2019-04-18 14:07:52,830 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   type [VARBINARY] 
     1.9 -2019-04-18 14:07:52,830 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   size [0] 
    1.10 -2019-04-18 14:07:52,831 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   temp [VARBINARY(MAX)] 
    1.11 -2019-04-18 14:07:52,831 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] Create JdbcColumn [XX_VE4IEATL5VQR6AAAAFVDBRTRUU] 
    1.12 -2019-04-18 14:07:52,832 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] sqltype() 
    1.13 -2019-04-18 14:07:52,832 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   name [XX_VE4IEATL5VQR6AAAAFVDBRTRUU] 
    1.14 -2019-04-18 14:07:52,832 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   type [CHAR] 
    1.15 -2019-04-18 14:07:52,832 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   size [10] 
    1.16 -2019-04-18 14:07:52,832 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   temp [CHAR(10)] 
    1.17 -2019-04-18 14:07:52,832 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] Create JdbcColumn [XX_IHGXXSUN63QKSAAAAFVDBRTRVA] 
    1.18 -2019-04-18 14:07:52,832 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] sqltype() 
    1.19 -2019-04-18 14:07:52,832 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   name [XX_IHGXXSUN63QKSAAAAFVDBRTRVA] 
    1.20 -2019-04-18 14:07:52,832 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   type [CHAR] 
    1.21 -2019-04-18 14:07:52,833 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   size [16] 
    1.22 -2019-04-18 14:07:52,835 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   temp [CHAR(16)] 
    1.23 -2019-04-18 14:07:52,835 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] Create JdbcColumn [XX_XI7KPZ2NN7HFYAAAAFVDBRTRVQ] 
    1.24 -2019-04-18 14:07:52,835 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] sqltype() 
    1.25 -2019-04-18 14:07:52,835 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   name [XX_XI7KPZ2NN7HFYAAAAFVDBRTRVQ] 
    1.26 -2019-04-18 14:07:52,835 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   type [CHAR] 
    1.27 -2019-04-18 14:07:52,835 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   size [256] 
    1.28 -2019-04-18 14:07:52,835 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   temp [CHAR(256)] 
    1.29 -2019-04-18 14:07:52,835 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] Create JdbcColumn [XX_NL5HKGNVBUPUQAAAAFVDBRTRV4] 
    1.30 -2019-04-18 14:07:52,836 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] sqltype() 
    1.31 -2019-04-18 14:07:52,836 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   name [XX_NL5HKGNVBUPUQAAAAFVDBRTRV4] 
    1.32 -2019-04-18 14:07:52,836 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   type [FLOAT] 
    1.33 -2019-04-18 14:07:52,836 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   size [0] 
    1.34 -2019-04-18 14:07:52,836 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   temp [FLOAT] 
    1.35 -2019-04-18 14:07:52,836 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] Create JdbcColumn [XX_AH62XZC2ZIPSCAAAAFVDBRTRWM] 
    1.36 -2019-04-18 14:07:52,836 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] sqltype() 
    1.37 -2019-04-18 14:07:52,836 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   name [XX_AH62XZC2ZIPSCAAAAFVDBRTRWM] 
    1.38 -2019-04-18 14:07:52,836 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   type [FLOAT] 
    1.39 -2019-04-18 14:07:52,836 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   size [0] 
    1.40 -2019-04-18 14:07:52,836 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   temp [FLOAT] 
    1.41 -2019-04-18 14:07:52,836 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] Create JdbcColumn [XX_LUQOMBUR6TVXOAAAAFVDBRTRWY] 
    1.42 -2019-04-18 14:07:52,836 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] sqltype() 
    1.43 -2019-04-18 14:07:52,836 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   name [XX_LUQOMBUR6TVXOAAAAFVDBRTRWY] 
    1.44 -2019-04-18 14:07:52,836 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   type [FLOAT] 
    1.45 -2019-04-18 14:07:52,837 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   size [0] 
    1.46 -2019-04-18 14:07:52,837 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   temp [FLOAT] 
    1.47 -2019-04-18 14:07:52,837 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] Create JdbcColumn [XX_V5DXURNYVLXCSAAAAFVDBRTRXI] 
    1.48 -2019-04-18 14:07:52,837 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] sqltype() 
    1.49 -2019-04-18 14:07:52,837 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   name [XX_V5DXURNYVLXCSAAAAFVDBRTRXI] 
    1.50 -2019-04-18 14:07:52,837 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   type [FLOAT] 
    1.51 -2019-04-18 14:07:52,837 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   size [0] 
    1.52 -2019-04-18 14:07:52,837 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   temp [FLOAT] 
    1.53 -2019-04-18 14:07:52,837 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] Create JdbcColumn [XX_AOVOA3MGPYFRWAAAAFVDBRTRXY] 
    1.54 -2019-04-18 14:07:52,837 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] sqltype() 
    1.55 -2019-04-18 14:07:52,837 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   name [XX_AOVOA3MGPYFRWAAAAFVDBRTRXY] 
    1.56 -2019-04-18 14:07:52,837 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   type [FLOAT] 
    1.57 -2019-04-18 14:07:52,837 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   size [0] 
    1.58 -2019-04-18 14:07:52,838 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   temp [FLOAT] 
    1.59 -2019-04-18 14:07:52,838 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] Create JdbcColumn [XX_7SU5DGIYKJ2SMAAAAFVDBRTRYE] 
    1.60 -2019-04-18 14:07:52,838 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] sqltype() 
    1.61 -2019-04-18 14:07:52,838 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   name [XX_7SU5DGIYKJ2SMAAAAFVDBRTRYE] 
    1.62 -2019-04-18 14:07:52,838 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   type [VARBINARY] 
    1.63 -2019-04-18 14:07:52,838 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   size [0] 
    1.64 -2019-04-18 14:07:52,838 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator]   temp [VARBINARY(MAX)] 
    1.65  2019-04-18 14:07:52,838 DEBUG [FireThornTaskExecutor-4] [SQLServerOperator] SQL statement [CREATE TABLE [FirethornUserdataTAP20181212LIVE].[dbo].[XX_D5MKXLIWPVASCAAAAFVDBRTQ2Y] ([XX_E3QN4P5W6LAWMAAAAFVDBRTRT4] VARBINARY(MAX) , [XX_VE4IEATL5VQR6AAAAFVDBRTRUU] CHAR(10) , [XX_IHGXXSUN63QKSAAAAFVDBRTRVA] CHAR(16) , [XX_XI7KPZ2NN7HFYAAAAFVDBRTRVQ] CHAR(256) , [XX_NL5HKGNVBUPUQAAAAFVDBRTRV4] FLOAT , [XX_AH62XZC2ZIPSCAAAAFVDBRTRWM] FLOAT , [XX_LUQOMBUR6TVXOAAAAFVDBRTRWY] FLOAT , [XX_V5DXURNYVLXCSAAAAFVDBRTRXI] FLOAT , [XX_AOVOA3MGPYFRWAAAAFVDBRTRXY] FLOAT , [XX_7SU5DGIYKJ2SMAAAAFVDBRTRYE] VARBINARY(MAX) )] 
    1.66  2019-04-18 14:07:52,838 DEBUG [FireThornTaskExecutor-4] [JdbcConnectionEntity] operator() for [mssql] 
    1.67  2019-04-18 14:07:52,838 DEBUG [FireThornTaskExecutor-4] [JdbcConnectionEntity] open [jdbc:jtds:sqlserver://ramses2/FirethornUserdataTAP20181212LIVE][1] 
     2.1 --- /dev/null	Thu Jan 01 00:00:00 1970 +0000
     2.2 +++ b/doc/notes/stv/20190418-Firethorn-Logs-Propagating.txt	Thu Apr 18 18:34:50 2019 +0300
     2.3 @@ -0,0 +1,207 @@
     2.4 +2019-04-18 15:28:27,305 DEBUG [FireThornTaskExecutor-42] [SQLServerOperator] SQL statement [CREATE TABLE [FirethornUserdataTAP20181212LIVE].[dbo].[XX_NMBIUZPJP3TYMAAAAFVDCEBWHI] ([XX_PWTWRP5VENWHYAAAAFVDCEBWOQ] VARBINARY(MAX) , [XX_3TME7XONQFEZUAAAAFVDCEBWPA] CHAR(10) , [XX_VVTFHCSCLP46MAAAAFVDCEBWPM] CHAR(16) , [XX_2QHO2BTRWZH24AAAAFVDCEBWPY] CHAR(256) , [XX_JUUJGPOU7X5KQAAAAFVDCEBWQI] FLOAT , [XX_MCCCEP4R43GFUAAAAFVDCEBWQU] FLOAT , [XX_NS4ML2VURNPLYAAAAFVDCEBWRA] FLOAT , [XX_SY3VXZQJT4ARUAAAAFVDCEBWRM] FLOAT , [XX_3LOYBU6V24HT6AAAAFVDCEBWR4] FLOAT , [XX_MGH3ZRGROALM6AAAAFVDCEBWSI] VARBINARY(MAX) )] 
     2.5 +2019-04-18 15:28:27,305 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] operator() for [mssql] 
     2.6 +2019-04-18 15:28:27,305 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] open [jdbc:jtds:sqlserver://ramses2/FirethornUserdataTAP20181212LIVE][1] 
     2.7 +2019-04-18 15:28:27,305 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] operator() for [mssql] 
     2.8 +2019-04-18 15:28:27,305 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] get() [jdbc:jtds:sqlserver://ramses2/FirethornUserdataTAP20181212LIVE] 
     2.9 +2019-04-18 15:28:27,305 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] operator() for [mssql] 
    2.10 +2019-04-18 15:28:27,306 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] initialValue() [jdbc:jtds:sqlserver://ramses2/FirethornUserdataTAP20181212LIVE] 
    2.11 +2019-04-18 15:28:27,306 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] operator() for [mssql] 
    2.12 +2019-04-18 15:28:27,306 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] connect [jdbc:jtds:sqlserver://ramses2/FirethornUserdataTAP20181212LIVE] 
    2.13 +2019-04-18 15:28:27,306 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] State is CLOSED, updating to READY 
    2.14 +2019-04-18 15:28:27,306 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] State is READY, initialising Connection 
    2.15 +2019-04-18 15:28:27,306 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] With user/pass 
    2.16 +2019-04-18 15:28:27,306 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] connecting .... 
    2.17 +2019-04-18 15:28:27,311 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] .... connected 
    2.18 +2019-04-18 15:28:27,318 DEBUG [FireThornTaskExecutor-42] [SQLServerOperator] SQL result [0] 
    2.19 +2019-04-18 15:28:27,318 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] operator() for [mssql] 
    2.20 +2019-04-18 15:28:27,318 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] close [jdbc:jtds:sqlserver://ramses2/FirethornUserdataTAP20181212LIVE][1] 
    2.21 +2019-04-18 15:28:27,318 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] operator() for [mssql] 
    2.22 +2019-04-18 15:28:27,318 DEBUG [FireThornTaskExecutor-42] [JdbcConnectionEntity] get() [jdbc:jtds:sqlserver://ramses2/FirethornUserdataTAP20181212LIVE] 
    2.23 +2019-04-18 15:28:27,319 INFO  [FireThornTaskExecutor-42] [AdqlQueryTimings] TIMING - jdbcdone() 
    2.24 +2019-04-18 15:28:27,321 DEBUG [FireThornTaskExecutor-42] [AdqlQueryTimings] AdqlQueryTimings() 
    2.25 +2019-04-18 15:28:27,339 DEBUG [FireThornTaskExecutor-43] [BlueQueryEntity] before refresh() 
    2.26 +2019-04-18 15:28:27,343 DEBUG [FireThornTaskExecutor-43] [AdqlQueryTimings] AdqlQueryTimings() 
    2.27 +2019-04-18 15:28:27,343 DEBUG [FireThornTaskExecutor-43] [AdqlQueryTimings] AdqlQueryTimings() 
    2.28 +2019-04-18 15:28:27,344 DEBUG [FireThornTaskExecutor-43] [BlueQueryEntity] after refresh() 
    2.29 +2019-04-18 15:28:27,344 DEBUG [FireThornTaskExecutor-43] [BlueQueryEntity] Getting base BaseResource 
    2.30 +2019-04-18 15:28:27,344 DEBUG [FireThornTaskExecutor-43] [BlueQueryEntity] Found base BaseResource [OSA JDBC] 
    2.31 +2019-04-18 15:28:27,344 DEBUG [FireThornTaskExecutor-43] [BlueQueryEntity] Getting direct resource 
    2.32 +2019-04-18 15:28:27,345 DEBUG [FireThornTaskExecutor-43] [OgsaJdbcResourceEntity$EntityFactory] primary(JdbcResource) [2102] 
    2.33 +2019-04-18 15:28:27,345 DEBUG [FireThornTaskExecutor-43] [OgsaServiceEntity] primary() 
    2.34 +2019-04-18 15:28:27,346 DEBUG [FireThornTaskExecutor-43] [OgsaJdbcResourceEntity$EntityFactory] primary(OgsaService, JdbcResource) [22702][2102] 
    2.35 +2019-04-18 15:28:27,348 DEBUG [FireThornTaskExecutor-43] [OgsaJdbcResourceEntity$EntityFactory] Found primary OgsaJdbcResource [69602] 
    2.36 +2019-04-18 15:28:27,348 DEBUG [FireThornTaskExecutor-43] [OgsaJdbcResourceEntity$EntityFactory] Checking ogsaid ... 
    2.37 +2019-04-18 15:28:27,348 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] ogsaid [ACTIVE][ogsadai-b0a4219c-7ef8-40ea-9c3d-2f482313d0a6] 
    2.38 +2019-04-18 15:28:27,348 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] scan for [69602][XX_QHRA4VEJ6UAUKAAAAFVDB3OF2Y] 
    2.39 +2019-04-18 15:28:27,348 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] scantest() 
    2.40 +2019-04-18 15:28:27,348 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   name   [XX_QHRA4VEJ6UAUKAAAAFVDB3OF2Y] 
    2.41 +2019-04-18 15:28:27,348 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   ident  [69602] 
    2.42 +2019-04-18 15:28:27,348 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   ogsaid [ogsadai-b0a4219c-7ef8-40ea-9c3d-2f482313d0a6] 
    2.43 +2019-04-18 15:28:27,348 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] prevscan   [2019-04-18T15:24:17.555Z] 
    2.44 +2019-04-18 15:28:27,348 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] start time [2019-04-18T14:49:15.752Z] 
    2.45 +2019-04-18 15:28:27,348 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] scantest for [69602][XX_QHRA4VEJ6UAUKAAAAFVDB3OF2Y] 
    2.46 +2019-04-18 15:28:27,348 DEBUG [FireThornTaskExecutor-43] [OgsaJdbcResourceEntity] factory() 
    2.47 +2019-04-18 15:28:27,348 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity$EntityFactory] scanperiod() 
    2.48 +2019-04-18 15:28:27,348 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity$EntityFactory]   value [PT11M] 
    2.49 +2019-04-18 15:28:27,348 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] Factory scanperiod  [PT11M] 
    2.50 +2019-04-18 15:28:27,349 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] prevscan   [2019-04-18T15:24:17.555Z] 
    2.51 +2019-04-18 15:28:27,349 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] scanperiod [PT11M] 
    2.52 +2019-04-18 15:28:27,349 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] prev scan is recent - skipping 
    2.53 +2019-04-18 15:28:27,349 DEBUG [FireThornTaskExecutor-43] [OgsaJdbcResourceEntity$EntityFactory] Found ogsaid [ogsadai-b0a4219c-7ef8-40ea-9c3d-2f482313d0a6][ACTIVE] 
    2.54 +2019-04-18 15:28:27,349 DEBUG [FireThornTaskExecutor-43] [BlueQueryEntity] Using direct resource [XX_QHRA4VEJ6UAUKAAAAFVDB3OF2Y] 
    2.55 +2019-04-18 15:28:27,349 DEBUG [FireThornTaskExecutor-43] [BlueQueryEntity] Getting source OgsaService 
    2.56 +2019-04-18 15:28:27,349 DEBUG [FireThornTaskExecutor-43] [BlueQueryEntity] Found source OgsaService [http://jarmila:8080/ogsadai/services] 
    2.57 +2019-04-18 15:28:27,349 DEBUG [FireThornTaskExecutor-43] [BlueQueryEntity] Getting target table 
    2.58 +2019-04-18 15:28:27,351 DEBUG [FireThornTaskExecutor-43] [JdbcConnectionEntity] operator() for [mssql] 
    2.59 +2019-04-18 15:28:27,351 DEBUG [FireThornTaskExecutor-43] [BlueQueryEntity] Found target table [[FirethornUserdataTAP20181212LIVE].[dbo].[XX_NMBIUZPJP3TYMAAAAFVDCEBWHI]] 
    2.60 +2019-04-18 15:28:27,351 DEBUG [FireThornTaskExecutor-43] [BlueQueryEntity] Getting target OgsaBaseResource 
    2.61 +2019-04-18 15:28:27,351 DEBUG [FireThornTaskExecutor-43] [OgsaJdbcResourceEntity$EntityFactory] primary(JdbcResource) [52] 
    2.62 +2019-04-18 15:28:27,351 DEBUG [FireThornTaskExecutor-43] [OgsaServiceEntity] primary() 
    2.63 +2019-04-18 15:28:27,352 DEBUG [FireThornTaskExecutor-43] [OgsaJdbcResourceEntity$EntityFactory] primary(OgsaService, JdbcResource) [22702][52] 
    2.64 +2019-04-18 15:28:27,353 DEBUG [FireThornTaskExecutor-43] [OgsaJdbcResourceEntity$EntityFactory] Found primary OgsaJdbcResource [69603] 
    2.65 +2019-04-18 15:28:27,353 DEBUG [FireThornTaskExecutor-43] [OgsaJdbcResourceEntity$EntityFactory] Checking ogsaid ... 
    2.66 +2019-04-18 15:28:27,353 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] ogsaid [ACTIVE][ogsadai-0cec79d0-76da-4cba-b586-689d8d367817] 
    2.67 +2019-04-18 15:28:27,353 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] scan for [69603][XX_UDKY4JFC63UWMAAAAFVDB3OF54] 
    2.68 +2019-04-18 15:28:27,353 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] scantest() 
    2.69 +2019-04-18 15:28:27,353 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   name   [XX_UDKY4JFC63UWMAAAAFVDB3OF54] 
    2.70 +2019-04-18 15:28:27,353 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   ident  [69603] 
    2.71 +2019-04-18 15:28:27,353 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   ogsaid [ogsadai-0cec79d0-76da-4cba-b586-689d8d367817] 
    2.72 +2019-04-18 15:28:27,353 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] prevscan   [2019-04-18T15:24:17.587Z] 
    2.73 +2019-04-18 15:28:27,353 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] start time [2019-04-18T14:49:15.752Z] 
    2.74 +2019-04-18 15:28:27,353 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] scantest for [69603][XX_UDKY4JFC63UWMAAAAFVDB3OF54] 
    2.75 +2019-04-18 15:28:27,353 DEBUG [FireThornTaskExecutor-43] [OgsaJdbcResourceEntity] factory() 
    2.76 +2019-04-18 15:28:27,353 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity$EntityFactory] scanperiod() 
    2.77 +2019-04-18 15:28:27,353 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity$EntityFactory]   value [PT11M] 
    2.78 +2019-04-18 15:28:27,353 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] Factory scanperiod  [PT11M] 
    2.79 +2019-04-18 15:28:27,354 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] prevscan   [2019-04-18T15:24:17.587Z] 
    2.80 +2019-04-18 15:28:27,354 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] scanperiod [PT11M] 
    2.81 +2019-04-18 15:28:27,354 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] prev scan is recent - skipping 
    2.82 +2019-04-18 15:28:27,354 DEBUG [FireThornTaskExecutor-43] [OgsaJdbcResourceEntity$EntityFactory] Found ogsaid [ogsadai-0cec79d0-76da-4cba-b586-689d8d367817][ACTIVE] 
    2.83 +2019-04-18 15:28:27,354 DEBUG [FireThornTaskExecutor-43] [BlueQueryEntity] Found target OgsaBaseResource [XX_UDKY4JFC63UWMAAAAFVDB3OF54] 
    2.84 +2019-04-18 15:28:27,354 DEBUG [FireThornTaskExecutor-43] [BlueTaskEntity] transition(TaskState) 
    2.85 +2019-04-18 15:28:27,354 DEBUG [FireThornTaskExecutor-43] [BlueTaskEntity]   ident [69437] 
    2.86 +2019-04-18 15:28:27,354 DEBUG [FireThornTaskExecutor-43] [BlueTaskEntity]   state [READY][RUNNING] 
    2.87 +2019-04-18 15:28:27,354 DEBUG [FireThornTaskExecutor-43] [BlueTaskEntity] accept(TaskState) 
    2.88 +2019-04-18 15:28:27,354 DEBUG [FireThornTaskExecutor-43] [BlueTaskEntity]   ident [69437] 
    2.89 +2019-04-18 15:28:27,355 DEBUG [FireThornTaskExecutor-43] [BlueTaskEntity]   state [READY][RUNNING] 
    2.90 +2019-04-18 15:28:27,355 DEBUG [FireThornTaskExecutor-43] [BlueTaskEntity] handle() [] 
    2.91 +2019-04-18 15:28:27,355 DEBUG [FireThornTaskExecutor-43] [BlueTaskEntity$Handle] event() [69437]:[RUNNING]->[RUNNING]:[false]->[true] 
    2.92 +2019-04-18 15:28:27,356 DEBUG [FireThornTaskExecutor-43] [OgsaExecResourceEntity$EntityFactory] primary(OgsaService) [22702] 
    2.93 +2019-04-18 15:28:27,357 DEBUG [FireThornTaskExecutor-43] [OgsaExecResourceEntity$EntityFactory] No primary OgsaExecResource, creating a new one 
    2.94 +2019-04-18 15:28:27,357 DEBUG [FireThornTaskExecutor-43] [OgsaExecResourceEntity$EntityFactory] simple(OgsaService) [22702] 
    2.95 +2019-04-18 15:28:27,358 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] ogsaid [CREATED][DataRequestExecutionResource] 
    2.96 +2019-04-18 15:28:27,358 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] scan for [69639][XX_RHSYF3ZOCVXPIAAAAFVDCEBW3U] 
    2.97 +2019-04-18 15:28:27,358 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] scantest() 
    2.98 +2019-04-18 15:28:27,358 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   name   [XX_RHSYF3ZOCVXPIAAAAFVDCEBW3U] 
    2.99 +2019-04-18 15:28:27,358 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   ident  [69639] 
   2.100 +2019-04-18 15:28:27,358 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   ogsaid [DataRequestExecutionResource] 
   2.101 +2019-04-18 15:28:27,358 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] prevscan   [null] 
   2.102 +2019-04-18 15:28:27,358 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] start time [2019-04-18T14:49:15.752Z] 
   2.103 +2019-04-18 15:28:27,358 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] scantest for [69639][XX_RHSYF3ZOCVXPIAAAAFVDCEBW3U] 
   2.104 +2019-04-18 15:28:27,358 DEBUG [FireThornTaskExecutor-43] [OgsaExecResourceEntity] factory() 
   2.105 +2019-04-18 15:28:27,358 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity$EntityFactory] scanperiod() 
   2.106 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity$EntityFactory]   value [PT11M] 
   2.107 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] Factory scanperiod  [PT11M] 
   2.108 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] prevscan   [null] 
   2.109 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] scanperiod [PT11M] 
   2.110 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] prev scan is null - scanning 
   2.111 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] Checking for existing lock [69639][XX_RHSYF3ZOCVXPIAAAAFVDCEBW3U] 
   2.112 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] No existing lock found [69639][XX_RHSYF3ZOCVXPIAAAAFVDCEBW3U] 
   2.113 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] Adding new lock [69639][XX_RHSYF3ZOCVXPIAAAAFVDCEBW3U][2019-04-18T15:28:27.359Z] 
   2.114 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] Running scan [69639][XX_RHSYF3ZOCVXPIAAAAFVDCEBW3U] 
   2.115 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] scanimpl() 
   2.116 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   name   [XX_RHSYF3ZOCVXPIAAAAFVDCEBW3U] 
   2.117 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   ident  [69639] 
   2.118 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   ogsaid [DataRequestExecutionResource] 
   2.119 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] ping() 
   2.120 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   name   [XX_RHSYF3ZOCVXPIAAAAFVDCEBW3U] 
   2.121 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   ident  [69639] 
   2.122 +2019-04-18 15:28:27,359 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   ogsaid [DataRequestExecutionResource] 
   2.123 +2019-04-18 15:28:27,360 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] Service request [69639][http://jarmila:8080/ogsadai/services/dataResources/DataRequestExecutionResource] 
   2.124 +2019-04-18 15:28:27,365 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] Service response [69639][null] 
   2.125 +2019-04-18 15:28:27,365 ERROR [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] Ping test failed [69639][DataRequestExecutionResource][404] 
   2.126 +2019-04-18 15:28:27,365 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] status(status) 
   2.127 +2019-04-18 15:28:27,365 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   name   [XX_RHSYF3ZOCVXPIAAAAFVDCEBW3U] 
   2.128 +2019-04-18 15:28:27,365 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   ident  [69639] 
   2.129 +2019-04-18 15:28:27,365 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   ogsaid [DataRequestExecutionResource] 
   2.130 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   status [ERROR] 
   2.131 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] Releasing locks [69639][XX_RHSYF3ZOCVXPIAAAAFVDCEBW3U] 
   2.132 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] Found existing lock [69639][XX_RHSYF3ZOCVXPIAAAAFVDCEBW3U][2019-04-18T15:28:27.359Z] 
   2.133 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] Removing .... 
   2.134 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] Notifying .... 
   2.135 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] ogsaid [ACTIVE][ogsadai-b0a4219c-7ef8-40ea-9c3d-2f482313d0a6] 
   2.136 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] scan for [69602][XX_QHRA4VEJ6UAUKAAAAFVDB3OF2Y] 
   2.137 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] scantest() 
   2.138 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   name   [XX_QHRA4VEJ6UAUKAAAAFVDB3OF2Y] 
   2.139 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   ident  [69602] 
   2.140 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   ogsaid [ogsadai-b0a4219c-7ef8-40ea-9c3d-2f482313d0a6] 
   2.141 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] prevscan   [2019-04-18T15:24:17.555Z] 
   2.142 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] start time [2019-04-18T14:49:15.752Z] 
   2.143 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] scantest for [69602][XX_QHRA4VEJ6UAUKAAAAFVDB3OF2Y] 
   2.144 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [OgsaJdbcResourceEntity] factory() 
   2.145 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity$EntityFactory] scanperiod() 
   2.146 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity$EntityFactory]   value [PT11M] 
   2.147 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] Factory scanperiod  [PT11M] 
   2.148 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] prevscan   [2019-04-18T15:24:17.555Z] 
   2.149 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] scanperiod [PT11M] 
   2.150 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] prev scan is recent - skipping 
   2.151 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] ogsaid [ACTIVE][ogsadai-0cec79d0-76da-4cba-b586-689d8d367817] 
   2.152 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] scan for [69603][XX_UDKY4JFC63UWMAAAAFVDB3OF54] 
   2.153 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] scantest() 
   2.154 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   name   [XX_UDKY4JFC63UWMAAAAFVDB3OF54] 
   2.155 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   ident  [69603] 
   2.156 +2019-04-18 15:28:27,366 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity]   ogsaid [ogsadai-0cec79d0-76da-4cba-b586-689d8d367817] 
   2.157 +2019-04-18 15:28:27,367 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] prevscan   [2019-04-18T15:24:17.587Z] 
   2.158 +2019-04-18 15:28:27,367 DEBUG [FireThornTaskExecutor-43] [OgsaBaseResourceEntity] start time [2019-04-18T14:49:15.752Z] 
   2.159 +2019-04-18 15:28:27,367 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] scantest for [69603][XX_UDKY4JFC63UWMAAAAFVDB3OF54] 
   2.160 +2019-04-18 15:28:27,367 DEBUG [FireThornTaskExecutor-43] [OgsaJdbcResourceEntity] factory() 
   2.161 +2019-04-18 15:28:27,367 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity$EntityFactory] scanperiod() 
   2.162 +2019-04-18 15:28:27,367 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity$EntityFactory]   value [PT11M] 
   2.163 +2019-04-18 15:28:27,367 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] Factory scanperiod  [PT11M] 
   2.164 +2019-04-18 15:28:27,367 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] prevscan   [2019-04-18T15:24:17.587Z] 
   2.165 +2019-04-18 15:28:27,367 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] scanperiod [PT11M] 
   2.166 +2019-04-18 15:28:27,367 DEBUG [FireThornTaskExecutor-43] [BaseComponentEntity] prev scan is recent - skipping 
   2.167 +2019-04-18 15:28:27,449 DEBUG [FireThornTaskExecutor-43] [OgsaContextClient] monkey(MonkeyParam) 
   2.168 +2019-04-18 15:28:27,515 DEBUG [FireThornTaskExecutor-43] [SimpleWorkflowResult] RequestExecutionStatus [uk.org.ogsadai.resource.request.status.COMPLETED_WITH_ERROR] 
   2.169 +2019-04-18 15:28:27,518 DEBUG [FireThornTaskExecutor-43] [AdqlQueryTimings] AdqlQueryTimings() 
   2.170 +2019-04-18 15:28:27,518 DEBUG [FireThornTaskExecutor-43] [AdqlQueryTimings] AdqlQueryTimings() 
   2.171 +2019-04-18 15:28:27,519 DEBUG [FireThornTaskExecutor-43] [BlueTaskEntity] transition(TaskState) 
   2.172 +2019-04-18 15:28:27,519 DEBUG [FireThornTaskExecutor-43] [BlueTaskEntity]   ident [69437] 
   2.173 +2019-04-18 15:28:27,520 DEBUG [FireThornTaskExecutor-43] [BlueTaskEntity]   state [READY][FAILED] 
   2.174 +2019-04-18 15:28:27,520 DEBUG [FireThornTaskExecutor-43] [BlueTaskEntity] accept(TaskState) 
   2.175 +2019-04-18 15:28:27,520 DEBUG [FireThornTaskExecutor-43] [BlueTaskEntity]   ident [69437] 
   2.176 +2019-04-18 15:28:27,520 DEBUG [FireThornTaskExecutor-43] [BlueTaskEntity]   state [READY][FAILED] 
   2.177 +2019-04-18 15:28:27,520 DEBUG [FireThornTaskExecutor-43] [BlueTaskEntity] After execute() 
   2.178 +2019-04-18 15:28:27,520 DEBUG [FireThornTaskExecutor-43] [BlueTaskEntity]   state [FAILED] 
   2.179 +2019-04-18 15:28:27,523 DEBUG [FireThornTaskExecutor-43] [AdqlQueryTimings] AdqlQueryTimings() 
   2.180 +2019-04-18 15:28:27,527 DEBUG [FireThornTaskExecutor-38] [BlueTaskEntity] Finished thread() 
   2.181 +2019-04-18 15:28:27,527 DEBUG [FireThornTaskExecutor-38] [BlueTaskEntity]   state [READY] 
   2.182 +2019-04-18 15:28:27,527 DEBUG [FireThornTaskExecutor-38] [BlueTaskEntity] Refreshing state 
   2.183 +2019-04-18 15:28:27,531 DEBUG [FireThornTaskExecutor-38] [AdqlQueryTimings] AdqlQueryTimings() 
   2.184 +2019-04-18 15:28:27,531 DEBUG [FireThornTaskExecutor-38] [AdqlQueryTimings] AdqlQueryTimings() 
   2.185 +2019-04-18 15:28:27,532 DEBUG [FireThornTaskExecutor-38] [BlueTaskEntity] Finished running() 
   2.186 +2019-04-18 15:28:27,532 DEBUG [FireThornTaskExecutor-38] [BlueTaskEntity]   state [FAILED] 
   2.187 +2019-04-18 15:28:27,533 DEBUG [main-interface-9] [BlueQueryEntity] Converting current instance [69437] 
   2.188 +2019-04-18 15:28:27,536 DEBUG [main-interface-9] [AdqlQueryTimings] AdqlQueryTimings() 
   2.189 +2019-04-18 15:28:27,536 DEBUG [main-interface-9] [AdqlQueryTimings] AdqlQueryTimings() 
   2.190 +2019-04-18 15:28:27,537 DEBUG [main-interface-9] [BlueTaskEntity] waitfor() [69437]:[FAILED]->[FAILED]->[COMPLETED]:[3000000] 
   2.191 +2019-04-18 15:28:27,537 DEBUG [main-interface-9] [BlueTaskEntity] handle() [] 
   2.192 +2019-04-18 15:28:27,537 DEBUG [main-interface-9] [BlueQueryEntity] update(Handle) [69437][FAILED][RUNNING] 
   2.193 +2019-04-18 15:28:27,537 DEBUG [main-interface-9] [BlueQueryEntity] transition() [69437][EMPTY][EMPTY][0] 
   2.194 +2019-04-18 15:28:27,537 DEBUG [main-interface-9] [BlueQueryEntity] No-op state change [EMPTY][EMPTY] 
   2.195 +2019-04-18 15:28:27,554 DEBUG [main-interface-9] [AbstractEntityController] Operation [70232] 
   2.196 +2019-04-18 15:28:27,554 DEBUG [main-interface-9] [AbstractEntityController] Authentication [Authentication[Ident[70282]Identity[784632fc-23f1-4588-9732-6b6938e6697c]Method[urn:simple.http.header]]] 
   2.197 +2019-04-18 15:28:27,554 DEBUG [main-interface-9] [AbstractEntityController] Identity  [69208][784632fc-23f1-4588-9732-6b6938e6697c] 
   2.198 +2019-04-18 15:28:27,554 DEBUG [main-interface-9] [AbstractEntityController] Community [102][friends] 
   2.199 +2019-04-18 15:28:27,564 DEBUG [main-interface-9] [HttpRequestDebug] Response headers 
   2.200 +2019-04-18 15:28:27,564 DEBUG [main-interface-9] [HttpRequestDebug]   [X-Clacks-Overhead][GNU Terry Pratchett] 
   2.201 +2019-04-18 15:28:27,564 DEBUG [main-interface-9] [HttpRequestDebug]   [Location][http://localhost:...] 
   2.202 +2019-04-18 15:28:27,564 DEBUG [main-interface-9] [HttpRequestDebug]   [firethorn.auth.username][784632fc-23f1-458...] 
   2.203 +2019-04-18 15:28:27,564 DEBUG [main-interface-9] [HttpRequestDebug]   [firethorn.auth.community][friends] 
   2.204 +2019-04-18 15:28:27,564 DEBUG [main-interface-9] [HttpRequestDebug]   [Content-Type][application/json;...] 
   2.205 +2019-04-18 15:28:27,564 DEBUG [main-interface-9] [HttpRequestDebug]   [Transfer-Encoding][chunked] 
   2.206 +2019-04-18 15:28:27,564 DEBUG [main-interface-9] [HttpRequestDebug]   [Date][Thu, 18 Apr 2019 ...] 
   2.207 +2019-04-18 15:28:27,564 DEBUG [main-interface-9] [HttpRequestDebug]   [Connection][close] 
   2.208 +2019-04-18 15:28:27,564 DEBUG [main-interface-9] [HttpRequestDebug] ---- 
   2.209 +^C
   2.210 +