Debian 7 install with Oracle java JRE 1.7.0

[Michael Pope]

Hello,

I've just installed debian 7, oracle java 1.7.0, auskey software,
UnlimitedJCEPolicyJDK7 and I'm getting the following error:

"Credential required

You need an AUSkey to login, but one cannot be found on your computer.

If you have an AUSkey on a USB device, insert it, select Cacncel, open
the AUSkey browser on the USB device, then login again to the desired
application.

If you don't have an AUSkey you can reqgister for one at
https://www.abr.gov.au/auskey"

Here is what I've done so far:
1. Uninstalled openjdk-6-jre, openjdk-6-jre-headless, openjdk-6-jre-lib,
openjdk-7-jre, openjdk-7-jre-headless, openjdk-7-jre-lib
2. Downloaded jdk-7-linux-x64.tar.gz from oracle
3. Untar this into /opt/jdk1.7.0

4. Set this java as my default with the following comands:
# update-alternatives --install /usr/bin/java java
${jvm_folder}/jdk1.7.0/bin/java 1065
# update-alternatives --install /usr/bin/jar jar
${jvm_folder}/jdk1.7.0/bin/jar 1065

5. Installed the plugin for iceweasel 10.0.12
# ln -s /opt/jdk1.7.0/jre/lib/amd64/libnpjp2.so
chdir=/usr/lib/mozilla/plugins creates=/usr/lib/mozilla/plugins/libnpjp2.so

6. Proved that java works in iceweasel with the correct version (this
worked)

7. Copy over security from downloaded UnlimitedJCEPolicy
# cp /tmp/UnlimitedJCEPolicy/* /opt/jdk1.7.0/jre/lib/security/

