You are viewing a plain text version of this content. The canonical link for it is here.
Posted to commits@carbondata.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/09/15 08:11:52 UTC

Build failed in Jenkins: carbondata-master-spark-2.1 » Apache CarbonData :: presto #1382

See <https://builds.apache.org/job/carbondata-master-spark-2.1/org.apache.carbondata$carbondata-presto/1382/display/redirect>

------------------------------------------
[...truncated 211.19 KB...]
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:83)
Sep 15, 2017 8:11:25 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081124_00005_n4nzz :: Transaction:[be636804-a39d-4862-9d74-d75a274303d7] :: elapsed 524ms :: planning 120ms :: scheduling 404ms :: running 0ms :: finishing 404ms :: begin 2017-09-15T08:11:24.454Z :: end 2017-09-15T08:11:24.978Z
2017-09-15 08:11:25 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT COUNT(DISTINCT BONUS) AS RESULT FROM TESTDB.TESTTABLE 
- test the result for max() with distinct operator in presto *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:89)
Sep 15, 2017 8:11:25 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081125_00006_n4nzz :: Transaction:[cc8fb95c-4d3d-4094-a7f7-49d3426d1eeb] :: elapsed 431ms :: planning 86ms :: scheduling 345ms :: running 0ms :: finishing 345ms :: begin 2017-09-15T08:11:25.005Z :: end 2017-09-15T08:11:25.436Z
2017-09-15 08:11:25 INFO  AbstractDataLoadProcessorStep:150 - Thread-2 Rows processed in step Data Writer : 10
2017-09-15 08:11:25 INFO  AbstractDataLoadProcessorStep:150 - Thread-4 Rows processed in step Input Processor : 10
2017-09-15 08:11:25 INFO  AbstractDataLoadProcessorStep:150 - Thread-3 Rows processed in step Data Converter : 10
- test the result for count()clause with distinct operator on decimal column in presto
2017-09-15 08:11:25 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT COUNT(BONUS) AS RESULT FROM TESTDB.TESTTABLE 
Sep 15, 2017 8:11:25 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081125_00007_n4nzz :: Transaction:[d967052d-747d-4daf-897b-f0d2248f8fcf] :: elapsed 396ms :: planning 115ms :: scheduling 281ms :: running 0ms :: finishing 281ms :: begin 2017-09-15T08:11:25.461Z :: end 2017-09-15T08:11:25.857Z
Sep 15, 2017 8:11:26 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081125_00008_n4nzz :: Transaction:[8eec3028-215c-417c-a2df-d83a259a9d14] :: elapsed 117ms :: planning 59ms :: scheduling 58ms :: running 0ms :: finishing 58ms :: begin 2017-09-15T08:11:25.891Z :: end 2017-09-15T08:11:26.008Z
- test the result for count()clause with out  distinct operator on decimal column in presto
2017-09-15 08:11:26 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT SUM(DISTINCT BONUS) AS RESULT FROM TESTDB.TESTTABLE 
- test the result for sum()with out distinct operator for decimal column in presto
2017-09-15 08:11:26 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT SUM(DISTINCT BONUS) AS RESULT FROM TESTDB.TESTTABLE 
Sep 15, 2017 8:11:26 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081126_00009_n4nzz :: Transaction:[ca353679-76ab-4b18-9f4f-4b8b8e2e1676] :: elapsed 397ms :: planning 93ms :: scheduling 304ms :: running 0ms :: finishing 304ms :: begin 2017-09-15T08:11:26.034Z :: end 2017-09-15T08:11:26.431Z
2017-09-15 08:11:26 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT AVG(DISTINCT BONUS) AS RESULT FROM TESTDB.TESTTABLE 
- test the result for sum() with distinct operator for decimal column in presto *** FAILED ***
  java.lang.NullPointerException:
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$11.apply$mcV$sp(PrestoAllDataTypeTest.scala:115)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$11.apply(PrestoAllDataTypeTest.scala:110)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$11.apply(PrestoAllDataTypeTest.scala:110)
  at org.scalatest.Transformer$$anonfun$apply$1.apply$mcV$sp(Transformer.scala:22)
  at org.scalatest.OutcomeOf$class.outcomeOf(OutcomeOf.scala:85)
  at org.scalatest.OutcomeOf$.outcomeOf(OutcomeOf.scala:104)
  at org.scalatest.Transformer.apply(Transformer.scala:22)
  at org.scalatest.Transformer.apply(Transformer.scala:20)
  at org.scalatest.FunSuiteLike$$anon$1.apply(FunSuiteLike.scala:166)
  at org.scalatest.Suite$class.withFixture(Suite.scala:1122)
  ...
Sep 15, 2017 8:11:26 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081126_00010_n4nzz :: Transaction:[72c29fe4-363c-419a-8924-a9afbd882286] :: elapsed 253ms :: planning 60ms :: scheduling 193ms :: running 0ms :: finishing 193ms :: begin 2017-09-15T08:11:26.461Z :: end 2017-09-15T08:11:26.714Z
- test the result for avg() with distinct operator on decimal coin presto
2017-09-15 08:11:27 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT MIN(BONUS) AS RESULT FROM TESTDB.TESTTABLE 
Sep 15, 2017 8:11:27 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081126_00011_n4nzz :: Transaction:[ff9d0fb3-8306-4164-82bc-99d407af6b8c] :: elapsed 270ms :: planning 99ms :: scheduling 171ms :: running 0ms :: finishing 171ms :: begin 2017-09-15T08:11:26.750Z :: end 2017-09-15T08:11:27.020Z
- test the result for min() with distinct operator in decimalType of presto
Sep 15, 2017 8:11:27 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081127_00012_n4nzz :: Transaction:[b930d00f-221a-4219-9a76-b18c86569086] :: elapsed 194ms :: planning 98ms :: scheduling 96ms :: running 0ms :: finishing 96ms :: begin 2017-09-15T08:11:27.056Z :: end 2017-09-15T08:11:27.250Z
2017-09-15 08:11:27 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT MAX(BONUS) AS RESULT FROM TESTDB.TESTTABLE 
- test the result for max() with distinct operator in decimalType of presto
2017-09-15 08:11:27 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT DISTINCT BONUS FROM TESTDB.TESTTABLE ORDER BY BONUS limit 3 
Sep 15, 2017 8:11:27 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081127_00013_n4nzz :: Transaction:[92af6fb8-7941-46af-8de8-4f9b7f13529b] :: elapsed 146ms :: planning 89ms :: scheduling 57ms :: running 0ms :: finishing 57ms :: begin 2017-09-15T08:11:27.274Z :: end 2017-09-15T08:11:27.420Z
2017-09-15 08:11:27 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME FROM TESTDB.TESTTABLE ORDER BY NAME
- select decimal data type with ORDER BY  clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:147)
Sep 15, 2017 8:11:27 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081127_00014_n4nzz :: Transaction:[8dd37b28-85ba-4cf8-a52b-ad69a98e4627] :: elapsed 347ms :: planning 69ms :: scheduling 278ms :: running 0ms :: finishing 278ms :: begin 2017-09-15T08:11:27.479Z :: end 2017-09-15T08:11:27.826Z
2017-09-15 08:11:28 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT DATE FROM TESTDB.TESTTABLE ORDER BY DATE
- select string type with order by clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:162)
Sep 15, 2017 8:11:28 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081127_00015_n4nzz :: Transaction:[56087b2f-f272-4580-b7c2-984ff9a7cee1] :: elapsed 167ms :: planning 63ms :: scheduling 104ms :: running 0ms :: finishing 104ms :: begin 2017-09-15T08:11:27.849Z :: end 2017-09-15T08:11:28.016Z
2017-09-15 08:11:28 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT DISTINCT ID FROM TESTDB.TESTTABLE ORDER BY ID
Sep 15, 2017 8:11:28 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081128_00016_n4nzz :: Transaction:[e06f95da-2f70-45dd-9f05-779d23069a3b] :: elapsed 118ms :: planning 43ms :: scheduling 75ms :: running 0ms :: finishing 75ms :: begin 2017-09-15T08:11:28.048Z :: end 2017-09-15T08:11:28.166Z
- select DATE type with order by clause *** FAILED ***
  java.util.NoSuchElementException: head of empty list
  at scala.collection.immutable.Nil$.head(List.scala:420)
  at scala.collection.immutable.Nil$.head(List.scala:417)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$17.apply$mcV$sp(PrestoAllDataTypeTest.scala:182)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$17.apply(PrestoAllDataTypeTest.scala:164)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$17.apply(PrestoAllDataTypeTest.scala:164)
  at org.scalatest.Transformer$$anonfun$apply$1.apply$mcV$sp(Transformer.scala:22)
  at org.scalatest.OutcomeOf$class.outcomeOf(OutcomeOf.scala:85)
  at org.scalatest.OutcomeOf$.outcomeOf(OutcomeOf.scala:104)
  at org.scalatest.Transformer.apply(Transformer.scala:22)
  at org.scalatest.Transformer.apply(Transformer.scala:20)
  ...
- select int type with order by clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:197)
2017-09-15 08:11:28 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS FROM TESTDB.TESTTABLE WHERE BONUS>1234 AND ID>2 GROUP BY ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS ORDER BY ID
Sep 15, 2017 8:11:28 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081128_00017_n4nzz :: Transaction:[0e6d9ffe-830c-40e1-af09-edede4cb6c0c] :: elapsed 173ms :: planning 54ms :: scheduling 119ms :: running 0ms :: finishing 119ms :: begin 2017-09-15T08:11:28.186Z :: end 2017-09-15T08:11:28.359Z
- test and filter clause with greater than expression *** FAILED ***
  actualResult.toString().equals(expectedResult.toString()) was false (PrestoAllDataTypeTest.scala:223)
