22:48:26.840 [main] INFO  [CloudPoolRestApiImpl:<init>:102] - CloudPoolRestApiImpl created
22:48:26.840 [main] INFO  [CloudPoolServer:restoreConfig:169] - restoring cloudpool config from /home/simon/git/elastisys/scale.cloudpool/api/target/cloudpool/storage/config.json
22:48:26.929 [main] INFO  [Log:initialized:193] - Logging initialized @3097ms to org.eclipse.jetty.util.log.Slf4jLog
22:48:27.141 [main] DEBUG [ServletServerBuilder:createServletHandler:206] - adding servlet 'org.glassfish.jersey.servlet.ServletContainer@e822394' at context path '/' with path spec '/*' and init-params {}
22:48:27.149 [main] DEBUG [ServletServerBuilder:addCrossOriginFilter:113] - enabling CORS support
22:48:27.173 [main] INFO  [Server:doStart:371] - jetty-9.4.12.v20180830; built: 2018-08-30T13:59:14.071Z; git: 27208684755d94a92186989f695db2d7b21ebc51; jvm 11.0.2+9-Ubuntu-3ubuntu118.04.3
22:48:27.245 [main] INFO  [DefaultSessionIdManager:doStart:365] - DefaultSessionIdManager workerName=node0
22:48:27.245 [main] INFO  [DefaultSessionIdManager:doStart:370] - No SessionScavenger set, using defaults
22:48:27.249 [main] INFO  [HouseKeeper:startScavenging:149] - node0 Scavenging every 660000ms
22:48:27.988 [main] INFO  [ContextHandler:doStart:850] - Started o.e.j.s.ServletContextHandler@18bb7eca{/,null,AVAILABLE}
22:48:28.056 [main] INFO  [SslContextFactory:load:310] - x509=X509@55df76ac(1,h=[],w=[]) for SslContextFactory@7b6c6005[provider=null,keyStore=file:///home/simon/git/elastisys/scale.cloudpool/api/target/test-classes/security/server/server_keystore.p12,trustStore=file:///home/simon/git/elastisys/scale.cloudpool/api/target/test-classes/security/server/server_keystore.p12]
22:48:28.091 [main] INFO  [AbstractConnector:doStart:292] - Started ServerConnector@1f1d7c52{SSL,[ssl, http/1.1]}{0.0.0.0:37541}
22:48:28.091 [main] INFO  [Server:doStart:408] - Started @4259ms
22:48:28.202 [main] DEBUG [Http:execute:82] - sending request (GET https://localhost:37541/pool/size HTTP/1.1)
22:48:28.647 [qtp343016386-21] INFO  [RequestLogFilter:filter:26] - GET https://localhost:37541/pool/size by 127.0.0.1
22:48:28.712 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/config HTTP/1.1)
22:48:28.759 [qtp343016386-16] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/config by 127.0.0.1
22:48:28.770 [qtp343016386-16] ERROR [CloudPoolRestApiImpl:setConfig:154] - illegal input: bad config!
java.lang.IllegalArgumentException: bad config!
	at com.elastisys.scale.cloudpool.api.restapi.impl.CloudPoolRestApiImpl.setConfig(CloudPoolRestApiImpl.java:149)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	at org.glassfish.jersey.server.model.internal.ResourceMethodInvocationHandlerFactory$1.invoke(ResourceMethodInvocationHandlerFactory.java:81)
	at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher$1.run(AbstractJavaResourceMethodDispatcher.java:144)
	at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.invoke(AbstractJavaResourceMethodDispatcher.java:161)
	at org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$ResponseOutInvoker.doDispatch(JavaResourceMethodDispatcherProvider.java:160)
	at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.dispatch(AbstractJavaResourceMethodDispatcher.java:99)
	at org.glassfish.jersey.server.model.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:389)
	at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:347)
	at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:102)
	at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:326)
	at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271)
	at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267)
	at org.glassfish.jersey.internal.Errors.process(Errors.java:315)
	at org.glassfish.jersey.internal.Errors.process(Errors.java:297)
	at org.glassfish.jersey.internal.Errors.process(Errors.java:267)
	at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317)
	at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305)
	at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154)
	at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473)
	at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427)
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388)
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341)
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228)
	at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:865)
	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1655)
	at org.eclipse.jetty.servlets.CrossOriginFilter.handle(CrossOriginFilter.java:311)
	at org.eclipse.jetty.servlets.CrossOriginFilter.doFilter(CrossOriginFilter.java:265)
	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642)
	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1340)
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1242)
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
	at org.eclipse.jetty.server.handler.HandlerList.handle(HandlerList.java:61)
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
	at org.eclipse.jetty.server.Server.handle(Server.java:503)
	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364)
	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305)
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
	at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:411)
	at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:305)
	at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:159)
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
	at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333)
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310)
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168)
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:132)
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765)
	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683)
	at java.base/java.lang.Thread.run(Thread.java:834)
