[JIRA] (JJDBC-5) Unable to install JDBC Plugin in idp4.1.0 or 4.2.1

Wed Jun 22 20:45:52 UTC 2022

Vivien Wu ( https://shibboleth.atlassian.net/secure/ViewProfile.jspa?accountId=5f0e4b291a26ad001434d48d ) *created* an issue

Plugin - JDBC StorageService ( https://shibboleth.atlassian.net/browse/JJDBC?atlOrigin=eyJpIjoiNGVhN2MwOWE2YmExNGYzYWE0ZGUxNmQ3MTk4ZTNlY2MiLCJwIjoiaiJ9 ) / Bug ( https://shibboleth.atlassian.net/browse/JJDBC-5?atlOrigin=eyJpIjoiNGVhN2MwOWE2YmExNGYzYWE0ZGUxNmQ3MTk4ZTNlY2MiLCJwIjoiaiJ9 ) JJDBC-5 ( https://shibboleth.atlassian.net/browse/JJDBC-5?atlOrigin=eyJpIjoiNGVhN2MwOWE2YmExNGYzYWE0ZGUxNmQ3MTk4ZTNlY2MiLCJwIjoiaiJ9 ) Unable to install JDBC Plugin in idp4.1.0 or 4.2.1 ( https://shibboleth.atlassian.net/browse/JJDBC-5?atlOrigin=eyJpIjoiNGVhN2MwOWE2YmExNGYzYWE0ZGUxNmQ3MTk4ZTNlY2MiLCJwIjoiaiJ9 )

Issue Type: Bug Affects Versions: 1.0.0 Assignee: Rod Widdowson ( https://shibboleth.atlassian.net/secure/ViewProfile.jspa?accountId=59fb32bbc24efb3c4ed3c977 ) Created: 22/Jun/22 4:45 PM Priority: Trivial Reporter: Vivien Wu ( https://shibboleth.atlassian.net/secure/ViewProfile.jspa?accountId=5f0e4b291a26ad001434d48d )

--------------------------------------
Perhaps I’m missing something obvious.
--------------------------------------

Per slack channel, a verbose output as follows,

#31 34.03 2022-06-22 19:44:37,266 - DEBUG [net.shibboleth.utilities.java.support.httpclient.TLSSocketFactory:346] - Selected cipher suite: TLS_AES_256_GCM_SHA384
#31 34.03 2022-06-22 19:44:37,267 - DEBUG [org.apache.http.impl.conn.DefaultHttpClientConnectionOperator:146] - Connection established 172.17.0.2:60918<->3.213.250.186:443
#31 34.03 2022-06-22 19:44:37,268 - DEBUG [org.apache.http.impl.conn.DefaultManagedHttpClientConnection:88] - http-outgoing-1: set socket timeout to 60000
#31 34.03 2022-06-22 19:44:37,269 - DEBUG [org.apache.http.impl.execchain.MainClientExec:255] - Executing request GET /downloads/identity-provider/plugins/jdbc/1.0.0/java-plugin-jdbc-storage-1.0.0.tar.gz.asc HTTP/1.1
#31 34.03 2022-06-22 19:44:37,269 - DEBUG [org.apache.http.impl.execchain.MainClientExec:260] - Target auth state: UNCHALLENGED
#31 34.03 2022-06-22 19:44:37,269 - DEBUG [org.apache.http.impl.execchain.MainClientExec:266] - Proxy auth state: UNCHALLENGED
#31 34.04 2022-06-22 19:44:37,270 - DEBUG [org.apache.http.headers:133] - http-outgoing-1 >> GET /downloads/identity-provider/plugins/jdbc/1.0.0/java-plugin-jdbc-storage-1.0.0.tar.gz.asc HTTP/1.1
#31 34.04 2022-06-22 19:44:37,271 - DEBUG [org.apache.http.headers:136] - http-outgoing-1 >> Host: http://shibboleth.net
#31 34.04 2022-06-22 19:44:37,272 - DEBUG [org.apache.http.headers:136] - http-outgoing-1 >> Connection: Keep-Alive
#31 34.04 2022-06-22 19:44:37,272 - DEBUG [org.apache.http.headers:136] - http-outgoing-1 >> User-Agent: ShibbolethIdp/4.2.1 OpenSAML/4.2.0
#31 34.04 2022-06-22 19:44:37,272 - DEBUG [org.apache.http.headers:136] - http-outgoing-1 >> Accept-Encoding: gzip,deflate
#31 34.04 2022-06-22 19:44:37,272 - DEBUG [org.apache.http.headers:136] - http-outgoing-1 >> Connection: close
#31 34.04 2022-06-22 19:44:37,274 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 >> "GET /downloads/identity-provider/plugins/jdbc/1.0.0/java-plugin-jdbc-storage-1.0.0.tar.gz.asc HTTP/1.1[\\r][
n]"
#31 34.04 2022-06-22 19:44:37,274 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 >> "Host: http://shibboleth.net [\\r][
n]"
#31 34.04 2022-06-22 19:44:37,274 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 >> "Connection: Keep-Alive[\\r][
n]"
#31 34.04 2022-06-22 19:44:37,274 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 >> "User-Agent: ShibbolethIdp/4.2.1 OpenSAML/4.2.0[\\r][
n]"
#31 34.04 2022-06-22 19:44:37,274 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 >> "Accept-Encoding: gzip,deflate[\\r][
n]"
#31 34.04 2022-06-22 19:44:37,274 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 >> "Connection: close[\\r][
n]"
#31 34.04 2022-06-22 19:44:37,274 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 >> "[\\r][
n]"
#31 34.12 2022-06-22 19:44:37,351 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "HTTP/1.1 200 OK[\\r][
n]"
#31 34.12 2022-06-22 19:44:37,353 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "Date: Wed, 22 Jun 2022 19:44:37 GMT[\\r][
n]"
#31 34.12 2022-06-22 19:44:37,355 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "Server: Apache/2.4.37 (rocky) OpenSSL/1.1.1k[\\r][
n]"
#31 34.12 2022-06-22 19:44:37,356 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "Last-Modified: Tue, 14 Jun 2022 15:09:49 GMT[\\r][
n]"
#31 34.12 2022-06-22 19:44:37,356 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "ETag: "344-5e169cbbe2488"[\\r][
n]"
#31 34.12 2022-06-22 19:44:37,356 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "Accept-Ranges: bytes[\\r][
n]"
#31 34.12 2022-06-22 19:44:37,357 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "Content-Length: 836[\\r][
n]"
#31 34.12 2022-06-22 19:44:37,357 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "Vary: User-Agent[\\r][
n]"
#31 34.12 2022-06-22 19:44:37,357 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "Connection: close[\\r][
n]"
#31 34.12 2022-06-22 19:44:37,357 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "Content-Type: text/plain; charset=UTF-8[\\r][
n]"
#31 34.12 2022-06-22 19:44:37,357 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "[\\r][
n]"
#31 34.12 2022-06-22 19:44:37,357 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "---- BEGIN PGP SIGNATURE ----[
n]"
#31 34.12 2022-06-22 19:44:37,357 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "Version: GnuPG v2.0.22 (GNU/Linux)[
n]"
#31 34.12 2022-06-22 19:44:37,357 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "[
n]"
#31 34.12 2022-06-22 19:44:37,357 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "iQIcBAABAgAGBQJiqJnMAAoJEAFIPyYqSz/wEAcQAKW1COezgBhiedYi/jZpLej1[
n]"
#31 34.12 2022-06-22 19:44:37,357 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "ji8o9xvUaWDZzWVFlh0p602Bz8VrWPMvYbM3NNCFIrCSGyTrbFCKiXBOn9M38k5+[
n]"
#31 34.12 2022-06-22 19:44:37,357 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "qFXhORLHHCsDTyAJ0yAomI6ahbUqGeR63ZcFgEq9ewcuT0ird7eFpmLkN5T4tn3l[
n]"
#31 34.12 2022-06-22 19:44:37,357 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "97qV4BwNN93oZeM81n8uaGMUKn4IsyQGmaU7sNgut8pWMf4xAt0lgo52GdQW8Mzn[
n]"
#31 34.12 2022-06-22 19:44:37,357 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "9Ppn/lg9LcBlWCEE3kuQJidQfzauXxUq2ZTEvK43wLHMezjUsedsGUw137gP1F1C[
n]"
#31 34.12 2022-06-22 19:44:37,357 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "qIxLGzGt0yN3dCeDdIBLgBzwwRmjmtTEMYFHqZhhoPLeHnG6amT3MPb6YKSDnUKx[
n]"
#31 34.12 2022-06-22 19:44:37,358 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "IO3+izq2yet/dIaAXYfvcgdOSiGMvCPErb/Btlyf2JV+ANCUXrm/PccHcwupVPjK[
n]"
#31 34.12 2022-06-22 19:44:37,358 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "q4qn2N+eRNZjYAsXOeK+FOhp04aycV+Xov5mdRyBQ449Vnz3W8myri6RD84n42mJ[
n]"
#31 34.12 2022-06-22 19:44:37,358 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "vWVBn2V/7nY1ORY7WEuP1g5y9ro9ij82rRLsLh5/PP6gUovVmPdngQhzxpdHpShJ[
n]"
#31 34.12 2022-06-22 19:44:37,358 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "lP9vke9tXAtXB5Hr18MuqCWe6ZVgf14thkO9BKHKHAsq/Hvvba/wRvB+E2EQU+W3[
n]"
#31 34.12 2022-06-22 19:44:37,358 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "Le/rntkKobTAqAp4LMk5pHCupfkPPvwZyEPgkz+ALJJX0/eBm/TmH8Nofsuk6UZJ[
n]"
#31 34.12 2022-06-22 19:44:37,358 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "8dnHlT7+Gqiqq9WSLBeR[
n]"
#31 34.12 2022-06-22 19:44:37,358 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "=uNlA[
n]"
#31 34.12 2022-06-22 19:44:37,358 - DEBUG [org.apache.http.wire:73] - http-outgoing-1 << "---- END PGP SIGNATURE ----[
n]"
#31 34.12 2022-06-22 19:44:37,358 - DEBUG [org.apache.http.headers:122] - http-outgoing-1 << HTTP/1.1 200 OK
#31 34.12 2022-06-22 19:44:37,358 - DEBUG [org.apache.http.headers:125] - http-outgoing-1 << Date: Wed, 22 Jun 2022 19:44:37 GMT
#31 34.12 2022-06-22 19:44:37,359 - DEBUG [org.apache.http.headers:125] - http-outgoing-1 << Server: Apache/2.4.37 (rocky) OpenSSL/1.1.1k
#31 34.12 2022-06-22 19:44:37,359 - DEBUG [org.apache.http.headers:125] - http-outgoing-1 << Last-Modified: Tue, 14 Jun 2022 15:09:49 GMT
#31 34.12 2022-06-22 19:44:37,359 - DEBUG [org.apache.http.headers:125] - http-outgoing-1 << ETag: "344-5e169cbbe2488"
#31 34.12 2022-06-22 19:44:37,359 - DEBUG [org.apache.http.headers:125] - http-outgoing-1 << Accept-Ranges: bytes
#31 34.12 2022-06-22 19:44:37,359 - DEBUG [org.apache.http.headers:125] - http-outgoing-1 << Content-Length: 836
#31 34.13 2022-06-22 19:44:37,361 - DEBUG [org.apache.http.headers:125] - http-outgoing-1 << Vary: User-Agent
#31 34.13 2022-06-22 19:44:37,362 - DEBUG [org.apache.http.headers:125] - http-outgoing-1 << Connection: close
#31 34.13 2022-06-22 19:44:37,364 - DEBUG [org.apache.http.headers:125] - http-outgoing-1 << Content-Type: text/plain; charset=UTF-8
#31 34.13 2022-06-22 19:44:37,365 - DEBUG [net.shibboleth.ext.spring.resource.HTTPResource:139] - Non caching client provided
#31 34.13 2022-06-22 19:44:37,366 - DEBUG [org.apache.http.impl.conn.DefaultManagedHttpClientConnection:79] - http-outgoing-1: Close connection
#31 34.13 2022-06-22 19:44:37,367 - DEBUG [org.apache.http.impl.execchain.MainClientExec:104] - Connection discarded
#31 34.13 2022-06-22 19:44:37,369 - DEBUG [org.apache.http.impl.conn.PoolingHttpClientConnectionManager:351] - Connection released: [id: 1][route: {s}-> https://shibboleth.net:443 [total|https://shibboleth.net:443%5D%5Btotal] available: 0; route allocated: 0 of 100; total allocated: 0 of 100]
#31 34.29 2022-06-22 19:44:37,529 - DEBUG [net.shibboleth.idp.installer.plugin.impl.TrustStore:181] - Plugin net.shibboleth.plugin.storage.jdbc: Loading explicit truststore /tmp/truststore/net.shibboleth.plugin.storage.jdbc/truststore.asc
#31 34.39 2022-06-22 19:44:37,629 - DEBUG [net.shibboleth.idp.installer.plugin.impl.TrustStore:176] - Looking for key with Id 0x1483F262A4B3FF0
#31 34.46 2022-06-22 19:44:37,699 - DEBUG [net.shibboleth.idp.installer.plugin.impl.TrustStore:171] - Signature Check Succeeded
#31 34.49 2022-06-22 19:44:37,722 - DEBUG [net.shibboleth.idp.module.PropertyDrivenIdPModule:175] - Plugin net.shibboleth.plugin.storage.jdbc loaded
#31 34.49 2022-06-22 19:44:37,724 - DEBUG [net.shibboleth.idp.installer.plugin.impl.PluginInstaller:176] - Found Service announcing itself as net.shibboleth.plugin.storage.jdbc
#31 34.49 2022-06-22 19:44:37,725 - ERROR [net.shibboleth.idp.installer.plugin.impl.PluginState:428] - Plugin net.shibboleth.plugin.storage.jdbc: No available servers found.
#31 34.49 ERROR - Plugin net.shibboleth.plugin.storage.jdbc: No available servers found.
#31 34.50 2022-06-22 19:44:37,737 - DEBUG [net.shibboleth.idp.installer.plugin.impl.PluginInstallerSupport:176] - Deleting directory /tmp/plugin-installer-download9600301635409502576
#31 34.50 2022-06-22 19:44:37,738 - DEBUG [net.shibboleth.idp.installer.InstallerSupport:171] - Not windows. Not [re]setting readonly bit
#31 34.51 2022-06-22 19:44:37,742 - DEBUG [net.shibboleth.idp.installer.plugin.impl.PluginInstallerSupport:176] - Deleting directory /tmp/plugin-installer-unpack4210971583016432244
#31 34.51 2022-06-22 19:44:37,744 - DEBUG [net.shibboleth.idp.installer.InstallerSupport:171] - Not windows. Not [re]setting readonly bit
#31 34.52 2022-06-22 19:44:37,747 - DEBUG [net.shibboleth.idp.installer.InstallerSupport:171] - Not windows. Not [re]setting readonly bit
#31 34.52 2022-06-22 19:44:37,754 - ERROR [net.shibboleth.idp.installer.plugin.impl.PluginInstallerCLI:452] - Plugin Install failed:
#31 34.52 org.apache.tools.ant.BuildException: net.shibboleth.utilities.java.support.component.ComponentInitializationException: Could not locate information for net.shibboleth.plugin.storage.jdbc
#31 34.52 at net.shibboleth.idp.installer.plugin.impl.PluginInstaller.installPlugin(PluginInstaller.java:287)
#31 34.52 at net.shibboleth.idp.installer.plugin.impl.PluginInstaller.installPlugin(PluginInstaller.java:253)
#31 34.52 at net.shibboleth.idp.installer.plugin.impl.PluginInstallerCLI.doRun(PluginInstallerCLI.java:162)
#31 34.52 at net.shibboleth.idp.installer.plugin.impl.PluginInstallerCLI.doRun(PluginInstallerCLI.java:66)
#31 34.52 at net.shibboleth.ext.spring.cli.AbstractCommandLine.run(AbstractCommandLine.java:202)
#31 34.52 at net.shibboleth.idp.installer.plugin.impl.PluginInstallerCLI.runMain(PluginInstallerCLI.java:578)
#31 34.52 at net.shibboleth.idp.installer.plugin.impl.PluginInstallerCLI.main(PluginInstallerCLI.java:586)
#31 34.52 Caused by: net.shibboleth.utilities.java.support.component.ComponentInitializationException: Could not locate information for net.shibboleth.plugin.storage.jdbc
#31 34.52 at net.shibboleth.idp.installer.plugin.impl.PluginState.doInitialize(PluginState.java:162)
#31 34.52 at net.shibboleth.utilities.java.support.component.AbstractInitializableComponent.initialize(AbstractInitializableComponent.java:65)
#31 34.52 at net.shibboleth.idp.installer.plugin.impl.PluginInstaller.installPlugin(PluginInstaller.java:285)
#31 34.52 ... 6 common frames omitted
#31 34.53 2022-06-22 19:44:37,763 - DEBUG [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:1049] - Closing ApplicationContext:anonymous, started on Wed Jun 22 19:44:34 UTC 2022