2017-09-15 08:11:28 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS FROM TESTDB.TESTTABLE WHERE BONUS>=1234.444 GROUP BY ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS ORDER BY ID
Sep 15, 2017 8:11:28 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081128_00018_n4nzz :: Transaction:[04afb802-7cda-4bce-b4b4-e6080cbcee30] :: elapsed 430ms :: planning 278ms :: scheduling 152ms :: running 0ms :: finishing 152ms :: begin 2017-09-15T08:11:28.452Z :: end 2017-09-15T08:11:28.882Z
- test and filter clause with greater than equal to expression *** FAILED ***
  actualResult.toString().equals(expectedResult.toString()) was false (PrestoAllDataTypeTest.scala:267)
2017-09-15 08:11:29 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS FROM TESTDB.TESTTABLE WHERE BONUS<=1234.444 GROUP BY ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS ORDER BY ID LIMIT 2
Sep 15, 2017 8:11:29 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081128_00019_n4nzz :: Transaction:[c0d871f9-a6a2-44b4-9e94-6d6ae05009ac] :: elapsed 216ms :: planning 112ms :: scheduling 104ms :: running 0ms :: finishing 104ms :: begin 2017-09-15T08:11:28.931Z :: end 2017-09-15T08:11:29.147Z
2017-09-15 08:11:29 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID FROM TESTDB.TESTTABLE WHERE BONUS=1234.444
- test and filter clause with less than equal to expression *** FAILED ***
  actualResult.toString().equals(expectedResult.toString()) was false (PrestoAllDataTypeTest.scala:292)
Sep 15, 2017 8:11:29 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081129_00020_n4nzz :: Transaction:[88475bec-f4f7-4911-a777-a317d711bcfc] :: elapsed 193ms :: planning 90ms :: scheduling 103ms :: running 0ms :: finishing 103ms :: begin 2017-09-15T08:11:29.180Z :: end 2017-09-15T08:11:29.373Z
Sep 15, 2017 8:11:29 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081129_00021_n4nzz :: Transaction:[2a808bbf-4da9-4692-a83c-49bc5cb8fe02] :: elapsed 97ms :: planning 56ms :: scheduling 41ms :: running 0ms :: finishing 41ms :: begin 2017-09-15T08:11:29.412Z :: end 2017-09-15T08:11:29.509Z
2017-09-15 08:11:29 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS FROM TESTDB.TESTTABLE WHERE BONUS>1234 AND ID<2 GROUP BY ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS ORDER BY ID
- test equal to expression on decimal value *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:301)
- test less than expression with and operator *** FAILED ***
  actualResult.toString().equals(expectedResult.toString()) was false (PrestoAllDataTypeTest.scala:317)
2017-09-15 08:11:29 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME from testdb.testtable WHERE PHONETYPE IN('phone1848','phone706')
Sep 15, 2017 8:11:29 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081129_00022_n4nzz :: Transaction:[be0ebb2d-2d4c-40ab-9376-fcff14ffff95] :: elapsed 225ms :: planning 123ms :: scheduling 102ms :: running 0ms :: finishing 102ms :: begin 2017-09-15T08:11:29.544Z :: end 2017-09-15T08:11:29.769Z
2017-09-15 08:11:30 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME from testdb.testtable WHERE PHONETYPE NOT IN('phone1848','phone706')
Sep 15, 2017 8:11:30 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081129_00023_n4nzz :: Transaction:[dd890fd5-7542-4825-9fa9-830da063d1ba] :: elapsed 705ms :: planning 673ms :: scheduling 32ms :: running 0ms :: finishing 32ms :: begin 2017-09-15T08:11:29.828Z :: end 2017-09-15T08:11:30.533Z
- test the result for in clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:327)
2017-09-15 08:11:30 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID FROM TESTDB.TESTTABLE WHERE DATE IS NULL
- test the result for not in clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:341)
Sep 15, 2017 8:11:30 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081130_00024_n4nzz :: Transaction:[65cadd58-d148-49ae-a3a0-d9c43e24482c] :: elapsed 108ms :: planning 79ms :: scheduling 29ms :: running 0ms :: finishing 29ms :: begin 2017-09-15T08:11:30.558Z :: end 2017-09-15T08:11:30.666Z
Sep 15, 2017 8:11:30 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081130_00025_n4nzz :: Transaction:[6d75dbf4-e040-413d-afe0-967872077e3f] :: elapsed 82ms :: planning 51ms :: scheduling 31ms :: running 0ms :: finishing 31ms :: begin 2017-09-15T08:11:30.689Z :: end 2017-09-15T08:11:30.771Z
2017-09-15 08:11:30 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME FROM TESTDB.TESTTABLE WHERE DATE IS NOT NULL AND ID=9
- test for null operator on date data type *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:347)
2017-09-15 08:11:30 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME FROM TESTDB.TESTTABLE WHERE DOB IS NOT NULL AND ID=9
Sep 15, 2017 8:11:30 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081130_00026_n4nzz :: Transaction:[7535455a-928d-487a-ae2c-32ed30bdee46] :: elapsed 99ms :: planning 73ms :: scheduling 26ms :: running 0ms :: finishing 26ms :: begin 2017-09-15T08:11:30.799Z :: end 2017-09-15T08:11:30.898Z
- test for not null operator on date data type *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:354)
Sep 15, 2017 8:11:31 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081130_00027_n4nzz :: Transaction:[73c158d3-0aa4-469c-b97f-0659deb13059] :: elapsed 134ms :: planning 95ms :: scheduling 39ms :: running 0ms :: finishing 39ms :: begin 2017-09-15T08:11:30.921Z :: end 2017-09-15T08:11:31.055Z
2017-09-15 08:11:31 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME FROM TESTDB.TESTTABLE WHERE DOB IS NULL AND ID=1
- test for not null operator on timestamp type *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:362)
- test for null operator on timestamp type *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:369)
Sep 15, 2017 8:11:31 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081131_00028_n4nzz :: Transaction:[8f9baf6e-f7b5-48b0-b946-ed7e5f05d505] :: elapsed 91ms :: planning 61ms :: scheduling 30ms :: running 0ms :: finishing 30ms :: begin 2017-09-15T08:11:31.080Z :: end 2017-09-15T08:11:31.171Z
2017-09-15 08:11:31 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT DISTINCT SHORTFIELD from testdb.testtable ORDER BY SHORTFIELD 
2017-09-15 08:11:31 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID from testdb.testtable WHERE SHORTFIELD IS NULL ORDER BY SHORTFIELD 
- test the result for short datatype with order by clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:385)
Sep 15, 2017 8:11:31 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081131_00029_n4nzz :: Transaction:[0a251fba-d124-4de3-b3a5-cf913d3366d6] :: elapsed 156ms :: planning 49ms :: scheduling 107ms :: running 0ms :: finishing 107ms :: begin 2017-09-15T08:11:31.200Z :: end 2017-09-15T08:11:31.356Z
2017-09-15 08:11:31 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID from testdb.testtable WHERE SHORTFIELD>11 
Sep 15, 2017 8:11:31 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081131_00030_n4nzz :: Transaction:[b4b5ae41-3462-4194-9ef3-9dc25ef83656] :: elapsed 141ms :: planning 50ms :: scheduling 91ms :: running 0ms :: finishing 91ms :: begin 2017-09-15T08:11:31.372Z :: end 2017-09-15T08:11:31.513Z
- test the result for short datatype in clause where field is null *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:393)
Sep 15, 2017 8:11:31 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_081131_00031_n4nzz :: Transaction:[4937dabb-4df1-4f55-a55d-d13b81390787] :: elapsed 78ms :: planning 54ms :: scheduling 24ms :: running 0ms :: finishing 24ms :: begin 2017-09-15T08:11:31.549Z :: end 2017-09-15T08:11:31.627Z
- test the result for short datatype with greater than operator *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:401)
Sep 15, 2017 8:11:31 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@4ef277ef{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:8086}
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@2c51c756{/,null,UNAVAILABLE,@http}
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=14<=200,i=12,q=12} Couldn't stop Thread[http-worker-377,5,main]
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=14<=200,i=2,q=9} Couldn't stop Thread[http-worker-195,5,]
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=14<=200,i=0,q=9} Couldn't stop Thread[http-worker-387,5,]
Sep 15, 2017 8:11:31 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
Sep 15, 2017 8:11:31 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@31c0c7e5{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:44639}
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@67f266bd{/,null,UNAVAILABLE,@http}
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=11<=200,i=10,q=10} Couldn't stop Thread[http-worker-139,5,]
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=11<=200,i=3,q=8} Couldn't stop Thread[http-worker-143,5,]
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=11<=200,i=0,q=7} Couldn't stop Thread[http-worker-297,5,]
Sep 15, 2017 8:11:31 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
Sep 15, 2017 8:11:31 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@4adcc981{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:45769}
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@729d6ee2{/,null,UNAVAILABLE,@http}
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=9<=200,i=7,q=7} Couldn't stop Thread[http-worker-296,5,]
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=9<=200,i=0,q=6} Couldn't stop Thread[http-worker-105,5,]
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=9<=200,i=0,q=6} Couldn't stop Thread[http-worker-108,5,]
Sep 15, 2017 8:11:31 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
Sep 15, 2017 8:11:31 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@38929da{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:39549}
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@1e6d30c0{/,null,UNAVAILABLE,@http}
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=10<=200,i=2,q=2} Couldn't stop Thread[http-worker-74,5,main]
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=10<=200,i=0,q=1} Couldn't stop Thread[http-worker-392,5,]
Sep 15, 2017 8:11:31 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
Sep 15, 2017 8:11:31 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@7da635c0{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:37211}
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@3d615b2e{/,null,UNAVAILABLE,@http}
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=8<=200,i=3,q=2} Couldn't stop Thread[http-worker-393,5,main]
Sep 15, 2017 8:11:31 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=8<=200,i=0,q=1} Couldn't stop Thread[http-worker-80,5,]
Sep 15, 2017 8:11:31 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
2017-09-15 08:11:31 INFO  PrestoServer$:88 - ***** Stopping The Server *****
Run completed in 38 seconds, 747 milliseconds.
Total number of tests run: 32
Suites: completed 2, aborted 0
Tests: succeeded 6, failed 26, canceled 0, ignored 0, pending 0
*** 26 TESTS FAILED ***
[JENKINS] Recording test results

