search

Nuix / Nuix-Java-Engine-Baseline

A Java project which can be adapted as a starting point for development against the Nuix Java Engine API
Apache License 2.0
7 stars 3 forks source link

Execution hangs after iterating over available server licenses #8

Closed chrishaupt closed 3 years ago

chrishaupt commented 4 years ago

Using your EngineWrapper (with the Nuix Engine v8.4.6.866) to obtain licenses from our NMS the execution hangs for close to a minute after this code section:

https://github.com/Nuix/Nuix-Java-Engine-Baseline/blob/28d8d2f9fa02fda90a8aa8fd6911211d5a0b8018/Java/src/main/java/com/nuix/javaenginesimple/EngineWrapper.java#L350-L353

it seems that the executions stopps after the programm iterated over all available licenses on the server.

when I set the logging level to debug. I get the following debug messages after the last license where summarised:

2020-07-24 11:40:03 DEBUG com.nuix.util.net.f:a():82 - Resolving for _nuix2._tcp...
2020-07-24 11:40:24 DEBUG com.nuix.util.net.f:a():100 - No results for _nuix2._tcp

after that the executions continues without problem and a license is obtained. when is comment the code section above and don't iterate to the last license these Debug messages won't appear.

what is Nuix doing in the background and how can I stop it?

JuicyDragon commented 4 years ago

Hello @chrishaupt are you calling this method https://github.com/Nuix/Nuix-Java-Engine-Baseline/blob/28d8d2f9fa02fda90a8aa8fd6911211d5a0b8018/Java/src/main/java/com/nuix/javaenginesimple/EngineWrapper.java#L141 or this method? https://github.com/Nuix/Nuix-Java-Engine-Baseline/blob/28d8d2f9fa02fda90a8aa8fd6911211d5a0b8018/Java/src/main/java/com/nuix/javaenginesimple/EngineWrapper.java#L156

If you are using the first one, can you please try using the second method. The difference is the second method accepts a String argument server which can be an IP address/hostname and port for the machine on which the license server service is running. My suspicion is you may be using the first method in which case the engine is attempting to automatically discover where the license server may be located on the network. If you tell it where to look and it can find the specified license server I suspect the license acquisition may go smoother.

JuicyDragon commented 3 years ago

I am going to close this issue. If you are still experiencing this problem feel free to let me know and we can open this back up.

chrishaupt commented 3 years ago

Hi Jason,

thanks for your reply. Last year unfortunately I didn't got around to this project until now. I tried your solution, but the problem stays the same.

I used this function

https://github.com/Nuix/Nuix-Java-Engine-Baseline/blob/28d8d2f9fa02fda90a8aa8fd6911211d5a0b8018/Java/src/main/java/com/nuix/javaenginesimple/EngineWrapper.java#L156

and called it in the two following ways:

nuixEngineWrapper.withServerLicense(config.nuixConnection.host+":"+config.nuixConnection.port, config.nuixConnection.user, config.nuixConnection.pass, util);

and

nuixEngineWrapper.withServerLicense(config.nuixConnection.host, config.nuixConnection.user, config.nuixConnection.pass, util);

Below you find the Nuix.log from start of the application until the licenses are enumerated. You can see that com.nuix.util.net still tries to resolve _nuix2._tcp (for round about 1:30 minutes):