8. Manually installed AUSkey with the following:
cd /tmp
wget https://www.auskey.abr.gov.au/plugin/AUSkey_software_for_Linux.zip
unzip -u AUSkey_software_for_Linux.zip
cp -v /tmp/ABR_PKG/component/*.jar /opt/jdk1.7.0/jre/lib/ext
chmod -R a+rw /opt/jdk1.7.0/jre/lib/ext

9. Copy log4j.properties to my home directory and set permissions
mkdir ~/AUSkey
cp -n /tmp/ABR_PKG/component/log4j.properties ~/AUSkey;
chown -R <my user> ~/AUSkey;
chmod o+rw ~/AUSkey/log4j.properties

10. Turn on some logging (add to my ~/AUSkey/log4j.properties and change
<user> to my user)
log4j.rootLogger=DEBUG,R
log4j.appender.R=org.apache.log4j.RollingFileAppender
log4j.appender.R.File=/home/<user>/AUSkey/debug.log
log4j.appender.R.MaxFileSize=10MB
log4j.appender.R.MaxBackupIndex=10
log4j.appender.R.layout=org.apache.log4j.PatternLayout
#log4j.appender.R.layout.ConversionPattern=%p %t %c - %m%n

log4j.appender.R.layout.ConversionPattern=%d{HH:mm:ss,SSS} %-5p [%c] -
%m%n
log4j.appender.CONSOLE=org.apache.log4j.ConsoleAppender
log4j.appender.CONSOLE.layout=org.apache.log4j.PatternLayout
log4j.appender.CONSOLE.layout.ConversionPattern=%d{HH:mm:ss,SSS} %-5p
[%c] - %m%n
log4j.appender.CONSOLE.layout=org.apache.log4j.PatternLayout

11. Turned off 'Keep temp files' in jcontrol (clearing old temp files first)

12. Registered for a new AUSkey for this new computer, activated &
installed successfully on the new computer

13. Tried to login and got an error.

Here is my AUSkey/debug.log:
10:17:21,842 DEBUG
[au.gov.abr.auth.browserenabler.operationstatemanager.OperationStateManager]
- Performing operation: isABRKeystoreInstalled, with parameters:
{"minVersion":"1.3.18","errorHandler":"OnAppletError","resultHandler":"OnKeyStoreInstalled"}
10:17:21,924 DEBUG
[au.gov.abr.auth.credential.store.ABRConfig$PreferencesSerializer] -
Loading preferences to JAXB.
10:17:22,562 DEBUG
[au.gov.abr.auth.credential.store.ABRConfig$PreferencesSerializer] -
Unmarshalling preferences to JAXB.
10:17:22,627 DEBUG
[au.gov.abr.auth.credential.store.ABRConfig$PreferencesSerializer] -
Checking preferences content.
10:17:22,627 DEBUG
[au.gov.abr.auth.credential.store.ABRConfig$PreferencesSerializer] -
Number of profiles = 1
10:17:22,627 INFO [au.gov.abr.auth.credential.store.ABRKeyStoreFactory]
- Will attempt to load the keystore, if they keystore doesn't exist then
it is allowed to be created
10:17:22,628 INFO
[au.gov.abr.auth.credential.store.ABRKeyStoreSerializerTransporterFactory]
- No custom Transporter specified, using the default File Transporter.
10:17:22,643 INFO
[au.gov.abr.auth.credential.store.ABRKeyStoreSerializerTransporterFactory]
- No custom Transporter specified, using the default File Transporter.
10:17:22,646 INFO
[au.gov.abr.auth.credential.store.ABRKeyStoreSerializerTransporter] -
Setting store valid.
10:17:22,649 INFO
[au.gov.abr.auth.credential.store.ABRKeyStoreSerializerTransporter] -
Setting store lastWritten to = Tue May 21 08:57:24 EST 2013
10:17:23,278 INFO
[au.gov.abr.auth.credential.store.ABRCredentialFactory] - ABRCredential
create method - setting the sha1Fingerprint
10:17:23,278 INFO
[au.gov.abr.auth.credential.store.ABRCredentialFactory] - Salt was found
during ABRCredentialFactory create method
10:17:23,358 INFO [au.gov.abr.auth.credential.store.ABRKeyStoreFactory]
- Saving a reference to the keystore in the hash map
10:17:23,577 INFO
[au.gov.abr.auth.browserenabler.operations.IsABRKeystoreInstalledOperation]
- Version of the AUSKey software currently installed is: 1.4.0.8.
Minimum version required is: 1.3.18
10:17:23,579 DEBUG
[au.gov.abr.auth.browserenabler.operationstatemanager.OperationStateManager]
- Completed operation isABRKeystoreInstalled
10:17:23,581 DEBUG
[au.gov.abr.auth.browserenabler.operationstatemanager.OperationStateManager]
- Performing operation: getCredentials, with parameters:
{"sortOrder":"","errorHandler":"OnAppletError","resultHandler":"OnGetValidUserCredentials","filter":{"filter":[["credentialType","U"],["name1",""],["name2",""],["abn",""],["legalName",""],["valid","Y"],["sha1FingerPrint",""],["alias",""]]}}
10:17:23,583 DEBUG
[au.gov.abr.auth.credential.store.ABRConfig$PreferencesSerializer] -
Loading preferences to JAXB.
10:17:23,741 DEBUG
[au.gov.abr.auth.credential.store.ABRConfig$PreferencesSerializer] -
Unmarshalling preferences to JAXB.
10:17:23,784 DEBUG
[au.gov.abr.auth.credential.store.ABRConfig$PreferencesSerializer] -
Checking preferences content.
10:17:23,784 DEBUG
[au.gov.abr.auth.credential.store.ABRConfig$PreferencesSerializer] -
Number of profiles = 1
10:17:23,784 INFO [au.gov.abr.auth.credential.store.ABRKeyStoreFactory]
- Will attempt to load the keystore, if they keystore doesn't exist then
it is allowed to be created
10:17:23,784 INFO
[au.gov.abr.auth.credential.store.ABRKeyStoreSerializerTransporterFactory]
- No custom Transporter specified, using the default File Transporter.
10:17:23,794 DEBUG
[au.gov.abr.auth.browserenabler.operationstatemanager.OperationStateManager]
- Completed operation getCredentials
10:20:54,014 DEBUG
[au.gov.abr.auth.browserenabler.operationstatemanager.OperationStateManager]
- Performing operation: isABRKeystoreInstalled, with parameters:
{"minVersion":"1.3.18","errorHandler":"OnAppletError","resultHandler":"OnKeyStoreInstalled"}
10:20:54,122 DEBUG
[au.gov.abr.auth.credential.store.ABRConfig$PreferencesSerializer] -
Loading preferences to JAXB.
10:20:54,744 DEBUG
[au.gov.abr.auth.credential.store.ABRConfig$PreferencesSerializer] -
Unmarshalling preferences to JAXB.
10:20:54,808 DEBUG
[au.gov.abr.auth.credential.store.ABRConfig$PreferencesSerializer] -
Checking preferences content.
10:20:54,808 DEBUG
[au.gov.abr.auth.credential.store.ABRConfig$PreferencesSerializer] -
Number of profiles = 1
10:20:54,808 INFO [au.gov.abr.auth.credential.store.ABRKeyStoreFactory]
- Will attempt to load the keystore, if they keystore doesn't exist then
it is allowed to be created
10:20:54,809 INFO
[au.gov.abr.auth.credential.store.ABRKeyStoreSerializerTransporterFactory]
- No custom Transporter specified, using the default File Transporter.
10:20:54,822 INFO
[au.gov.abr.auth.credential.store.ABRKeyStoreSerializerTransporterFactory]
- No custom Transporter specified, using the default File Transporter.
10:20:54,826 INFO
[au.gov.abr.auth.credential.store.ABRKeyStoreSerializerTransporter] -
Setting store valid.
10:20:54,829 INFO
[au.gov.abr.auth.credential.store.ABRKeyStoreSerializerTransporter] -
Setting store lastWritten to = Tue May 21 08:57:24 EST 2013
10:20:55,423 INFO
[au.gov.abr.auth.credential.store.ABRCredentialFactory] - ABRCredential
create method - setting the sha1Fingerprint
10:20:55,423 INFO
[au.gov.abr.auth.credential.store.ABRCredentialFactory] - Salt was found
during ABRCredentialFactory create method
10:20:55,497 INFO [au.gov.abr.auth.credential.store.ABRKeyStoreFactory]
- Saving a reference to the keystore in the hash map
10:20:55,702 INFO
[au.gov.abr.auth.browserenabler.operations.IsABRKeystoreInstalledOperation]
- Version of the AUSKey software currently installed is: 1.4.0.8.
Minimum version required is: 1.3.18
10:20:55,705 DEBUG
[au.gov.abr.auth.browserenabler.operationstatemanager.OperationStateManager]
- Completed operation isABRKeystoreInstalled
10:20:55,707 DEBUG
[au.gov.abr.auth.browserenabler.operationstatemanager.OperationStateManager]
- Performing operation: getCredentials, with parameters:
{"sortOrder":"","errorHandler":"OnAppletError","resultHandler":"OnGetValidUserCredentials","filter":{"filter":[["credentialType","U"],["name1",""],["name2",""],["abn",""],["legalName",""],["valid","Y"],["sha1FingerPrint",""],["alias",""]]}}
10:20:55,709 DEBUG
[au.gov.abr.auth.credential.store.ABRConfig$PreferencesSerializer] -
Loading preferences to JAXB.
10:20:55,841 DEBUG
[au.gov.abr.auth.credential.store.ABRConfig$PreferencesSerializer] -
Unmarshalling preferences to JAXB.
10:20:55,892 DEBUG
[au.gov.abr.auth.credential.store.ABRConfig$PreferencesSerializer] -
Checking preferences content.
10:20:55,893 DEBUG
[au.gov.abr.auth.credential.store.ABRConfig$PreferencesSerializer] -
Number of profiles = 1
10:20:55,893 INFO [au.gov.abr.auth.credential.store.ABRKeyStoreFactory]
- Will attempt to load the keystore, if they keystore doesn't exist then
it is allowed to be created
10:20:55,893 INFO
[au.gov.abr.auth.credential.store.ABRKeyStoreSerializerTransporterFactory]
- No custom Transporter specified, using the default File Transporter.
10:20:55,903 DEBUG
[au.gov.abr.auth.browserenabler.operationstatemanager.OperationStateManager]
- Completed operation getCredentials

Here is my ~/.java/deployment/log/plugin8589336552216103724.log file:
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
<!DOCTYPE log SYSTEM "logger.dtd">
<log>
<record>
<date>2013-05-21T10:20:53</date>
<millis>1369095653025</millis>
<sequence>0</sequence>
<logger>sun.plugin</logger>
<level>FINE</level>
<class>com.sun.deploy.trace.LoggerTraceListener</class>
<method>print</method>
<thread>8</thread>
<message>Detected from bootclasspath: /opt/jdk1.7.0/jre/lib/deploy.jar
</message>
</record>
<record>
<date>2013-05-21T10:20:53</date>
<millis>1369095653031</millis>
<sequence>1</sequence>
<logger>sun.plugin</logger>
<level>FINE</level>
<class>com.sun.deploy.trace.LoggerTraceListener</class>
<method>print</method>
<thread>8</thread>
<message>IsJava5InstalledApplet: Checking if Java 1.5 or higher is
installed.
</message>
</record>
<record>
<date>2013-05-21T10:20:53</date>
<millis>1369095653031</millis>
<sequence>2</sequence>
<logger>sun.plugin</logger>
<level>FINE</level>
<class>com.sun.deploy.trace.LoggerTraceListener</class>
<method>print</method>
<thread>8</thread>
<message>IsJava5InstalledApplet: Java 1.5 or higher is installed
</message>
</record>
<record>
<date>2013-05-21T10:20:53</date>
<millis>1369095653031</millis>
<sequence>3</sequence>
<logger>sun.plugin</logger>
<level>FINE</level>
<class>com.sun.deploy.trace.LoggerTraceListener</class>
<method>print</method>
<thread>8</thread>
<message>plugin2manager.parentwindowDispose
</message>
</record>
<record>
<date>2013-05-21T10:20:53</date>
<millis>1369095653032</millis>
<sequence>4</sequence>
<logger>sun.plugin</logger>
<level>FINE</level>
<class>com.sun.deploy.trace.LoggerTraceListener</class>
<method>print</method>
<thread>8</thread>
<message>Keystore size is: 8290
</message>
</record>
<record>
<date>2013-05-21T10:20:53</date>
<millis>1369095653032</millis>
<sequence>5</sequence>
<logger>sun.plugin</logger>
<level>FINE</level>
<class>com.sun.deploy.trace.LoggerTraceListener</class>
<method>print</method>
<thread>8</thread>
<message>Removing credential extension elements....
</message>
</record>
<record>
<date>2013-05-21T10:20:53</date>
<millis>1369095653164</millis>
<sequence>6</sequence>
<logger>sun.plugin</logger>
<level>FINE</level>
<class>com.sun.deploy.trace.LoggerTraceListener</class>
<method>print</method>
<thread>8</thread>
<message>Keystore size is: 8290
</message>
</record>
<record>
<date>2013-05-21T10:20:53</date>
<millis>1369095653165</millis>
<sequence>7</sequence>
<logger>sun.plugin</logger>
<level>FINE</level>
<class>com.sun.deploy.trace.LoggerTraceListener</class>
<method>print</method>
<thread>8</thread>
<message>Removing credential extension elements....
</message>
</record>
<record>
<date>2013-05-21T10:20:53</date>
<millis>1369095653369</millis>
<sequence>8</sequence>
<logger>sun.plugin</logger>
<level>FINE</level>
<class>com.sun.deploy.trace.LoggerTraceListener</class>
<method>print</method>
<thread>8</thread>
<message>plugin2manager.parentwindowDispose
</message>
</record>
<record>
<date>2013-05-21T10:20:56</date>
<millis>1369095656282</millis>
<sequence>9</sequence>
<logger>sun.plugin</logger>
<level>FINE</level>
<class>com.sun.deploy.trace.LoggerTraceListener</class>
<method>print</method>
<thread>8</thread>
<message>plugin2manager.parentwindowDispose
</message>
</record>
</log>

Here is a listing of my AUSkey directory
-rw-r--r-- 1 map7 map7 7108 May 21 10:20 debug.log
-rw-r--r-- 1 map7 map7 8290 May 21 08:57 keystore.xml
-rw-r--r-- 1 map7 map7 8290 May 21 08:56 keystore.xml.bak
-r--r--rw- 1 map7 map7 662 May 21 10:15 log4j.properties
-r--r--r-- 1 map7 map7 293 May 21 10:03 log4j.properties.bak
-rw-r--r-- 1 map7 map7 717 May 20 16:14 preferences.xml

from
Michael

[Michael Pope]

Fixed the problem

The date on the machine has to be set within 1 minute of the ATO's date for the AUSkey to work.

As I was using snapshots in a virtual environment and kept jumping about between snapshots it would bump my system time all over the place, so that's why I ran into this error.

from
Michael