Jenkins build is back to normal : carbondata-master-spark-2.1 » Apache CarbonData :: presto #1386

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/carbondata-master-spark-2.1/org.apache.carbondata$carbondata-presto/1386/display/redirect?page=changes>


Build failed in Jenkins: carbondata-master-spark-2.1 » Apache CarbonData :: presto #1385

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/carbondata-master-spark-2.1/org.apache.carbondata$carbondata-presto/1385/display/redirect>

------------------------------------------
[...truncated 174.40 KB...]
2017-09-15 09:50:01 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT SUM(DISTINCT BONUS) AS RESULT FROM TESTDB.TESTTABLE 
Sep 15, 2017 9:50:01 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095001_00009_hq8e7 :: Transaction:[58a0b043-7da2-4149-aa6a-01a58484d446] :: elapsed 263ms :: planning 52ms :: scheduling 211ms :: running 0ms :: finishing 211ms :: begin 2017-09-15T09:50:01.367Z :: end 2017-09-15T09:50:01.630Z
2017-09-15 09:50:01 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT AVG(DISTINCT BONUS) AS RESULT FROM TESTDB.TESTTABLE 
Sep 15, 2017 9:50:01 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095001_00010_hq8e7 :: Transaction:[b8314270-610f-4f05-8bd2-6ab417f2383d] :: elapsed 171ms :: planning 33ms :: scheduling 138ms :: running 0ms :: finishing 138ms :: begin 2017-09-15T09:50:01.655Z :: end 2017-09-15T09:50:01.826Z
- test the result for sum() with distinct operator for decimal column in presto *** FAILED ***
  java.lang.NullPointerException:
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$11.apply$mcV$sp(PrestoAllDataTypeTest.scala:115)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$11.apply(PrestoAllDataTypeTest.scala:110)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$11.apply(PrestoAllDataTypeTest.scala:110)
  at org.scalatest.Transformer$$anonfun$apply$1.apply$mcV$sp(Transformer.scala:22)
  at org.scalatest.OutcomeOf$class.outcomeOf(OutcomeOf.scala:85)
  at org.scalatest.OutcomeOf$.outcomeOf(OutcomeOf.scala:104)
  at org.scalatest.Transformer.apply(Transformer.scala:22)
  at org.scalatest.Transformer.apply(Transformer.scala:20)
  at org.scalatest.FunSuiteLike$$anon$1.apply(FunSuiteLike.scala:166)
  at org.scalatest.Suite$class.withFixture(Suite.scala:1122)
  ...
Sep 15, 2017 9:50:02 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095001_00011_hq8e7 :: Transaction:[13652ef9-c0d9-4dd0-92cc-0c7ff76a72e9] :: elapsed 171ms :: planning 45ms :: scheduling 126ms :: running 0ms :: finishing 126ms :: begin 2017-09-15T09:50:01.844Z :: end 2017-09-15T09:50:02.015Z
2017-09-15 09:50:02 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT MIN(BONUS) AS RESULT FROM TESTDB.TESTTABLE 
- test the result for avg() with distinct operator on decimal coin presto
Sep 15, 2017 9:50:02 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095002_00012_hq8e7 :: Transaction:[18d7e1d1-5efa-491e-bd1f-68a2f8758890] :: elapsed 93ms :: planning 47ms :: scheduling 46ms :: running 0ms :: finishing 46ms :: begin 2017-09-15T09:50:02.037Z :: end 2017-09-15T09:50:02.130Z
- test the result for min() with distinct operator in decimalType of presto
2017-09-15 09:50:02 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT MAX(BONUS) AS RESULT FROM TESTDB.TESTTABLE 
Sep 15, 2017 9:50:02 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095002_00013_hq8e7 :: Transaction:[e4f64e44-3ec3-4787-9522-1debda83443f] :: elapsed 95ms :: planning 49ms :: scheduling 46ms :: running 0ms :: finishing 46ms :: begin 2017-09-15T09:50:02.143Z :: end 2017-09-15T09:50:02.238Z
- test the result for max() with distinct operator in decimalType of presto
2017-09-15 09:50:02 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT DISTINCT BONUS FROM TESTDB.TESTTABLE ORDER BY BONUS limit 3 
2017-09-15 09:50:02 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME FROM TESTDB.TESTTABLE ORDER BY NAME
- select decimal data type with ORDER BY  clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:147)
Sep 15, 2017 9:50:02 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095002_00014_hq8e7 :: Transaction:[1bc9d592-b439-4b93-ba3b-c6a31f235278] :: elapsed 174ms :: planning 30ms :: scheduling 144ms :: running 0ms :: finishing 144ms :: begin 2017-09-15T09:50:02.267Z :: end 2017-09-15T09:50:02.441Z
2017-09-15 09:50:02 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT DATE FROM TESTDB.TESTTABLE ORDER BY DATE
- select string type with order by clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:162)
Sep 15, 2017 9:50:02 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095002_00015_hq8e7 :: Transaction:[f8b291f2-dc18-4c2c-ba4b-65e7de979070] :: elapsed 96ms :: planning 32ms :: scheduling 64ms :: running 0ms :: finishing 64ms :: begin 2017-09-15T09:50:02.456Z :: end 2017-09-15T09:50:02.552Z
2017-09-15 09:50:02 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT DISTINCT ID FROM TESTDB.TESTTABLE ORDER BY ID
- select DATE type with order by clause *** FAILED ***
  java.util.NoSuchElementException: head of empty list
  at scala.collection.immutable.Nil$.head(List.scala:420)
  at scala.collection.immutable.Nil$.head(List.scala:417)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$17.apply$mcV$sp(PrestoAllDataTypeTest.scala:182)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$17.apply(PrestoAllDataTypeTest.scala:164)
Sep 15, 2017 9:50:02 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095002_00016_hq8e7 :: Transaction:[577d29d5-e401-4b78-b74a-17dacfd977bf] :: elapsed 58ms :: planning 32ms :: scheduling 26ms :: running 0ms :: finishing 26ms :: begin 2017-09-15T09:50:02.570Z :: end 2017-09-15T09:50:02.628Z
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$17.apply(PrestoAllDataTypeTest.scala:164)
  at org.scalatest.Transformer$$anonfun$apply$1.apply$mcV$sp(Transformer.scala:22)
  at org.scalatest.OutcomeOf$class.outcomeOf(OutcomeOf.scala:85)
  at org.scalatest.OutcomeOf$.outcomeOf(OutcomeOf.scala:104)
  at org.scalatest.Transformer.apply(Transformer.scala:22)
  at org.scalatest.Transformer.apply(Transformer.scala:20)
  ...
2017-09-15 09:50:02 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS FROM TESTDB.TESTTABLE WHERE BONUS>1234 AND ID>2 GROUP BY ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS ORDER BY ID
Sep 15, 2017 9:50:02 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095002_00017_hq8e7 :: Transaction:[a3a5aa4b-6f2d-41db-9884-54ebc02a429a] :: elapsed 84ms :: planning 29ms :: scheduling 55ms :: running 0ms :: finishing 55ms :: begin 2017-09-15T09:50:02.643Z :: end 2017-09-15T09:50:02.727Z
- select int type with order by clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:197)
- test and filter clause with greater than expression *** FAILED ***
  actualResult.toString().equals(expectedResult.toString()) was false (PrestoAllDataTypeTest.scala:223)
2017-09-15 09:50:03 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS FROM TESTDB.TESTTABLE WHERE BONUS>=1234.444 GROUP BY ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS ORDER BY ID
Sep 15, 2017 9:50:03 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095002_00018_hq8e7 :: Transaction:[c76ee8fb-2e68-4253-bbe2-b0076a13e821] :: elapsed 267ms :: planning 182ms :: scheduling 85ms :: running 0ms :: finishing 85ms :: begin 2017-09-15T09:50:02.769Z :: end 2017-09-15T09:50:03.036Z
2017-09-15 09:50:03 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS FROM TESTDB.TESTTABLE WHERE BONUS<=1234.444 GROUP BY ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS ORDER BY ID LIMIT 2
- test and filter clause with greater than equal to expression *** FAILED ***
  actualResult.toString().equals(expectedResult.toString()) was false (PrestoAllDataTypeTest.scala:267)
Sep 15, 2017 9:50:03 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095003_00019_hq8e7 :: Transaction:[9cfb004e-24e7-466d-94a2-f1203e56c0b2] :: elapsed 134ms :: planning 68ms :: scheduling 66ms :: running 0ms :: finishing 66ms :: begin 2017-09-15T09:50:03.094Z :: end 2017-09-15T09:50:03.228Z
2017-09-15 09:50:03 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID FROM TESTDB.TESTTABLE WHERE BONUS=1234.444
- test and filter clause with less than equal to expression *** FAILED ***
  actualResult.toString().equals(expectedResult.toString()) was false (PrestoAllDataTypeTest.scala:292)
Sep 15, 2017 9:50:03 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095003_00020_hq8e7 :: Transaction:[801b6928-39b6-4736-99f8-a7e4221ec806] :: elapsed 114ms :: planning 61ms :: scheduling 53ms :: running 0ms :: finishing 53ms :: begin 2017-09-15T09:50:03.252Z :: end 2017-09-15T09:50:03.366Z
Sep 15, 2017 9:50:03 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095003_00021_hq8e7 :: Transaction:[92e4f765-facc-4652-9f39-7ce48bdfe71b] :: elapsed 56ms :: planning 38ms :: scheduling 18ms :: running 0ms :: finishing 18ms :: begin 2017-09-15T09:50:03.384Z :: end 2017-09-15T09:50:03.440Z
2017-09-15 09:50:03 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS FROM TESTDB.TESTTABLE WHERE BONUS>1234 AND ID<2 GROUP BY ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS ORDER BY ID
- test equal to expression on decimal value *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:301)
2017-09-15 09:50:03 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME from testdb.testtable WHERE PHONETYPE IN('phone1848','phone706')
- test less than expression with and operator *** FAILED ***
  actualResult.toString().equals(expectedResult.toString()) was false (PrestoAllDataTypeTest.scala:317)