C:\Users\.jdks\adopt-openjdk-11.0.12\bin\java.exe -Dnuix.libdir=C:\Nuix\engine-dist-win32-amd64-9.2.2.340\lib -Dlog4j.configurationFile=C:\Users\\IdeaProjects\nuix-kafka-casemaker\resources\log4j2-nuix-9.yml -Dlog4j.debug "-Dnuix.logdir=C:\Nuix\engine-dist-win32-amd64-9.2.2.340\logs\" "-javaagent:C:\Program Files\JetBrains\IntelliJ IDEA 2020.3.3\lib\idea_rt.jar=41375:C:\Program Files\JetBrains\IntelliJ IDEA 2020.3.3\bin" -Dfile.encoding=UTF-8 -classpath C:\Users\\AppData\Local\Temp\classpath841222725.jar de.sva.nuix.api.CreateCase C:\Users\\IdeaProjects\nuix-kafka-casemaker\caseParams.json C:\Nuix\CaseFolder TestCase C:\Nuix_Evidence\Office_Files 2021-08-04 14:33:42,968 main WARN Unable to instantiate org.fusesource.jansi.WindowsAnsiOutputStream 2021-08-04 14:33:43.688 +0200 [main] 4832 INFO de.sva.nuix.api.EngineWrapper - Setting 'nuix.libdir' to: C:\Nuix\engine-dist-win32-amd64-9.2.2.340\lib 2021-08-04 14:33:43.698 +0200 [main] 4842 INFO de.sva.nuix.api.EngineWrapper - Creating GlobalContainer... 2021-08-04 14:33:44.998 +0200 [main] 6142 INFO com.nuix.util.concurrent.d - Initialising with 2 IO threads 2021-08-04 14:33:44.998 +0200 [main] 6142 INFO com.nuix.util.concurrent.d - Initialising with 4 store threads 2021-08-04 14:33:45.457 +0200 [main] 6601 INFO com.nuix.storage.lowlevel.graph.a - Setting un-specified OrientDB System property 'memory.leftToOS' to Nuix default of '90%'. 2021-08-04 14:33:45.458 +0200 [main] 6602 INFO com.nuix.storage.lowlevel.graph.a - Setting un-specified OrientDB System property 'memory.useUnsafe' to Nuix default of 'false'. 2021-08-04 14:33:45.458 +0200 [main] 6602 INFO com.nuix.storage.lowlevel.graph.a - Setting un-specified OrientDB System property 'storage.compressionMethod' to Nuix default of 'gzip'. 2021-08-04 14:33:45.492 +0200 [main] 6636 DEBUG com.nuix.monitoring.a - Adding monitor name 'TempDirectory' for directory 'C:\Users\\AppData\Local\Temp' 2021-08-04 14:33:45.499 +0200 [main] 6643 DEBUG com.nuix.monitoring.a - Adding monitor name 'LogDirectory' for directory 'C:\Nuix\engine-dist-win32-amd64-9.2.2.340\logs' 2021-08-04 14:33:45.710 +0200 [main] 6854 INFO de.sva.nuix.api.EngineWrapper - Initializing engine.... 2021-08-04 14:33:45.710 +0200 [main] 6854 INFO de.sva.nuix.api.EngineWrapper - Building engine instance... 2021-08-04 14:33:45.710 +0200 [main] 6854 INFO de.sva.nuix.api.EngineWrapper - Engine initialization settings: 2021-08-04 14:33:45.710 +0200 [main] 6854 INFO de.sva.nuix.api.EngineWrapper - registry.servers.port: null 2021-08-04 14:33:45.710 +0200 [main] 6854 INFO de.sva.nuix.api.EngineWrapper - registry.servers: null 2021-08-04 14:33:45.710 +0200 [main] 6854 INFO de.sva.nuix.api.EngineWrapper - nuix.logdir: C:\Nuix\engine-dist-win32-amd64-9.2.2.340\logs\ 2021-08-04 14:33:45.710 +0200 [main] 6854 INFO de.sva.nuix.api.EngineWrapper - user: username 2021-08-04 14:33:45.710 +0200 [main] 6854 INFO de.sva.nuix.api.EngineWrapper - userDataDirs: C:\Nuix\engine-dist-win32-amd64-9.2.2.340\user-data 2021-08-04 14:33:45.837 +0200 [main] 6981 DEBUG com.nuix.usersession.session.d - Resolving service providers for SessionContainerBootstrapProvider 2021-08-04 14:33:45.847 +0200 [main] 6991 DEBUG com.nuix.usersession.session.d - Found service provider: com.nuix.monitoring.SessionContainerBootstrapProviderImpl 2021-08-04 14:33:45.867 +0200 [main] 7011 DEBUG com.nuix.usersession.session.d - Found service provider: com.nuix.processing.SessionContainerBootstrapProviderImpl 2021-08-04 14:33:45.968 +0200 [main] 7112 DEBUG com.nuix.usersession.session.d - Found service provider: com.nuix.storage.SessionContainerBootstrapProviderImpl 2021-08-04 14:33:46.074 +0200 [main] 7218 DEBUG com.nuix.usersession.session.d - Found service provider: com.nuix.usersession.SessionContainerBootstrapProviderImpl 2021-08-04 14:33:46.411 +0200 [main] 7555 INFO de.sva.nuix.api.EngineWrapper - Obtained Engine instance v9.2.2.340 2021-08-04 14:33:46.411 +0200 [main] 7555 INFO de.sva.nuix.api.EngineWrapper - Specifying credentials to use with license server... 2021-08-04 14:33:46.411 +0200 [main] 7555 INFO de.sva.nuix.api.EngineWrapper - Attempting to obtain a license... 2021-08-04 14:33:46.411 +0200 [main] 7555 INFO de.sva.nuix.api.EngineWrapper - Obtaining licensor.... 2021-08-04 14:33:46.427 +0200 [main] 7571 INFO de.sva.nuix.api.EngineWrapper - Finding licences using options: 2021-08-04 14:33:46.427 +0200 [main] 7571 INFO de.sva.nuix.api.EngineWrapper - sources: server 2021-08-04 14:33:46.427 +0200 [main] 7571 INFO de.sva.nuix.api.EngineWrapper - Iterating available licences... 2021-08-04 14:33:46.527 +0200 [main] 7671 DEBUG com.nuix.server.api.c - System property nuix.registry.servers = 192.168.0.100:27443 WARNING: An illegal reflective access operation has occurred WARNING: Illegal reflective access by net.sf.cglib.core.ReflectUtils$1 (file:/C:/Nuix/engine-dist-win32-amd64-9.2.2.340/lib/cglib-nodep-3.3.0.jar) to method java.lang.ClassLoader.defineClass(java.lang.String,byte[],int,int,java.security.ProtectionDomain) WARNING: Please consider reporting this to the maintainers of net.sf.cglib.core.ReflectUtils$1 WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations WARNING: All illegal access operations will be denied in a future release 2021-08-04 14:33:47.513 +0200 [main] 8657 DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: standard 2021-08-04 14:33:47.545 +0200 [main] 8689 DEBUG org.apache.http.client.protocol.RequestAddCookies - Cookie [version: 0][name: JSESSIONID][value: node01jreg2jbxwzrmo9cvdeed0rgw232.node0][domain: 192.168.0.100][path: /][expiry: null] match [(secure)192.168.0.100:27443/api/nurpc/v9/licences/listAvailableLicences] 2021-08-04 14:33:47.560 +0200 [main] 8704 DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context 2021-08-04 14:33:47.560 +0200 [main] 8704 DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection request: [route: {s}->https://192.168.0.100:27443][total available: 0; route allocated: 0 of 2; total allocated: 0 of 20] 2021-08-04 14:33:47.594 +0200 [main] 8738 DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection leased: [id: 0][route: {s}->https://192.168.0.100:27443][total available: 0; route allocated: 1 of 2; total allocated: 1 of 20] 2021-08-04 14:33:47.594 +0200 [main] 8738 DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {s}->https://192.168.0.100:27443 2021-08-04 14:33:47.610 +0200 [main] 8754 DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to /192.168.0.100:27443 2021-08-04 14:33:47.610 +0200 [main] 8754 DEBUG com.nuix.util.net.http.b - Connecting socket to /192.168.0.100:27443 with timeout 30000 2021-08-04 14:33:47.692 +0200 [main] 8836 DEBUG com.nuix.util.net.http.b - Enabled protocols: [TLSv1.3, TLSv1.2] 2021-08-04 14:33:47.692 +0200 [main] 8836 DEBUG com.nuix.util.net.http.b - Enabled cipher suites:[TLS_AES_128_GCM_SHA256, TLS_AES_256_GCM_SHA384, TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDH_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDH_RSA_WITH_AES_256_GCM_SHA384, TLS_DHE_RSA_WITH_AES_256_GCM_SHA384, TLS_DHE_DSS_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_DSS_WITH_AES_128_GCM_SHA256, TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA384, TLS_ECDH_RSA_WITH_AES_256_CBC_SHA384, TLS_DHE_RSA_WITH_AES_256_CBC_SHA256, TLS_DHE_DSS_WITH_AES_256_CBC_SHA256, TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA, TLS_ECDH_RSA_WITH_AES_256_CBC_SHA, TLS_DHE_RSA_WITH_AES_256_CBC_SHA, TLS_DHE_DSS_WITH_AES_256_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_DSS_WITH_AES_128_CBC_SHA256, TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_DSS_WITH_AES_128_CBC_SHA, TLS_EMPTY_RENEGOTIATION_INFO_SCSV] 2021-08-04 14:33:47.692 +0200 [main] 8836 DEBUG com.nuix.util.net.http.b - Starting handshake 2021-08-04 14:33:48.081 +0200 [main] 9225 INFO de.sva.nuix.api.EngineWrapper - Trusting certificate blindly! 2021-08-04 14:33:48.178 +0200 [main] 9322 DEBUG com.nuix.util.net.http.b - Secure session established 2021-08-04 14:33:48.178 +0200 [main] 9322 DEBUG com.nuix.util.net.http.b - negotiated protocol: TLSv1.2 2021-08-04 14:33:48.178 +0200 [main] 9322 DEBUG com.nuix.util.net.http.b - negotiated cipher suite: TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384 2021-08-04 14:33:48.178 +0200 [main] 9322 DEBUG com.nuix.util.net.http.b - peer principal: CN=192.168.0.100 2021-08-04 14:33:48.178 +0200 [main] 9322 DEBUG com.nuix.util.net.http.b - peer alternative names: [192.168.0.100] 2021-08-04 14:33:48.178 +0200 [main] 9322 DEBUG com.nuix.util.net.http.b - issuer principal: CN=192.168.0.100 2021-08-04 14:33:48.178 +0200 [main] 9322 DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 10.206.15.92:41383<->192.168.0.100:27443 2021-08-04 14:33:48.178 +0200 [main] 9322 DEBUG org.apache.http.impl.conn.DefaultManagedHttpClientConnection - http-outgoing-0: set socket timeout to 30000 2021-08-04 14:33:48.178 +0200 [main] 9322 DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request POST /api/nurpc/v9/licences/listAvailableLicences HTTP/1.1 2021-08-04 14:33:48.178 +0200 [main] 9322 DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.headers - http-outgoing-0 >> POST /api/nurpc/v9/licences/listAvailableLicences HTTP/1.1 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.headers - http-outgoing-0 >> Accept: application/json; charset=UTF-8 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.headers - http-outgoing-0 >> Transfer-Encoding: chunked 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.headers - http-outgoing-0 >> Content-Type: application/json; charset=UTF-8 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.headers - http-outgoing-0 >> Host: 192.168.0.100:27443 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.headers - http-outgoing-0 >> Connection: Keep-Alive 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.headers - http-outgoing-0 >> User-Agent: Nuix/9.2.2.340 (Windows 10.0; de_DE) 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.headers - http-outgoing-0 >> Cookie: JSESSIONID=node01jreg2jbxwzrmo9cvdeed0rgw232.node0 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.headers - http-outgoing-0 >> Accept-Encoding: gzip,deflate 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.wire - http-outgoing-0 >> "POST /api/nurpc/v9/licences/listAvailableLicences HTTP/1.1[\r][\n]" 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.wire - http-outgoing-0 >> "Accept: application/json; charset=UTF-8[\r][\n]" 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.wire - http-outgoing-0 >> "Transfer-Encoding: chunked[\r][\n]" 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.wire - http-outgoing-0 >> "Content-Type: application/json; charset=UTF-8[\r][\n]" 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.wire - http-outgoing-0 >> "Host: 192.168.0.100:27443[\r][\n]" 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.wire - http-outgoing-0 >> "Connection: Keep-Alive[\r][\n]" 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.wire - http-outgoing-0 >> "User-Agent: Nuix/9.2.2.340 (Windows 10.0; de_DE)[\r][\n]" 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.wire - http-outgoing-0 >> "Cookie: JSESSIONID=node01jreg2jbxwzrmo9cvdeed0rgw232.node0[\r][\n]" 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.wire - http-outgoing-0 >> "Accept-Encoding: gzip,deflate[\r][\n]" 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.wire - http-outgoing-0 >> "[\r][\n]" 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.wire - http-outgoing-0 >> "11[\r][\n]" 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.wire - http-outgoing-0 >> "{"params":[null]}[\r][\n]" 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.wire - http-outgoing-0 >> "0[\r][\n]" 2021-08-04 14:33:48.194 +0200 [main] 9338 DEBUG org.apache.http.wire - http-outgoing-0 >> "[\r][\n]" 2021-08-04 14:33:48.243 +0200 [main] 9387 DEBUG org.apache.http.wire - http-outgoing-0 << "HTTP/1.1 200 OK[\r][\n]" 2021-08-04 14:33:48.243 +0200 [main] 9387 DEBUG org.apache.http.wire - http-outgoing-0 << "Date: Wed, 04 Aug 2021 12:33:48 GMT[\r][\n]" 2021-08-04 14:33:48.243 +0200 [main] 9387 DEBUG org.apache.http.wire - http-outgoing-0 << "Content-Type: application/json;charset=utf-8[\r][\n]" 2021-08-04 14:33:48.243 +0200 [main] 9387 DEBUG org.apache.http.wire - http-outgoing-0 << "Content-Length: 3263[\r][\n]" 2021-08-04 14:33:48.243 +0200 [main] 9387 DEBUG org.apache.http.wire - http-outgoing-0 << "[\r][\n]" 2021-08-04 14:33:48.243 +0200 [main] 9387 DEBUG org.apache.http.wire - http-outgoing-0 << "{"result":{"availableProfiles":[{"description":"Nuix eDiscovery Workstation","name":"enterprise-workstation","properties":{"profile.features.plus":"INVESTIGATOR, ELASTIC_SEARCH, PHOTO_DNA, SENSITIVE_DATA_FINDER, OPEN_AUDITED_CASES","profile":"enterprise-workstation-with-automatic-classifier","id":"SVADEMO001","deadline":"20211130000000"},"remaining":6,"remainingWorkers":50,"requiresWorkers":true,"totalCount":0,"totalWorkerCount":0}]}}[\n]" 2021-08-04 14:33:48.243 +0200 [main] 9387 DEBUG org.apache.http.wire - http-outgoing-0 << "[\n]" 2021-08-04 14:33:48.253 +0200 [main] 9397 DEBUG org.apache.http.headers - http-outgoing-0 << HTTP/1.1 200 OK 2021-08-04 14:33:48.253 +0200 [main] 9397 DEBUG org.apache.http.headers - http-outgoing-0 << Date: Wed, 04 Aug 2021 12:33:48 GMT 2021-08-04 14:33:48.253 +0200 [main] 9397 DEBUG org.apache.http.headers - http-outgoing-0 << Content-Type: application/json;charset=utf-8 2021-08-04 14:33:48.253 +0200 [main] 9397 DEBUG org.apache.http.headers - http-outgoing-0 << Content-Length: 3263 2021-08-04 14:33:48.253 +0200 [main] 9397 DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely 2021-08-04 14:33:48.314 +0200 [main] 9458 DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection [id: 0][route: {s}->https://192.168.0.100:27443] can be kept alive indefinitely 2021-08-04 14:33:48.314 +0200 [main] 9458 DEBUG org.apache.http.impl.conn.DefaultManagedHttpClientConnection - http-outgoing-0: set socket timeout to 0 2021-08-04 14:33:48.314 +0200 [main] 9458 DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection released: [id: 0][route: {s}->https://192.168.0.100:27443][total available: 1; route allocated: 1 of 2; total allocated: 1 of 20] 2021-08-04 14:33:48.365 +0200 [main] 9509 DEBUG com.nuix.product.l - Null audit type encountered. 2021-08-04 14:33:48.365 +0200 [main] 9509 DEBUG com.nuix.product.l - Null audit type encountered. 2021-08-04 14:33:48.365 +0200 [main] 9509 DEBUG com.nuix.product.l - Null audit type encountered. 2021-08-04 14:33:48.365 +0200 [main] 9509 DEBUG com.nuix.product.l - Null audit type encountered. 2021-08-04 14:33:48.365 +0200 [main] 9509 DEBUG com.nuix.product.l - Null audit type encountered. 2021-08-04 14:33:48.365 +0200 [main] 9509 DEBUG com.nuix.product.l - Null audit type encountered. 2021-08-04 14:33:48.365 +0200 [main] 9509 DEBUG com.nuix.product.l - Null audit type encountered. 2021-08-04 14:33:48.365 +0200 [main] 9509 DEBUG com.nuix.product.l - Null audit type encountered. 2021-08-04 14:33:48.365 +0200 [main] 9509 DEBUG com.nuix.product.l - Null audit type encountered. 2021-08-04 14:33:48.365 +0200 [main] 9509 DEBUG com.nuix.product.l - Null audit type encountered. 2021-08-04 14:33:48.396 +0200 [main] 9540 DEBUG com.nuix.util.net.f - Resolving for _nuix2._tcp... 2021-08-04 14:33:58.575 +0200 [main] 19719 DEBUG com.nuix.util.net.f - No results for _nuix2._tcp 2021-08-04 14:34:25.213 +0200 [main] 46357 INFO de.sva.nuix.api.EngineWrapper - [192.168.0.100:27443/server/enterprise-workstation, Nuix eDiscovery Workstation, Count:6, Workers: 50, Features: ANALYSIS; AUTOMATIC_CLASSIFIER_EDITING; CASE_CREATION; CUSTOM_NAMED_ENTITIES; CYBER_CONTEXT; DESKTOP; ELASTIC_SEARCH; EXCHANGE_WS; EXPORT_CASE_SUBSET; EXPORT_DISCOVER; EXPORT_ITEMS; EXPORT_LEGAL; EXPORT_SINGLE_ITEM; EXPORT_VIEW; FAST_REVIEW; GENERAL_DATA; GRAPH; IMAP_POP; LOG_STASH; LOTUS_NOTES; METADATA_IMPORT; MOBILE_DEVICE_IMAGING; NETWORK_DATA; OCR_PROCESSING; OTHER_EMAIL; OUTLOOK; OUTLOOK_EXPRESS; PARTIAL_LOAD; PRODUCTION_SET; SCRIPTING; WORKER; WORKER_SCRIPTING]

