2016-05-12 13:07:02.084+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-3861916497829999845.avsc
2016-05-12 13:07:02.105+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:02.105+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping5406935659993595534.groovy
2016-05-12 13:07:02.105+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:02.106+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:05.165+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:05.166+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:05.173+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:05.174+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:35312
2016-05-12 13:07:05.191+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:05.200+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:06.192+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:06.236+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-1991788838878248561.avsc
2016-05-12 13:07:06.274+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:06.274+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping4698541662574984538.groovy
2016-05-12 13:07:06.274+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:06.275+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:06.409+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-7020458091559956572.avsc
2016-05-12 13:07:06.417+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:06.418+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping1484910860631323379.groovy
2016-05-12 13:07:06.418+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:06.418+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:07.959+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:07.959+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:07.966+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:07.966+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:36697
2016-05-12 13:07:08.018+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:08.019+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:09.018+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:09.098+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-7835693124028158035.avsc
2016-05-12 13:07:09.104+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:09.104+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping6677344035466312354.groovy
2016-05-12 13:07:09.104+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:09.104+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:10.996+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:10.996+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:11.012+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:11.012+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:48582
2016-05-12 13:07:11.036+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:11.037+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:12.036+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:12.096+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-8987585568010999845.avsc
2016-05-12 13:07:12.107+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:12.107+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping7821511133622174587.groovy
2016-05-12 13:07:12.107+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:12.108+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:13.824+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:13.825+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:13.831+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:13.832+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:35767
2016-05-12 13:07:13.845+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:13.845+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:14.845+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:14.890+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-5304984843991683124.avsc
2016-05-12 13:07:14.900+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:14.901+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping2529742912341231112.groovy
2016-05-12 13:07:14.901+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:14.901+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:14.971+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-4444771645771150591.avsc
2016-05-12 13:07:14.976+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:14.977+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping8198435515199379899.groovy
2016-05-12 13:07:14.977+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:14.977+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:16.414+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:16.414+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:16.421+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:16.422+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:47030
2016-05-12 13:07:16.430+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:16.431+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:17.430+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:17.473+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-1253230552215419918.avsc
2016-05-12 13:07:17.478+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:17.478+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping567900608490517156.groovy
2016-05-12 13:07:17.478+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:17.478+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:19.191+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:19.191+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:19.202+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:19.203+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:33371
2016-05-12 13:07:19.244+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:19.247+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:20.241+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:20.287+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-954703465080236449.avsc
2016-05-12 13:07:20.290+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:20.291+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping461829389805390257.groovy
2016-05-12 13:07:20.291+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:20.291+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:21.691+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:21.691+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:21.698+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:21.699+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:48987
2016-05-12 13:07:21.728+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:21.729+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:22.728+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:22.780+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-6705154581121481039.avsc
2016-05-12 13:07:22.784+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:22.784+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping436559030237090737.groovy
2016-05-12 13:07:22.784+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:22.784+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:24.246+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:24.247+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:24.253+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:24.254+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:56095
2016-05-12 13:07:24.268+02 [Incoming Request Processor - 0] INFO [DslRecordMapping$JsonValueProducer]: Error mapping JSON value "string" to schema: ["null","int"]
com.fasterxml.jackson.databind.exc.InvalidFormatException: Can not construct instance of java.lang.Integer from String value ("string"): not a valid Integer value
at [Source: N/A; line: -1, column: -1]
at com.fasterxml.jackson.databind.exc.InvalidFormatException.from(InvalidFormatException.java:74)
at com.fasterxml.jackson.databind.DeserializationContext.weirdStringException(DeserializationContext.java:923)
at com.fasterxml.jackson.databind.deser.std.StdDeserializer._parseInteger(StdDeserializer.java:444)
at com.fasterxml.jackson.databind.deser.std.NumberDeserializers$IntegerDeserializer.deserialize(NumberDeserializers.java:315)
at com.fasterxml.jackson.databind.deser.std.NumberDeserializers$IntegerDeserializer.deserialize(NumberDeserializers.java:293)
at com.fasterxml.jackson.databind.ObjectReader._bind(ObjectReader.java:1534)
at com.fasterxml.jackson.databind.ObjectReader.readValue(ObjectReader.java:944)
at com.fasterxml.jackson.databind.ObjectReader.readValue(ObjectReader.java:961)
at io.divolte.server.recordmapping.AvroGenericRecordMapper.read(AvroGenericRecordMapper.java:187)
at io.divolte.server.recordmapping.AvroGenericRecordMapper.readUnion(AvroGenericRecordMapper.java:364)
at io.divolte.server.recordmapping.AvroGenericRecordMapper.read(AvroGenericRecordMapper.java:175)
at io.divolte.server.recordmapping.AvroGenericRecordMapper.read(AvroGenericRecordMapper.java:141)
at io.divolte.server.recordmapping.DslRecordMapping$JsonValueProducer.mapToGenericRecord(DslRecordMapping.java:625)
at io.divolte.server.recordmapping.DslRecordMapping$JsonValueProducer.mapToGenericRecord(DslRecordMapping.java:601)
at io.divolte.server.recordmapping.DslRecordMapping.lambda$null$18(DslRecordMapping.java:125)
at java.util.Optional.flatMap(Optional.java:241)
at io.divolte.server.recordmapping.DslRecordMapping.lambda$map$20(DslRecordMapping.java:125)
at io.divolte.server.recordmapping.DslRecordMapper.newRecordFromExchange(DslRecordMapper.java:100)
at io.divolte.server.IncomingRequestProcessor.process(IncomingRequestProcessor.java:152)
at io.divolte.server.IncomingRequestProcessor.process(IncomingRequestProcessor.java:47)
at io.divolte.server.processing.ItemProcessor.process(ItemProcessor.java:32)
at io.divolte.server.processing.ProcessingPool.lambda$microBatchingQueueDrainerWithHeartBeat$6(ProcessingPool.java:146)
at java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1626)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
2016-05-12 13:07:24.270+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:24.272+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:25.271+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:25.313+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-5363885197156227074.avsc
2016-05-12 13:07:25.317+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:25.317+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping3170797614137631030.groovy
2016-05-12 13:07:25.317+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:25.318+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:26.802+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:26.802+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:26.813+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:26.813+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:60329
2016-05-12 13:07:26.824+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:26.825+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:27.824+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:27.873+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-2715008811068587251.avsc
2016-05-12 13:07:27.876+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:27.877+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping247612145930496204.groovy
2016-05-12 13:07:27.877+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:27.877+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:29.486+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:29.486+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:29.493+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:29.493+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:42076
2016-05-12 13:07:29.504+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:29.505+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:30.504+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:30.554+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-6673849982215993274.avsc
2016-05-12 13:07:30.558+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:30.558+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping3112660516398959953.groovy
2016-05-12 13:07:30.558+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:30.558+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:32.024+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:32.024+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:32.031+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:32.032+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:33173
2016-05-12 13:07:32.042+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:32.044+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:33.043+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:33.088+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-4941219511945422494.avsc
2016-05-12 13:07:33.092+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:33.092+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping8793626776135603278.groovy
2016-05-12 13:07:33.092+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:33.092+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:33.166+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-4520004903547496608.avsc
2016-05-12 13:07:33.172+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:33.172+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping3786622973160321436.groovy
2016-05-12 13:07:33.173+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:33.173+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:34.539+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:34.539+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:34.546+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:34.547+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:56101
2016-05-12 13:07:34.556+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:34.557+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:35.558+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:35.612+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-412710667759918275.avsc
2016-05-12 13:07:35.619+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:35.619+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping1503447127648921090.groovy
2016-05-12 13:07:35.619+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:35.619+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:37.150+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:37.151+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:37.157+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:37.157+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:42736
2016-05-12 13:07:37.168+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:37.169+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:38.168+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:38.204+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-4153351162921734367.avsc
2016-05-12 13:07:38.208+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:38.208+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping7635088882553620179.groovy
2016-05-12 13:07:38.208+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:38.208+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:39.852+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:39.852+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:39.859+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:39.860+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:53085
2016-05-12 13:07:39.890+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:39.892+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:40.891+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:40.959+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-2721035816589558979.avsc
2016-05-12 13:07:40.964+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:40.965+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping6870921140589918488.groovy
2016-05-12 13:07:40.965+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:40.965+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:42.394+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:42.395+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:42.402+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:42.403+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:57826
2016-05-12 13:07:42.421+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:42.423+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:43.421+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:43.480+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-7145110439438768478.avsc
2016-05-12 13:07:43.484+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:43.484+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping6351243194578943092.groovy
2016-05-12 13:07:43.484+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:43.484+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:45.002+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:45.003+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:45.009+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:45.010+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:60109
2016-05-12 13:07:45.041+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:45.042+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:46.040+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:46.090+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-4699027657952023376.avsc
2016-05-12 13:07:46.094+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:46.095+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping6900900642204952726.groovy
2016-05-12 13:07:46.095+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:46.095+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:47.453+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:47.453+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:47.462+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:47.463+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:59639
2016-05-12 13:07:47.487+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:47.488+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:48.488+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:48.544+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-8913426729704261567.avsc
2016-05-12 13:07:48.549+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:48.550+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping7934681561992071729.groovy
2016-05-12 13:07:48.550+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:48.552+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:50.242+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:50.242+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:50.249+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:50.249+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:36956
2016-05-12 13:07:50.258+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:50.259+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:51.259+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:51.336+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-3974042983945648309.avsc
2016-05-12 13:07:51.340+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:51.340+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping6684597538797270401.groovy
2016-05-12 13:07:51.340+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:51.341+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:52.744+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:52.744+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:52.750+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:52.751+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:46088
2016-05-12 13:07:52.769+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:52.770+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:53.769+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:53.807+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-5867723254752321972.avsc
2016-05-12 13:07:53.811+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:53.811+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping7472074734409714114.groovy
2016-05-12 13:07:53.811+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:53.811+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:55.225+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:55.225+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:55.232+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:55.233+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:34998
2016-05-12 13:07:55.240+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:55.241+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:56.241+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:56.273+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-613464338387473171.avsc
2016-05-12 13:07:56.276+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:56.276+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping6934423249547989062.groovy
2016-05-12 13:07:56.276+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:56.278+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:57.639+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:07:57.639+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:07:57.645+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:07:57.645+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:47920
2016-05-12 13:07:57.971+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/geo-mapping7723478683577713815.groovy
2016-05-12 13:07:57.971+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:57.971+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:07:58.131+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:07:58.132+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:07:58.653+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:07:58.689+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-6153426831215162971.avsc
2016-05-12 13:07:58.693+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:07:58.693+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping3590198631654698924.groovy
2016-05-12 13:07:58.693+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:07:58.693+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:08:00.346+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:08:00.347+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:08:00.353+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:08:00.354+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:54123
2016-05-12 13:08:00.362+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:08:00.362+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:08:01.362+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:08:01.426+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-1859273231515674100.avsc
2016-05-12 13:08:01.429+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:08:01.430+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping3000087996957487444.groovy
2016-05-12 13:08:01.430+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:08:01.430+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:08:02.936+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:08:02.936+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:08:02.942+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:08:02.943+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:38005
2016-05-12 13:08:02.951+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:08:02.952+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:08:03.951+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:08:03.984+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-4967096161942427095.avsc
2016-05-12 13:08:03.988+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:08:03.988+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping333866951152848515.groovy
2016-05-12 13:08:03.988+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:08:03.988+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:08:05.338+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:08:05.338+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:08:05.344+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:08:05.345+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:49848
2016-05-12 13:08:05.353+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:08:05.354+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:08:06.353+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:08:06.382+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-6643399779905795572.avsc
2016-05-12 13:08:06.385+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:08:06.386+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping8687383180223195833.groovy
2016-05-12 13:08:06.386+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:08:06.386+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:08:07.690+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:08:07.691+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:08:07.697+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:08:07.697+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:55490
2016-05-12 13:08:07.711+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:08:07.712+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:08:08.711+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:08:08.744+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-1435084698276687840.avsc
2016-05-12 13:08:08.748+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:08:08.748+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping6391645850043326194.groovy
2016-05-12 13:08:08.748+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:08:08.748+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:08:10.053+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:08:10.053+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:08:10.059+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:08:10.060+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:52408
2016-05-12 13:08:10.067+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:08:10.068+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:08:11.067+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.
2016-05-12 13:08:11.101+02 [Test worker] INFO [IncomingRequestProcessingPool]: Using Avro schema from configuration: /tmp/TestSchema-4228027141586796115.avsc
2016-05-12 13:08:11.105+02 [Test worker] INFO [IncomingRequestProcessor]: Using script based schema mapping.
2016-05-12 13:08:11.105+02 [Test worker] INFO [DslRecordMapper]: Using mapping from script file: /tmp/test-mapping2893486854053620866.groovy
2016-05-12 13:08:11.105+02 [Test worker] INFO [UserAgentParserAndCache]: Using non-updating (resource module based) user agent parser.
2016-05-12 13:08:11.105+02 [Test worker] INFO [UserAgentParserAndCache]: User agent parser data version: 20141024-01
2016-05-12 13:08:12.731+02 [Test worker] INFO [Slf4jErrorManager]: 0 error(s), 0 warning(s), 87.7136258660508% typed
2016-05-12 13:08:12.731+02 [Test worker] INFO [JavaScriptResource]: Pre-compiled JavaScript source: divolte.js
2016-05-12 13:08:12.741+02 [Test worker] INFO [GzippableHttpBody]: Compressed resource: 9061 -> 4139
2016-05-12 13:08:12.741+02 [Test worker] INFO [Server]: Starting server on 127.0.0.1:51119
2016-05-12 13:08:12.754+02 [Test worker] INFO [Server]: Stopping HTTP server.
2016-05-12 13:08:12.755+02 [Test worker] INFO [Server]: Stopping thread pools.
2016-05-12 13:08:13.754+02 [Test worker] INFO [Server]: Closing HDFS filesystem connection.