Sep 15, 2017 9:50:03 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095003_00022_hq8e7 :: Transaction:[bd4027d1-0541-4116-b127-74ec7caf4ddf] :: elapsed 134ms :: planning 74ms :: scheduling 60ms :: running 0ms :: finishing 60ms :: begin 2017-09-15T09:50:03.461Z :: end 2017-09-15T09:50:03.595Z
2017-09-15 09:50:04 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME from testdb.testtable WHERE PHONETYPE NOT IN('phone1848','phone706')
- test the result for in clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:327)
Sep 15, 2017 9:50:04 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095003_00023_hq8e7 :: Transaction:[1716b154-8579-4a5e-8261-ddb37cc8fe2a] :: elapsed 401ms :: planning 381ms :: scheduling 20ms :: running 0ms :: finishing 20ms :: begin 2017-09-15T09:50:03.622Z :: end 2017-09-15T09:50:04.023Z
Sep 15, 2017 9:50:04 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095004_00024_hq8e7 :: Transaction:[f5e9f160-6885-4d6c-b242-fbb208add3a5] :: elapsed 65ms :: planning 48ms :: scheduling 17ms :: running 0ms :: finishing 17ms :: begin 2017-09-15T09:50:04.048Z :: end 2017-09-15T09:50:04.113Z
2017-09-15 09:50:04 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID FROM TESTDB.TESTTABLE WHERE DATE IS NULL
- test the result for not in clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:341)
Sep 15, 2017 9:50:04 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095004_00025_hq8e7 :: Transaction:[3118cc06-e749-43d4-8887-044e0226b16f] :: elapsed 56ms :: planning 33ms :: scheduling 23ms :: running 0ms :: finishing 23ms :: begin 2017-09-15T09:50:04.129Z :: end 2017-09-15T09:50:04.185Z
2017-09-15 09:50:04 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME FROM TESTDB.TESTTABLE WHERE DATE IS NOT NULL AND ID=9
- test for null operator on date data type *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:347)
- test for not null operator on date data type *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:354)
Sep 15, 2017 9:50:04 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095004_00026_hq8e7 :: Transaction:[dee64055-34f9-4979-a0bf-351322539215] :: elapsed 63ms :: planning 40ms :: scheduling 23ms :: running 0ms :: finishing 23ms :: begin 2017-09-15T09:50:04.205Z :: end 2017-09-15T09:50:04.268Z
2017-09-15 09:50:04 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME FROM TESTDB.TESTTABLE WHERE DOB IS NOT NULL AND ID=9
- test for not null operator on timestamp type *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:362)
2017-09-15 09:50:04 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME FROM TESTDB.TESTTABLE WHERE DOB IS NULL AND ID=1
Sep 15, 2017 9:50:04 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095004_00027_hq8e7 :: Transaction:[0500319b-0f74-4d84-8243-f35ce8568228] :: elapsed 55ms :: planning 31ms :: scheduling 24ms :: running 0ms :: finishing 24ms :: begin 2017-09-15T09:50:04.281Z :: end 2017-09-15T09:50:04.336Z
Sep 15, 2017 9:50:04 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095004_00028_hq8e7 :: Transaction:[33dd3b5a-ab98-41e6-ada3-99e9ee79056f] :: elapsed 52ms :: planning 39ms :: scheduling 13ms :: running 0ms :: finishing 13ms :: begin 2017-09-15T09:50:04.356Z :: end 2017-09-15T09:50:04.408Z
2017-09-15 09:50:04 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT DISTINCT SHORTFIELD from testdb.testtable ORDER BY SHORTFIELD 
- test for null operator on timestamp type *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:369)
2017-09-15 09:50:04 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID from testdb.testtable WHERE SHORTFIELD IS NULL ORDER BY SHORTFIELD 
- test the result for short datatype with order by clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:385)
Sep 15, 2017 9:50:04 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095004_00029_hq8e7 :: Transaction:[659c0aef-7cd0-4398-a1e5-9c573d62bcec] :: elapsed 91ms :: planning 27ms :: scheduling 64ms :: running 0ms :: finishing 64ms :: begin 2017-09-15T09:50:04.423Z :: end 2017-09-15T09:50:04.514Z
- test the result for short datatype in clause where field is null *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:393)
Sep 15, 2017 9:50:04 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095004_00030_hq8e7 :: Transaction:[cbe4c977-285c-4f4a-9e38-a7a6975db663] :: elapsed 52ms :: planning 33ms :: scheduling 19ms :: running 0ms :: finishing 19ms :: begin 2017-09-15T09:50:04.527Z :: end 2017-09-15T09:50:04.579Z
2017-09-15 09:50:04 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID from testdb.testtable WHERE SHORTFIELD>11 
Sep 15, 2017 9:50:04 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_095004_00031_hq8e7 :: Transaction:[a4366ea3-1ab9-434a-8a7f-1a9f19ce0168] :: elapsed 53ms :: planning 35ms :: scheduling 18ms :: running 0ms :: finishing 18ms :: begin 2017-09-15T09:50:04.593Z :: end 2017-09-15T09:50:04.646Z
- test the result for short datatype with greater than operator *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:401)
Sep 15, 2017 9:50:04 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 9:50:04 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@4ef277ef{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:8086}
Sep 15, 2017 9:50:06 AM io.airlift.log.Logger warn
WARNING: Error fetching memory info from http://127.0.0.1:8086/v1/memory: Server refused connection: http://127.0.0.1:8086/v1/memory
Sep 15, 2017 9:50:07 AM io.airlift.log.Logger warn
WARNING: Error fetching node state from http://127.0.0.1:8086/v1/info/state: Server refused connection: http://127.0.0.1:8086/v1/info/state
Sep 15, 2017 9:50:07 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@2c51c756{/,null,UNAVAILABLE,@http}
Sep 15, 2017 9:50:07 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=16<=200,i=12,q=14} Couldn't stop Thread[http-worker-253,5,]
Sep 15, 2017 9:50:07 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=16<=200,i=7,q=13} Couldn't stop Thread[http-worker-371,5,]
Sep 15, 2017 9:50:07 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=16<=200,i=4,q=13} Couldn't stop Thread[http-worker-212,5,]
Sep 15, 2017 9:50:07 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=16<=200,i=0,q=12} Couldn't stop Thread[http-worker-210,5,]
Sep 15, 2017 9:50:07 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=16<=200,i=0,q=12} Couldn't stop Thread[http-worker-214,5,]
Sep 15, 2017 9:50:07 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
Sep 15, 2017 9:50:07 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 9:50:07 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@31c0c7e5{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:37405}
2017-09-15 09:50:07 INFO  AbstractDataLoadProcessorStep:150 - Thread-2 Rows processed in step Data Writer : 10
2017-09-15 09:50:07 INFO  AbstractDataLoadProcessorStep:150 - Thread-3 Rows processed in step Data Converter : 10
2017-09-15 09:50:07 INFO  AbstractDataLoadProcessorStep:150 - Thread-4 Rows processed in step Input Processor : 10
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@67f266bd{/,null,UNAVAILABLE,@http}
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=12<=200,i=8,q=9} Couldn't stop Thread[http-worker-300,5,]
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=12<=200,i=3,q=8} Couldn't stop Thread[http-worker-156,5,]
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=12<=200,i=0,q=7} Couldn't stop Thread[http-worker-161,5,]
Sep 15, 2017 9:50:08 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
Sep 15, 2017 9:50:08 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@4adcc981{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:45930}
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@729d6ee2{/,null,UNAVAILABLE,@http}
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=11,q=12} Couldn't stop Thread[http-worker-421,5,main]
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=5,q=10} Couldn't stop Thread[http-worker-252,5,]
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=3,q=9} Couldn't stop Thread[http-worker-381,5,]
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=0,q=7} Couldn't stop Thread[http-worker-250,5,]
Sep 15, 2017 9:50:08 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
Sep 15, 2017 9:50:08 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@38929da{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:36076}
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@1e6d30c0{/,null,UNAVAILABLE,@http}
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=10,q=10} Couldn't stop Thread[http-worker-85,5,main]
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=5,q=9} Couldn't stop Thread[http-worker-422,5,main]
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=0,q=7} Couldn't stop Thread[http-worker-373,5,]
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=0,q=7} Couldn't stop Thread[http-worker-372,5,]
Sep 15, 2017 9:50:08 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
Sep 15, 2017 9:50:08 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@7da635c0{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:40963}
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@3d615b2e{/,null,UNAVAILABLE,@http}
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=10<=200,i=8,q=8} Couldn't stop Thread[http-worker-65,5,main]
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=10<=200,i=4,q=7} Couldn't stop Thread[http-worker-93,5,]
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=10<=200,i=2,q=7} Couldn't stop Thread[http-worker-427,5,]
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=10<=200,i=2,q=7} Couldn't stop Thread[http-worker-426,5,main]
Sep 15, 2017 9:50:08 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=10<=200,i=2,q=7} Couldn't stop Thread[http-worker-425,5,main]
Sep 15, 2017 9:50:08 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
2017-09-15 09:50:08 INFO  PrestoServer$:88 - ***** Stopping The Server *****
Run completed in 21 seconds, 429 milliseconds.
Total number of tests run: 32
Suites: completed 2, aborted 0
Tests: succeeded 6, failed 26, canceled 0, ignored 0, pending 0
*** 26 TESTS FAILED ***
[JENKINS] Recording test results

Build failed in Jenkins: carbondata-master-spark-2.1 » Apache CarbonData :: presto #1384

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/carbondata-master-spark-2.1/org.apache.carbondata$carbondata-presto/1384/display/redirect>