JuicyDragon commented 3 years ago

Hello @chrishaupt, looking into this a bit I noticed this change in 9.2 which I had not noticed until now: image I found an internal discussion regarding this and it appears the method findAvailableLicencesStream was added because something about the Iterable returned by the (now deprecated) method findAvailableLicences was causing it to seek out licenses beyond what you had requested and even after you have picked a license to acquire.

I suspect this is what you may seeing, the Licensor checking additional possible sources even though it shouldn't, causing a delay at initialization. I'm working on updating the repository to support engine release 9.2, including this change. There were some other things that needed to be updated as well so the changes are taking a little bit of time. You can try swapping in that new method on your end if you want to try this out before I push my changes up, or I should hopefully have my updates pushed up soon and you can take from that 😄

chrishaupt commented 3 years ago

Hi Jason,

thanks for looking into the issue! I will wait for the updated version of the EngineWrapper. Do you think the updated version will still be downward compatible to Nuix Engine v8?

JuicyDragon commented 3 years ago

Hello @chrishaupt I just pushed the updates and merged into master (d300bb5dfda343d49794f0d8f14aa84558618dc8). I don't think this will work with versions prior to 9.2 really. The available license stream bit is new to 9.2 and I changed a bunch of the logging to now user log4j2. These changes are different enough I don't think the older engine releases would work against it unfortunately.

chrishaupt commented 3 years ago

Hi @JuicyDragon,

the issue was solved by your last merge. Thank you!