( https://shibboleth.atlassian.net/browse/JJDBC-5#add-comment?atlOrigin=eyJpIjoiNGVhN2MwOWE2YmExNGYzYWE0ZGUxNmQ3MTk4ZTNlY2MiLCJwIjoiaiJ9 ) Add Comment ( https://shibboleth.atlassian.net/browse/JJDBC-5#add-comment?atlOrigin=eyJpIjoiNGVhN2MwOWE2YmExNGYzYWE0ZGUxNmQ3MTk4ZTNlY2MiLCJwIjoiaiJ9 )

Get Jira notifications on your phone! Download the Jira Cloud app for Android ( https://play.google.com/store/apps/details?id=com.atlassian.android.jira.core&referrer=utm_source%3DNotificationLink%26utm_medium%3DEmail ) or iOS ( https://itunes.apple.com/app/apple-store/id1006972087?pt=696495&ct=EmailNotificationLink&mt=8 ) This message was sent by Atlassian Jira (v1001.0.0-SNAPSHOT#100201- sha1:5c1db44 )
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://shibboleth.net/pipermail/commits/attachments/20220622/e25b11eb/attachment-0001.htm>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: jira-generated-image-static-comment-icon-eb353ccd-b009-4431-ba62-b6ae438c69ce
Type: image/png
Size: 1084 bytes
Desc: not available
URL: <http://shibboleth.net/pipermail/commits/attachments/20220622/e25b11eb/attachment-0004.png>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: jira-generated-image-static-trivial-e9839eb3-d651-4004-a9d4-e5455b31e70c
Type: image/png
Size: 563 bytes
Desc: not available
URL: <http://shibboleth.net/pipermail/commits/attachments/20220622/e25b11eb/attachment-0005.png>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: jira-generated-image-avatar-eab31cb6-4acd-44ab-9f60-ed4111d2955a
Type: image/png
Size: 425 bytes
Desc: not available
URL: <http://shibboleth.net/pipermail/commits/attachments/20220622/e25b11eb/attachment-0006.png>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: jira-generated-image-static-footer-desktop-logo-466daad4-068f-40b5-9c0a-e94fcf0c9467
Type: image/png
Size: 10805 bytes
Desc: not available
URL: <http://shibboleth.net/pipermail/commits/attachments/20220622/e25b11eb/attachment-0007.png>