------------------------------------------
[...truncated 174.27 KB...]
 SELECT SUM(DISTINCT BONUS) AS RESULT FROM TESTDB.TESTTABLE 
2017-09-15 08:55:32 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT SUM(DISTINCT BONUS) AS RESULT FROM TESTDB.TESTTABLE 
- test the result for sum()with out distinct operator for decimal column in presto
Sep 15, 2017 8:55:32 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085532_00009_j5vw9 :: Transaction:[593c34bf-5c4f-4d5e-9db5-8a105d223393] :: elapsed 173ms :: planning 47ms :: scheduling 126ms :: running 0ms :: finishing 126ms :: begin 2017-09-15T08:55:32.323Z :: end 2017-09-15T08:55:32.496Z
2017-09-15 08:55:32 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT AVG(DISTINCT BONUS) AS RESULT FROM TESTDB.TESTTABLE 
Sep 15, 2017 8:55:32 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085532_00010_j5vw9 :: Transaction:[0b339f53-b244-4d90-a9eb-e4fdcaa86ff6] :: elapsed 117ms :: planning 26ms :: scheduling 91ms :: running 0ms :: finishing 91ms :: begin 2017-09-15T08:55:32.512Z :: end 2017-09-15T08:55:32.629Z
- test the result for sum() with distinct operator for decimal column in presto *** FAILED ***
  java.lang.NullPointerException:
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$11.apply$mcV$sp(PrestoAllDataTypeTest.scala:115)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$11.apply(PrestoAllDataTypeTest.scala:110)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$11.apply(PrestoAllDataTypeTest.scala:110)
  at org.scalatest.Transformer$$anonfun$apply$1.apply$mcV$sp(Transformer.scala:22)
  at org.scalatest.OutcomeOf$class.outcomeOf(OutcomeOf.scala:85)
  at org.scalatest.OutcomeOf$.outcomeOf(OutcomeOf.scala:104)
  at org.scalatest.Transformer.apply(Transformer.scala:22)
  at org.scalatest.Transformer.apply(Transformer.scala:20)
  at org.scalatest.FunSuiteLike$$anon$1.apply(FunSuiteLike.scala:166)
  at org.scalatest.Suite$class.withFixture(Suite.scala:1122)
  ...
- test the result for avg() with distinct operator on decimal coin presto
2017-09-15 08:55:32 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT MIN(BONUS) AS RESULT FROM TESTDB.TESTTABLE 
Sep 15, 2017 8:55:32 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085532_00011_j5vw9 :: Transaction:[620dfd5a-3f50-47e4-8c83-02d78fd66ecb] :: elapsed 168ms :: planning 39ms :: scheduling 129ms :: running 0ms :: finishing 129ms :: begin 2017-09-15T08:55:32.654Z :: end 2017-09-15T08:55:32.822Z
- test the result for min() with distinct operator in decimalType of presto
Sep 15, 2017 8:55:32 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085532_00012_j5vw9 :: Transaction:[45d7e407-3d8c-485f-afaf-f576d380e3f0] :: elapsed 67ms :: planning 37ms :: scheduling 30ms :: running 0ms :: finishing 30ms :: begin 2017-09-15T08:55:32.834Z :: end 2017-09-15T08:55:32.901Z
2017-09-15 08:55:32 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT MAX(BONUS) AS RESULT FROM TESTDB.TESTTABLE 
- test the result for max() with distinct operator in decimalType of presto
Sep 15, 2017 8:55:32 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085532_00013_j5vw9 :: Transaction:[acf53826-3dbc-4e89-95a2-ac5a31dbd9e3] :: elapsed 59ms :: planning 35ms :: scheduling 24ms :: running 0ms :: finishing 24ms :: begin 2017-09-15T08:55:32.913Z :: end 2017-09-15T08:55:32.972Z
2017-09-15 08:55:32 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT DISTINCT BONUS FROM TESTDB.TESTTABLE ORDER BY BONUS limit 3 
2017-09-15 08:55:33 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME FROM TESTDB.TESTTABLE ORDER BY NAME
- select decimal data type with ORDER BY  clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:147)
Sep 15, 2017 8:55:33 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085532_00014_j5vw9 :: Transaction:[858eab46-0aae-4534-aa76-b46742617fcf] :: elapsed 139ms :: planning 30ms :: scheduling 109ms :: running 0ms :: finishing 109ms :: begin 2017-09-15T08:55:33.005Z :: end 2017-09-15T08:55:33.144Z
2017-09-15 08:55:33 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT DATE FROM TESTDB.TESTTABLE ORDER BY DATE
- select string type with order by clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:162)
Sep 15, 2017 8:55:33 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085533_00015_j5vw9 :: Transaction:[600ab02b-6632-4433-8dd4-8744838324df] :: elapsed 82ms :: planning 25ms :: scheduling 57ms :: running 0ms :: finishing 57ms :: begin 2017-09-15T08:55:33.160Z :: end 2017-09-15T08:55:33.242Z
2017-09-15 08:55:33 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT DISTINCT ID FROM TESTDB.TESTTABLE ORDER BY ID
Sep 15, 2017 8:55:33 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085533_00016_j5vw9 :: Transaction:[51e5824e-f7d6-453e-bd4f-197ae8dcccda] :: elapsed 41ms :: planning 22ms :: scheduling 19ms :: running 0ms :: finishing 19ms :: begin 2017-09-15T08:55:33.260Z :: end 2017-09-15T08:55:33.301Z
- select DATE type with order by clause *** FAILED ***
  java.util.NoSuchElementException: head of empty list
  at scala.collection.immutable.Nil$.head(List.scala:420)
  at scala.collection.immutable.Nil$.head(List.scala:417)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$17.apply$mcV$sp(PrestoAllDataTypeTest.scala:182)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$17.apply(PrestoAllDataTypeTest.scala:164)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$17.apply(PrestoAllDataTypeTest.scala:164)
  at org.scalatest.Transformer$$anonfun$apply$1.apply$mcV$sp(Transformer.scala:22)
  at org.scalatest.OutcomeOf$class.outcomeOf(OutcomeOf.scala:85)
  at org.scalatest.OutcomeOf$.outcomeOf(OutcomeOf.scala:104)
  at org.scalatest.Transformer.apply(Transformer.scala:22)
  at org.scalatest.Transformer.apply(Transformer.scala:20)
  ...
2017-09-15 08:55:33 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS FROM TESTDB.TESTTABLE WHERE BONUS>1234 AND ID>2 GROUP BY ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS ORDER BY ID
- select int type with order by clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:197)
Sep 15, 2017 8:55:33 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085533_00017_j5vw9 :: Transaction:[ec8faf69-2472-4f15-9012-6e71ae778ed7] :: elapsed 83ms :: planning 40ms :: scheduling 43ms :: running 0ms :: finishing 43ms :: begin 2017-09-15T08:55:33.312Z :: end 2017-09-15T08:55:33.395Z
- test and filter clause with greater than expression *** FAILED ***
  actualResult.toString().equals(expectedResult.toString()) was false (PrestoAllDataTypeTest.scala:223)
2017-09-15 08:55:33 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS FROM TESTDB.TESTTABLE WHERE BONUS>=1234.444 GROUP BY ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS ORDER BY ID
Sep 15, 2017 8:55:33 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085533_00018_j5vw9 :: Transaction:[43cf13ff-2b20-44af-aa4f-7b36758606cf] :: elapsed 214ms :: planning 143ms :: scheduling 71ms :: running 0ms :: finishing 71ms :: begin 2017-09-15T08:55:33.429Z :: end 2017-09-15T08:55:33.643Z
- test and filter clause with greater than equal to expression *** FAILED ***
  actualResult.toString().equals(expectedResult.toString()) was false (PrestoAllDataTypeTest.scala:267)
2017-09-15 08:55:33 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS FROM TESTDB.TESTTABLE WHERE BONUS<=1234.444 GROUP BY ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS ORDER BY ID LIMIT 2
Sep 15, 2017 8:55:33 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085533_00019_j5vw9 :: Transaction:[2bdbbaa4-e4b3-442d-a9c6-300ea0af9b90] :: elapsed 98ms :: planning 57ms :: scheduling 41ms :: running 0ms :: finishing 41ms :: begin 2017-09-15T08:55:33.671Z :: end 2017-09-15T08:55:33.769Z
- test and filter clause with less than equal to expression *** FAILED ***
  actualResult.toString().equals(expectedResult.toString()) was false (PrestoAllDataTypeTest.scala:292)
2017-09-15 08:55:33 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID FROM TESTDB.TESTTABLE WHERE BONUS=1234.444
Sep 15, 2017 8:55:33 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085533_00020_j5vw9 :: Transaction:[269db722-bd43-4759-bf45-2c8606c1cf61] :: elapsed 78ms :: planning 43ms :: scheduling 35ms :: running 0ms :: finishing 35ms :: begin 2017-09-15T08:55:33.788Z :: end 2017-09-15T08:55:33.866Z
Sep 15, 2017 8:55:33 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085533_00021_j5vw9 :: Transaction:[beca9fdb-b2c6-41c7-a716-c61fae604376] :: elapsed 50ms :: planning 32ms :: scheduling 18ms :: running 0ms :: finishing 18ms :: begin 2017-09-15T08:55:33.883Z :: end 2017-09-15T08:55:33.933Z
- test equal to expression on decimal value *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:301)
2017-09-15 08:55:33 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS FROM TESTDB.TESTTABLE WHERE BONUS>1234 AND ID<2 GROUP BY ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS ORDER BY ID
- test less than expression with and operator *** FAILED ***
  actualResult.toString().equals(expectedResult.toString()) was false (PrestoAllDataTypeTest.scala:317)