22:48:28.778 [main] DEBUG [Http:execute:82] - sending request (GET https://localhost:37541/pool HTTP/1.1)
22:48:28.816 [qtp343016386-22] INFO  [RequestLogFilter:filter:26] - GET https://localhost:37541/pool by 127.0.0.1
22:48:28.848 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/config HTTP/1.1)
22:48:28.893 [qtp343016386-21] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/config by 127.0.0.1
22:48:28.908 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/pool/terminate HTTP/1.1)
22:48:28.969 [qtp343016386-17] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/pool/terminate by 127.0.0.1
22:48:28.986 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/pool/detach HTTP/1.1)
22:48:29.030 [qtp343016386-22] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/pool/detach by 127.0.0.1
22:48:29.037 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/pool/membershipStatus HTTP/1.1)
22:48:29.076 [qtp343016386-23] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/pool/membershipStatus by 127.0.0.1
22:48:29.082 [main] DEBUG [Http:execute:82] - sending request (GET https://localhost:37541/config HTTP/1.1)
22:48:29.113 [qtp343016386-21] INFO  [RequestLogFilter:filter:26] - GET https://localhost:37541/config by 127.0.0.1
22:48:29.119 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/pool/serviceState HTTP/1.1)
22:48:29.157 [qtp343016386-17] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/pool/serviceState by 127.0.0.1
22:48:29.166 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/stop HTTP/1.1)
22:48:29.199 [qtp343016386-22] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/stop by 127.0.0.1
22:48:29.203 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/pool/membershipStatus HTTP/1.1)
22:48:29.235 [qtp343016386-23] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/pool/membershipStatus by 127.0.0.1
22:48:29.246 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/pool/attach HTTP/1.1)
22:48:29.275 [qtp343016386-23] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/pool/attach by 127.0.0.1
22:48:29.281 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/start HTTP/1.1)
22:48:29.318 [qtp343016386-17] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/start by 127.0.0.1
22:48:29.322 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/pool/serviceState HTTP/1.1)
22:48:29.341 [qtp343016386-22] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/pool/serviceState by 127.0.0.1
22:48:29.346 [main] DEBUG [Http:execute:82] - sending request (GET https://localhost:37541/config HTTP/1.1)
22:48:29.364 [qtp343016386-23] INFO  [RequestLogFilter:filter:26] - GET https://localhost:37541/config by 127.0.0.1
22:48:29.368 [main] DEBUG [Http:execute:82] - sending request (GET https://localhost:37541/status HTTP/1.1)
22:48:29.388 [qtp343016386-17] INFO  [RequestLogFilter:filter:26] - GET https://localhost:37541/status by 127.0.0.1
22:48:29.392 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/pool/attach HTTP/1.1)
22:48:29.414 [qtp343016386-23] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/pool/attach by 127.0.0.1
22:48:29.420 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/pool/terminate HTTP/1.1)
22:48:29.441 [qtp343016386-22] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/pool/terminate by 127.0.0.1
22:48:29.445 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/pool/size HTTP/1.1)
22:48:29.471 [qtp343016386-17] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/pool/size by 127.0.0.1
22:48:29.478 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/pool/detach HTTP/1.1)
22:48:29.507 [qtp343016386-23] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/pool/detach by 127.0.0.1
22:48:29.514 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/pool/size HTTP/1.1)
22:48:29.538 [qtp343016386-22] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/pool/size by 127.0.0.1
22:48:29.547 [main] DEBUG [Http:execute:82] - sending request (POST https://localhost:37541/start HTTP/1.1)
22:48:29.582 [qtp343016386-17] INFO  [RequestLogFilter:filter:26] - POST https://localhost:37541/start by 127.0.0.1
22:48:29.583 [qtp343016386-17] ERROR [CloudPoolRestApiImpl:start:169] - no config set!
22:48:29.593 [main] INFO  [AbstractConnector:doStop:341] - Stopped ServerConnector@1f1d7c52{SSL,[ssl, http/1.1]}{0.0.0.0:37541}
22:48:29.594 [main] INFO  [HouseKeeper:stopScavenging:167] - node0 Stopped scavenging
22:48:29.601 [main] INFO  [ContextHandler:doStop:1040] - Stopped o.e.j.s.ServletContextHandler@18bb7eca{/,null,UNAVAILABLE}