2017-09-15 08:55:34 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME from testdb.testtable WHERE PHONETYPE IN('phone1848','phone706')
Sep 15, 2017 8:55:34 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085533_00022_j5vw9 :: Transaction:[ccfda4e0-cde7-410e-8615-04cc4b9de219] :: elapsed 92ms :: planning 52ms :: scheduling 40ms :: running 0ms :: finishing 40ms :: begin 2017-09-15T08:55:33.950Z :: end 2017-09-15T08:55:34.042Z
2017-09-15 08:55:34 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME from testdb.testtable WHERE PHONETYPE NOT IN('phone1848','phone706')
- test the result for in clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:327)
Sep 15, 2017 8:55:34 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085534_00023_j5vw9 :: Transaction:[a9aca817-8bb8-446e-93ca-56514a992eb6] :: elapsed 313ms :: planning 299ms :: scheduling 14ms :: running 0ms :: finishing 14ms :: begin 2017-09-15T08:55:34.067Z :: end 2017-09-15T08:55:34.380Z
Sep 15, 2017 8:55:34 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085534_00024_j5vw9 :: Transaction:[c5f10f93-29cc-4ac7-bea4-0b1c2e341846] :: elapsed 48ms :: planning 33ms :: scheduling 15ms :: running 0ms :: finishing 15ms :: begin 2017-09-15T08:55:34.392Z :: end 2017-09-15T08:55:34.440Z
2017-09-15 08:55:34 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID FROM TESTDB.TESTTABLE WHERE DATE IS NULL
- test the result for not in clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:341)
Sep 15, 2017 8:55:34 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085534_00025_j5vw9 :: Transaction:[b3fc1a20-94ee-42f2-b332-ce6c124b7313] :: elapsed 50ms :: planning 30ms :: scheduling 20ms :: running 0ms :: finishing 20ms :: begin 2017-09-15T08:55:34.457Z :: end 2017-09-15T08:55:34.507Z
2017-09-15 08:55:34 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME FROM TESTDB.TESTTABLE WHERE DATE IS NOT NULL AND ID=9
- test for null operator on date data type *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:347)
Sep 15, 2017 8:55:34 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085534_00026_j5vw9 :: Transaction:[864e8270-9b94-4568-8c20-a35b0a187708] :: elapsed 44ms :: planning 30ms :: scheduling 14ms :: running 0ms :: finishing 14ms :: begin 2017-09-15T08:55:34.519Z :: end 2017-09-15T08:55:34.563Z
2017-09-15 08:55:34 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME FROM TESTDB.TESTTABLE WHERE DOB IS NOT NULL AND ID=9
- test for not null operator on date data type *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:354)
- test for not null operator on timestamp type *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:362)
2017-09-15 08:55:34 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME FROM TESTDB.TESTTABLE WHERE DOB IS NULL AND ID=1
Sep 15, 2017 8:55:34 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085534_00027_j5vw9 :: Transaction:[fc67ad08-507d-40ef-8b76-bc9025bb2b58] :: elapsed 85ms :: planning 35ms :: scheduling 50ms :: running 0ms :: finishing 50ms :: begin 2017-09-15T08:55:34.575Z :: end 2017-09-15T08:55:34.660Z
- test for null operator on timestamp type *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:369)
2017-09-15 08:55:34 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT DISTINCT SHORTFIELD from testdb.testtable ORDER BY SHORTFIELD 
Sep 15, 2017 8:55:34 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085534_00028_j5vw9 :: Transaction:[62ae91ae-353d-4d72-8844-33905f456aa0] :: elapsed 42ms :: planning 27ms :: scheduling 15ms :: running 0ms :: finishing 15ms :: begin 2017-09-15T08:55:34.676Z :: end 2017-09-15T08:55:34.718Z
2017-09-15 08:55:34 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID from testdb.testtable WHERE SHORTFIELD IS NULL ORDER BY SHORTFIELD 
- test the result for short datatype with order by clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:385)
Sep 15, 2017 8:55:34 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085534_00029_j5vw9 :: Transaction:[b6f07a31-7560-4cda-a263-ee18a4c4e672] :: elapsed 64ms :: planning 23ms :: scheduling 41ms :: running 0ms :: finishing 41ms :: begin 2017-09-15T08:55:34.729Z :: end 2017-09-15T08:55:34.793Z
- test the result for short datatype in clause where field is null *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:393)
2017-09-15 08:55:34 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID from testdb.testtable WHERE SHORTFIELD>11 
Sep 15, 2017 8:55:34 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085534_00030_j5vw9 :: Transaction:[68436785-3aa9-4df1-90f5-1744725f7577] :: elapsed 41ms :: planning 22ms :: scheduling 19ms :: running 0ms :: finishing 19ms :: begin 2017-09-15T08:55:34.805Z :: end 2017-09-15T08:55:34.846Z
Sep 15, 2017 8:55:34 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085534_00031_j5vw9 :: Transaction:[cf25fd8d-5fa0-4752-976e-74a0bf315e17] :: elapsed 41ms :: planning 25ms :: scheduling 16ms :: running 0ms :: finishing 16ms :: begin 2017-09-15T08:55:34.859Z :: end 2017-09-15T08:55:34.900Z
- test the result for short datatype with greater than operator *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:401)
Sep 15, 2017 8:55:34 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 8:55:34 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@4ef277ef{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:8086}
Sep 15, 2017 8:55:34 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@2c51c756{/,null,UNAVAILABLE,@http}
Sep 15, 2017 8:55:34 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=16<=200,i=12,q=13} Couldn't stop Thread[http-worker-211,5,]
Sep 15, 2017 8:55:34 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=16<=200,i=2,q=12} Couldn't stop Thread[http-worker-212,5,]
Sep 15, 2017 8:55:34 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=16<=200,i=0,q=12} Couldn't stop Thread[http-worker-216,5,]
Sep 15, 2017 8:55:34 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=16<=200,i=0,q=12} Couldn't stop Thread[http-worker-310,5,]
Sep 15, 2017 8:55:34 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
Sep 15, 2017 8:55:34 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 8:55:34 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@31c0c7e5{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:37276}
2017-09-15 08:55:39 INFO  AbstractDataLoadProcessorStep:150 - Thread-2 Rows processed in step Data Writer : 10
2017-09-15 08:55:39 INFO  AbstractDataLoadProcessorStep:150 - Thread-4 Rows processed in step Input Processor : 10
2017-09-15 08:55:39 INFO  AbstractDataLoadProcessorStep:150 - Thread-3 Rows processed in step Data Converter : 10
Sep 15, 2017 8:55:39 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@67f266bd{/,null,UNAVAILABLE,@http}
Sep 15, 2017 8:55:39 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=12,q=12} Couldn't stop Thread[http-worker-161,5,main]
Sep 15, 2017 8:55:39 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=6,q=11} Couldn't stop Thread[http-worker-153,5,]
Sep 15, 2017 8:55:39 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=3,q=11} Couldn't stop Thread[http-worker-409,5,]
Sep 15, 2017 8:55:39 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=0,q=10} Couldn't stop Thread[http-worker-358,5,]
Sep 15, 2017 8:55:39 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
Sep 15, 2017 8:55:39 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 8:55:39 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@4adcc981{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:37889}
Sep 15, 2017 8:55:39 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@729d6ee2{/,null,UNAVAILABLE,@http}
Sep 15, 2017 8:55:39 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=11<=200,i=10,q=10} Couldn't stop Thread[http-worker-300,5,main]
Sep 15, 2017 8:55:39 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=11<=200,i=7,q=10} Couldn't stop Thread[http-worker-415,5,]
Sep 15, 2017 8:55:39 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=11<=200,i=3,q=9} Couldn't stop Thread[http-worker-414,5,main]
Sep 15, 2017 8:55:39 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=11<=200,i=3,q=9} Couldn't stop Thread[http-worker-413,5,main]
Sep 15, 2017 8:55:39 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=11<=200,i=1,q=9} Couldn't stop Thread[http-worker-118,5,]
Sep 15, 2017 8:55:40 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
Sep 15, 2017 8:55:40 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 8:55:40 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@38929da{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:33247}
Sep 15, 2017 8:55:40 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@1e6d30c0{/,null,UNAVAILABLE,@http}
Sep 15, 2017 8:55:40 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=10,q=10} Couldn't stop Thread[http-worker-418,5,main]
Sep 15, 2017 8:55:40 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=6,q=9} Couldn't stop Thread[http-worker-79,5,]
Sep 15, 2017 8:55:40 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=3,q=9} Couldn't stop Thread[http-worker-85,5,]
Sep 15, 2017 8:55:40 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=2,q=9} Couldn't stop Thread[http-worker-416,5,]
Sep 15, 2017 8:55:40 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=0,q=9} Couldn't stop Thread[http-worker-80,5,]
Sep 15, 2017 8:55:40 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
Sep 15, 2017 8:55:40 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 8:55:40 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@7da635c0{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:34081}
Sep 15, 2017 8:55:40 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@3d615b2e{/,null,UNAVAILABLE,@http}
Sep 15, 2017 8:55:40 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=10<=200,i=8,q=8} Couldn't stop Thread[http-worker-93,5,main]
Sep 15, 2017 8:55:40 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=10<=200,i=4,q=7} Couldn't stop Thread[http-worker-421,5,main]
Sep 15, 2017 8:55:40 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=10<=200,i=2,q=6} Couldn't stop Thread[http-worker-46,5,main]
Sep 15, 2017 8:55:40 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=10<=200,i=0,q=5} Couldn't stop Thread[http-worker-419,5,]
Sep 15, 2017 8:55:40 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
2017-09-15 08:55:40 INFO  PrestoServer$:88 - ***** Stopping The Server *****
Run completed in 21 seconds, 482 milliseconds.
Total number of tests run: 32
Suites: completed 2, aborted 0
Tests: succeeded 6, failed 26, canceled 0, ignored 0, pending 0
*** 26 TESTS FAILED ***
[JENKINS] Recording test results

Build failed in Jenkins: carbondata-master-spark-2.1 » Apache CarbonData :: presto #1383

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/carbondata-master-spark-2.1/org.apache.carbondata$carbondata-presto/1383/display/redirect>

------------------------------------------
[...truncated 174.40 KB...]
2017-09-15 08:50:53 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT SUM(DISTINCT BONUS) AS RESULT FROM TESTDB.TESTTABLE 
Sep 15, 2017 8:50:53 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085053_00009_6c8cn :: Transaction:[23dc290c-79cd-46ba-83ac-0019d11ba04a] :: elapsed 175ms :: planning 35ms :: scheduling 140ms :: running 0ms :: finishing 140ms :: begin 2017-09-15T08:50:53.395Z :: end 2017-09-15T08:50:53.570Z
2017-09-15 08:50:53 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT AVG(DISTINCT BONUS) AS RESULT FROM TESTDB.TESTTABLE 
Sep 15, 2017 8:50:53 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085053_00010_6c8cn :: Transaction:[e064d1de-05f4-4f49-aead-fd7456ac0a09] :: elapsed 104ms :: planning 26ms :: scheduling 78ms :: running 0ms :: finishing 78ms :: begin 2017-09-15T08:50:53.585Z :: end 2017-09-15T08:50:53.689Z
- test the result for sum() with distinct operator for decimal column in presto *** FAILED ***
  java.lang.NullPointerException:
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$11.apply$mcV$sp(PrestoAllDataTypeTest.scala:115)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$11.apply(PrestoAllDataTypeTest.scala:110)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$11.apply(PrestoAllDataTypeTest.scala:110)
  at org.scalatest.Transformer$$anonfun$apply$1.apply$mcV$sp(Transformer.scala:22)
  at org.scalatest.OutcomeOf$class.outcomeOf(OutcomeOf.scala:85)
  at org.scalatest.OutcomeOf$.outcomeOf(OutcomeOf.scala:104)
  at org.scalatest.Transformer.apply(Transformer.scala:22)
  at org.scalatest.Transformer.apply(Transformer.scala:20)
  at org.scalatest.FunSuiteLike$$anon$1.apply(FunSuiteLike.scala:166)
  at org.scalatest.Suite$class.withFixture(Suite.scala:1122)
  ...
- test the result for avg() with distinct operator on decimal coin presto
2017-09-15 08:50:53 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT MIN(BONUS) AS RESULT FROM TESTDB.TESTTABLE 
Sep 15, 2017 8:50:53 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085053_00011_6c8cn :: Transaction:[c291ffc2-8b9a-453a-bf38-fdf4ba654ac4] :: elapsed 92ms :: planning 34ms :: scheduling 58ms :: running 0ms :: finishing 58ms :: begin 2017-09-15T08:50:53.704Z :: end 2017-09-15T08:50:53.796Z
Sep 15, 2017 8:50:53 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085053_00012_6c8cn :: Transaction:[17e9a3c5-9edf-4237-a6d9-89088cd3e6e3] :: elapsed 66ms :: planning 36ms :: scheduling 30ms :: running 0ms :: finishing 30ms :: begin 2017-09-15T08:50:53.808Z :: end 2017-09-15T08:50:53.874Z
- test the result for min() with distinct operator in decimalType of presto
2017-09-15 08:50:53 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT MAX(BONUS) AS RESULT FROM TESTDB.TESTTABLE 
- test the result for max() with distinct operator in decimalType of presto
Sep 15, 2017 8:50:53 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085053_00013_6c8cn :: Transaction:[0b144227-7d62-43f6-ad3d-99cefd7dec1f] :: elapsed 61ms :: planning 29ms :: scheduling 32ms :: running 0ms :: finishing 32ms :: begin 2017-09-15T08:50:53.885Z :: end 2017-09-15T08:50:53.946Z
2017-09-15 08:50:53 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT DISTINCT BONUS FROM TESTDB.TESTTABLE ORDER BY BONUS limit 3 
- select decimal data type with ORDER BY  clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:147)
2017-09-15 08:50:54 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME FROM TESTDB.TESTTABLE ORDER BY NAME
Sep 15, 2017 8:50:54 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085053_00014_6c8cn :: Transaction:[d1afee35-e056-4d83-9db4-e321e2b230c0] :: elapsed 116ms :: planning 28ms :: scheduling 88ms :: running 0ms :: finishing 88ms :: begin 2017-09-15T08:50:53.965Z :: end 2017-09-15T08:50:54.081Z
2017-09-15 08:50:54 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT DATE FROM TESTDB.TESTTABLE ORDER BY DATE
- select string type with order by clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:162)
Sep 15, 2017 8:50:54 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085054_00015_6c8cn :: Transaction:[86e001dd-eb2f-4787-92ba-4b94437c3e08] :: elapsed 81ms :: planning 35ms :: scheduling 46ms :: running 0ms :: finishing 46ms :: begin 2017-09-15T08:50:54.100Z :: end 2017-09-15T08:50:54.181Z
2017-09-15 08:50:54 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT DISTINCT ID FROM TESTDB.TESTTABLE ORDER BY ID
Sep 15, 2017 8:50:54 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085054_00016_6c8cn :: Transaction:[f9a27026-bee3-47d3-a543-6a02d035f687] :: elapsed 48ms :: planning 24ms :: scheduling 24ms :: running 0ms :: finishing 24ms :: begin 2017-09-15T08:50:54.202Z :: end 2017-09-15T08:50:54.250Z
- select DATE type with order by clause *** FAILED ***
  java.util.NoSuchElementException: head of empty list
  at scala.collection.immutable.Nil$.head(List.scala:420)
  at scala.collection.immutable.Nil$.head(List.scala:417)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$17.apply$mcV$sp(PrestoAllDataTypeTest.scala:182)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$17.apply(PrestoAllDataTypeTest.scala:164)
  at org.apache.carbondata.presto.integrationtest.PrestoAllDataTypeTest$$anonfun$17.apply(PrestoAllDataTypeTest.scala:164)
  at org.scalatest.Transformer$$anonfun$apply$1.apply$mcV$sp(Transformer.scala:22)
  at org.scalatest.OutcomeOf$class.outcomeOf(OutcomeOf.scala:85)
  at org.scalatest.OutcomeOf$.outcomeOf(OutcomeOf.scala:104)
  at org.scalatest.Transformer.apply(Transformer.scala:22)
  at org.scalatest.Transformer.apply(Transformer.scala:20)
  ...
2017-09-15 08:50:54 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS FROM TESTDB.TESTTABLE WHERE BONUS>1234 AND ID>2 GROUP BY ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS ORDER BY ID
- select int type with order by clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:197)
Sep 15, 2017 8:50:54 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085054_00017_6c8cn :: Transaction:[93efd492-b1ad-4faf-a72a-3628e8425423] :: elapsed 60ms :: planning 24ms :: scheduling 36ms :: running 0ms :: finishing 36ms :: begin 2017-09-15T08:50:54.262Z :: end 2017-09-15T08:50:54.322Z
2017-09-15 08:50:54 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS FROM TESTDB.TESTTABLE WHERE BONUS>=1234.444 GROUP BY ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS ORDER BY ID
- test and filter clause with greater than expression *** FAILED ***
  actualResult.toString().equals(expectedResult.toString()) was false (PrestoAllDataTypeTest.scala:223)
Sep 15, 2017 8:50:54 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085054_00018_6c8cn :: Transaction:[e9365ce5-e618-4035-bdd7-b61463896a44] :: elapsed 171ms :: planning 116ms :: scheduling 55ms :: running 0ms :: finishing 55ms :: begin 2017-09-15T08:50:54.356Z :: end 2017-09-15T08:50:54.527Z
- test and filter clause with greater than equal to expression *** FAILED ***
  actualResult.toString().equals(expectedResult.toString()) was false (PrestoAllDataTypeTest.scala:267)
2017-09-15 08:50:54 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS FROM TESTDB.TESTTABLE WHERE BONUS<=1234.444 GROUP BY ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS ORDER BY ID LIMIT 2
Sep 15, 2017 8:50:54 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085054_00019_6c8cn :: Transaction:[c1aed66e-005c-44d7-a8c8-4ecb4b45057b] :: elapsed 104ms :: planning 69ms :: scheduling 35ms :: running 0ms :: finishing 35ms :: begin 2017-09-15T08:50:54.550Z :: end 2017-09-15T08:50:54.654Z
- test and filter clause with less than equal to expression *** FAILED ***
  actualResult.toString().equals(expectedResult.toString()) was false (PrestoAllDataTypeTest.scala:292)
2017-09-15 08:50:54 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID FROM TESTDB.TESTTABLE WHERE BONUS=1234.444
Sep 15, 2017 8:50:54 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085054_00020_6c8cn :: Transaction:[626a9578-fd7d-4cf4-bf88-0de517c7cbfc] :: elapsed 69ms :: planning 37ms :: scheduling 32ms :: running 0ms :: finishing 32ms :: begin 2017-09-15T08:50:54.674Z :: end 2017-09-15T08:50:54.743Z
Sep 15, 2017 8:50:54 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085054_00021_6c8cn :: Transaction:[0b9805ef-cd37-4ffc-a885-e5f25d1b8cf9] :: elapsed 47ms :: planning 27ms :: scheduling 20ms :: running 0ms :: finishing 20ms :: begin 2017-09-15T08:50:54.760Z :: end 2017-09-15T08:50:54.807Z
2017-09-15 08:50:54 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS FROM TESTDB.TESTTABLE WHERE BONUS>1234 AND ID<2 GROUP BY ID,DATE,COUNTRY,NAME,PHONETYPE,SERIALNAME,SALARY,BONUS ORDER BY ID
- test equal to expression on decimal value *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:301)
2017-09-15 08:50:54 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME from testdb.testtable WHERE PHONETYPE IN('phone1848','phone706')
- test less than expression with and operator *** FAILED ***
  actualResult.toString().equals(expectedResult.toString()) was false (PrestoAllDataTypeTest.scala:317)
Sep 15, 2017 8:50:54 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085054_00022_6c8cn :: Transaction:[23df2e60-f4a3-486c-a293-aa3f56acad23] :: elapsed 104ms :: planning 54ms :: scheduling 50ms :: running 0ms :: finishing 50ms :: begin 2017-09-15T08:50:54.824Z :: end 2017-09-15T08:50:54.928Z
Sep 15, 2017 8:50:55 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085054_00023_6c8cn :: Transaction:[1002650a-5472-490f-9f88-a764114e3534] :: elapsed 330ms :: planning 311ms :: scheduling 19ms :: running 0ms :: finishing 19ms :: begin 2017-09-15T08:50:54.951Z :: end 2017-09-15T08:50:55.281Z
2017-09-15 08:50:55 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME from testdb.testtable WHERE PHONETYPE NOT IN('phone1848','phone706')
- test the result for in clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:327)
Sep 15, 2017 8:50:55 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085055_00024_6c8cn :: Transaction:[b26d5038-1af9-4ba0-85ed-c58bfbe89c42] :: elapsed 54ms :: planning 37ms :: scheduling 17ms :: running 0ms :: finishing 17ms :: begin 2017-09-15T08:50:55.296Z :: end 2017-09-15T08:50:55.350Z
2017-09-15 08:50:55 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID FROM TESTDB.TESTTABLE WHERE DATE IS NULL
- test the result for not in clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:341)
Sep 15, 2017 8:50:55 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085055_00025_6c8cn :: Transaction:[f3ca6fe3-926d-4196-8d40-b1a73279d492] :: elapsed 41ms :: planning 22ms :: scheduling 19ms :: running 0ms :: finishing 19ms :: begin 2017-09-15T08:50:55.363Z :: end 2017-09-15T08:50:55.404Z
2017-09-15 08:50:55 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME FROM TESTDB.TESTTABLE WHERE DATE IS NOT NULL AND ID=9
- test for null operator on date data type *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:347)
Sep 15, 2017 8:50:55 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085055_00026_6c8cn :: Transaction:[0f5bb643-fa74-485e-abad-ae921ee5bf88] :: elapsed 49ms :: planning 33ms :: scheduling 16ms :: running 0ms :: finishing 16ms :: begin 2017-09-15T08:50:55.423Z :: end 2017-09-15T08:50:55.472Z
2017-09-15 08:50:55 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME FROM TESTDB.TESTTABLE WHERE DOB IS NOT NULL AND ID=9
- test for not null operator on date data type *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:354)
- test for not null operator on timestamp type *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:362)
2017-09-15 08:50:55 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT NAME FROM TESTDB.TESTTABLE WHERE DOB IS NULL AND ID=1
Sep 15, 2017 8:50:55 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085055_00027_6c8cn :: Transaction:[032c2561-789f-43b6-a0bf-52764714a802] :: elapsed 57ms :: planning 41ms :: scheduling 16ms :: running 0ms :: finishing 16ms :: begin 2017-09-15T08:50:55.485Z :: end 2017-09-15T08:50:55.542Z
Sep 15, 2017 8:50:55 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085055_00028_6c8cn :: Transaction:[02462fe1-20f5-4160-89fb-5c19cba5a75e] :: elapsed 45ms :: planning 30ms :: scheduling 15ms :: running 0ms :: finishing 15ms :: begin 2017-09-15T08:50:55.556Z :: end 2017-09-15T08:50:55.601Z
2017-09-15 08:50:55 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT DISTINCT SHORTFIELD from testdb.testtable ORDER BY SHORTFIELD 
- test for null operator on timestamp type *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:369)
2017-09-15 08:50:55 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID from testdb.testtable WHERE SHORTFIELD IS NULL ORDER BY SHORTFIELD 
- test the result for short datatype with order by clause *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:385)
Sep 15, 2017 8:50:55 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085055_00029_6c8cn :: Transaction:[6ef1eeea-0447-4477-bc5b-b9fd2867f4ba] :: elapsed 72ms :: planning 24ms :: scheduling 48ms :: running 0ms :: finishing 48ms :: begin 2017-09-15T08:50:55.612Z :: end 2017-09-15T08:50:55.684Z
- test the result for short datatype in clause where field is null *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:393)
2017-09-15 08:50:55 INFO  PrestoServer$:101 - ***** executing the query ***** 
 SELECT ID from testdb.testtable WHERE SHORTFIELD>11 
Sep 15, 2017 8:50:55 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085055_00030_6c8cn :: Transaction:[5752afcc-27e9-4ee0-92e4-0849c089b891] :: elapsed 48ms :: planning 31ms :: scheduling 17ms :: running 0ms :: finishing 17ms :: begin 2017-09-15T08:50:55.699Z :: end 2017-09-15T08:50:55.747Z
Sep 15, 2017 8:50:55 AM io.airlift.log.Logger info
INFO: TIMELINE: Query 20170915_085055_00031_6c8cn :: Transaction:[6d604deb-01bc-483a-9766-7ba375462fcc] :: elapsed 40ms :: planning 26ms :: scheduling 14ms :: running 0ms :: finishing 14ms :: begin 2017-09-15T08:50:55.759Z :: end 2017-09-15T08:50:55.799Z
- test the result for short datatype with greater than operator *** FAILED ***
  actualResult.equals(expectedResult) was false (PrestoAllDataTypeTest.scala:401)
Sep 15, 2017 8:50:55 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 8:50:55 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@4ef277ef{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:8086}
Sep 15, 2017 8:50:55 AM io.airlift.log.Logger warn
WARNING: Error fetching memory info from http://127.0.0.1:8086/v1/memory: Server refused connection: http://127.0.0.1:8086/v1/memory
Sep 15, 2017 8:50:57 AM io.airlift.log.Logger warn
WARNING: Error fetching memory info from http://127.0.0.1:8086/v1/memory: Server refused connection: http://127.0.0.1:8086/v1/memory
Sep 15, 2017 8:50:59 AM io.airlift.log.Logger warn
WARNING: Error fetching node state from http://127.0.0.1:8086/v1/info/state: Server refused connection: http://127.0.0.1:8086/v1/info/state
2017-09-15 08:50:59 INFO  AbstractDataLoadProcessorStep:150 - Thread-2 Rows processed in step Data Writer : 10
2017-09-15 08:50:59 INFO  AbstractDataLoadProcessorStep:150 - Thread-3 Rows processed in step Data Converter : 10
2017-09-15 08:50:59 INFO  AbstractDataLoadProcessorStep:150 - Thread-4 Rows processed in step Input Processor : 10
Sep 15, 2017 8:50:59 AM io.airlift.log.Logger warn
WARNING: Error fetching memory info from http://127.0.0.1:8086/v1/memory: Server refused connection: http://127.0.0.1:8086/v1/memory
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@2c51c756{/,null,UNAVAILABLE,@http}
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=16<=200,i=14,q=14} Couldn't stop Thread[http-worker-213,5,main]
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=16<=200,i=7,q=13} Couldn't stop Thread[http-worker-277,5,]
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=16<=200,i=4,q=13} Couldn't stop Thread[http-worker-314,5,]
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=16<=200,i=0,q=13} Couldn't stop Thread[http-worker-255,5,]
Sep 15, 2017 8:51:00 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
Sep 15, 2017 8:51:00 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@31c0c7e5{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:38555}
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@67f266bd{/,null,UNAVAILABLE,@http}
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=14<=200,i=13,q=13} Couldn't stop Thread[http-worker-397,5,main]
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=14<=200,i=6,q=11} Couldn't stop Thread[http-worker-304,5,]
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=14<=200,i=3,q=11} Couldn't stop Thread[http-worker-412,5,main]
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=14<=200,i=0,q=8} Couldn't stop Thread[http-worker-414,5,]
Sep 15, 2017 8:51:00 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
Sep 15, 2017 8:51:00 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@4adcc981{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:42055}
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@729d6ee2{/,null,UNAVAILABLE,@http}
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=14<=200,i=11,q=12} Couldn't stop Thread[http-worker-124,5,]
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=14<=200,i=9,q=11} Couldn't stop Thread[http-worker-415,5,main]
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=14<=200,i=2,q=10} Couldn't stop Thread[http-worker-306,5,]
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=14<=200,i=0,q=10} Couldn't stop Thread[http-worker-417,5,]
Sep 15, 2017 8:51:00 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
Sep 15, 2017 8:51:00 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@38929da{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:38018}
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@1e6d30c0{/,null,UNAVAILABLE,@http}
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=11,q=11} Couldn't stop Thread[http-worker-298,5,]
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=6,q=11} Couldn't stop Thread[http-worker-420,5,]
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=2,q=10} Couldn't stop Thread[http-worker-79,5,main]
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=13<=200,i=0,q=10} Couldn't stop Thread[http-worker-253,5,]
Sep 15, 2017 8:51:00 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
Sep 15, 2017 8:51:00 AM io.airlift.log.Logger info
INFO: Life cycle stopping...
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.server.AbstractConnector doStop
INFO: Stopped http@7da635c0{HTTP/1.1,[http/1.1, h2c, h2c-17, h2c-16, h2c-15, h2c-14]}{127.0.0.1:43221}
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.server.handler.ContextHandler doStop
INFO: Stopped o.e.j.s.ServletContextHandler@3d615b2e{/,null,UNAVAILABLE,@http}
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=10<=200,i=5,q=8} Couldn't stop Thread[http-worker-422,5,main]
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=10<=200,i=2,q=8} Couldn't stop Thread[http-worker-421,5,]
Sep 15, 2017 8:51:00 AM org.eclipse.jetty.util.thread.QueuedThreadPool doStop
WARNING: http-worker{STOPPING,2<=10<=200,i=1,q=8} Couldn't stop Thread[http-worker-423,5,main]
Sep 15, 2017 8:51:00 AM io.airlift.log.Logger info
INFO: Life cycle stopped.
2017-09-15 08:51:00 INFO  PrestoServer$:88 - ***** Stopping The Server *****
Run completed in 22 seconds, 56 milliseconds.
Total number of tests run: 32
Suites: completed 2, aborted 0
Tests: succeeded 6, failed 26, canceled 0, ignored 0, pending 0
*** 26 TESTS FAILED ***
[JENKINS] Recording test results