smpatch: Realization integrity checks failed
This is a re-post of the topic forumID=292&threadID=103584.
Brief summary: Two different users report independently the following problem after the installation of PatchManager 2.0 on their Solaris 8/sparc box:
# smpatch analyze
Realization integrity checks failed
No patches required.
#
#
# smpatch analyze -i 115831
Realization integrity checks failed
com.sun.patchpro.model.PatchProRuntimeException: Unexpected throwable
at com.sun.patchpro.cli.PatchServices.waitForThread(PatchServices.java:1284)
at com.sun.patchpro.cli.PatchServices.displayPatchList(PatchServices.java:616)
at com.sun.patchpro.cli.PatchServices.main(PatchServices.java:494)
Caused by:
java.lang.Throwable:
at com.sun.patchpro.model.PatchProModel$InnerSequencerThread.sequencerFailed(PatchProModel.java:2676)
at com.sun.patchpro.model.PatchProModel.sequencerFailed(PatchProModel.java:3459)
at com.sun.patchpro.patch.GroupPatchSequencer.dispatchFailedEvent(GroupPatchSequencer.java:402)
at com.sun.patchpro.patch.GroupPatchSequencer.sequencerFailed(GroupPatchSequencer.java:351)
at com.sun.patchpro.patch.GroupPatchSequencer.getPatchLists(GroupPatchSequencer.java:159)
at com.sun.patchpro.model.PatchProModel.runSequencer(PatchProModel.java:1903)
at com.sun.patchpro.model.PatchProStateMachine$9.run(PatchProStateMachine.java:482)
at com.sun.patchpro.util.State.run(State.java:266)
at java.lang.Thread.run(Thread.java:484)
#
The "smpach analyze" command always replies "No patches required", mostly (but not always) preceded by the "Realization integrity checks failed" line. At least one of the two machines is definitely known to need patching.
The second problem ("smpach analyze -i patchnr" command generating a stack trace) has been fixed already on one of the machines but the remedy doesn't seem to help resolve the issue on the other machine.
Any ideas?
[2007 byte] By [
_c_b_] at [2007-11-26 10:06:38]

# 1
Hello,We have seen a previous case where there java version which the customer has installed on the system was causing similar behaviour.Please can you run the following command:java -versionRegards,
# 2
Hello,
> Please can you run the following command:
> java -version
here the output:
# java -version
java version "1.5.0_04"
Java(TM) 2 Runtime Environment, Standard Edition (build 1.5.0_04-b05)
Java HotSpot(TM) Server VM (build 1.5.0_04-b05, mixed mode)
#
Best,
cb
_c_b_ at 2007-7-7 1:45:43 >

# 3
Could you see if there are any 0 byte files in /var/sadm/spool/cache ?
# 4
Yes, there are:
# ls -lrt /var/sadm/spool/cache
total 7440
-rw-r--r--1 rootother 0 Sep 11 14:17 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fdetectors.jar65517
-rw-r--r--1 rootother 0 Sep 11 16:44 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fpatchdb.zip18592
-rw-r--r--1 rootother 0 Sep 11 16:44 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fdetectors.jar18593
-rw-r--r--1 rootother 0 Sep 11 18:19 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fpatchdb.zip4254
-rw-r--r--1 rootother 0 Sep 11 18:19 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fdetectors.jar4255
-rw-r--r--1 rootother 0 Sep 11 18:35 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fpatchdb.zip58312
-rw-r--r--1 rootother 0 Sep 11 18:35 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fdetectors.jar58313
-rw-r--r--1 rootother 0 Sep 12 06:14 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fpatchdb.zip10577
-rw-r--r--1 rootother 0 Sep 12 06:14 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fdetectors.jar10578
-rw-r--r--1 rootother222827 Sep 13 05:40 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fpatchdb.zip
-rw-r--r--1 rootother3560007 Sep 13 05:40 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fdetectors.jar
#
I deleted all files in this directory once (on Sep 11, I believe), after my first attempts to get smpatch working failed. The deleted files were of varying size (for a given file type -- detectors,patchdb), partly quite empty.
Thank you.
Best,
cb
_c_b_ at 2007-7-7 1:45:43 >

# 5
Hi.Could you remove the contents of /var/sadm/spool/cache and try again?
# 6
Thank you very much for your assistance. I have tried it twice:
# pwd
/var/sadm/spool/cache
# rm *
# ls
# smpatch analyze
No patches required.
# ls -l
total 7440
-rw-r--r--1 rootother3560007 Sep 14 19:11 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fdetectors.jar
-rw-r--r--1 rootother222827 Sep 14 19:10 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fpatchdb.zip
# smpatch analyze
Realization integrity checks failed
No patches required.
# ls -l
total 7440
-rw-r--r--1 rootother3560007 Sep 14 19:14 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fdetectors.jar
-rw-r--r--1 rootother222827 Sep 14 19:14 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fpatchdb.zip
#
Best,
cb
_c_b_ at 2007-7-7 1:45:43 >

# 7
Could you update your Java install to 1.5.0_08 ? The only circumstances under which I have seen the integrity check error have been due to corrupt detectors (unlikely, since you have removed the detectors.jar files several times), and a Java issue.
# 8
OK, I'll install Java 1.5.0_08 as soon as possible, try it again and post a status report here.Best,cb
_c_b_ at 2007-7-7 1:45:43 >

# 9
I updated java to 1.5.0_08 but as far as I can see the behaviour didn't change:
# pwd
/var/sadm/spool/cache
# rm *
# ls
# java -version
java version "1.5.0_08"
Java(TM) 2 Runtime Environment, Standard Edition (build 1.5.0_08-b03)
Java HotSpot(TM) Server VM (build 1.5.0_08-b03, mixed mode)
# smpatch analyze
No patches required.
# ls -l
total 7440
-rw-r--r--1 rootother3560007 Sep 15 08:30 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fdetectors.jar
-rw-r--r--1 rootother223106 Sep 15 08:29 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fpatchdb.zip
# smpatch analyze
Realization integrity checks failed
No patches required.
# ls -l
total 7440
-rw-r--r--1 rootother3560007 Sep 15 10:47 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fdetectors.jar
-rw-r--r--1 rootother223106 Sep 15 10:46 https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fpatchdb.zip
#
Is there anything I should do next? Any hints appreciated.
Thank you.
Best,
cb
_c_b_ at 2007-7-7 1:45:43 >

# 10
Just for fun can you try setting an http proxy for smpatch (even if you dont actually need one)
# smpatch get
# smpatch set patchpro.proxy.host=test.notrealproxy.com
# smpatch set patchpro.proxy.port=80
# smpatch get
Afterwards feel free to unset/reset the settings to what is correct for your environment.
I'm hopeful that the attempt to set the proxy settings may actually produce a different error.
# 11
> I'm hopeful that the attempt to set the proxy
> settings may actually produce a different error.
It didn't:
# smpatch get
patchpro.backout.directory-""
patchpro.download.directory-/var/sadm/spool
patchpro.install.types -rebootafter:reconfigafter:standard
patchpro.patch.source-https://updateserver.sun.com/solaris/
patchpro.patchset-patchdb
patchpro.proxy.host -""
patchpro.proxy.passwd********
patchpro.proxy.port -8080
patchpro.proxy.user -""
patchpro.sun.passwd ********
patchpro.sun.user[PATCHED] ""
# smpatch set patchpro.proxy.host=test.notrealproxy.com
# smpatch set patchpro.proxy.port=80
# smpatch get
patchpro.backout.directory-""
patchpro.download.directory-/var/sadm/spool
patchpro.install.types -rebootafter:reconfigafter:standard
patchpro.patch.source-https://updateserver.sun.com/solaris/
patchpro.patchset-patchdb
patchpro.proxy.host test.notrealproxy.com""
patchpro.proxy.passwd********
patchpro.proxy.port 80 8080
patchpro.proxy.user -""
patchpro.sun.passwd ********
patchpro.sun.user[PATCHED] ""
# smpatch analyze
Realization integrity checks failed
Unknown host (test.notrealproxy.com) connecting to https://updateserver.sun.com/solaris/
# smpatch unset patchpro.proxy.port
# smpatch unset patchpro.proxy.host
# smpatch analyze
No patches required.
#
Thanks.
Best,
cb
_c_b_ at 2007-7-7 1:45:43 >

# 12
Could you run the following command, and look in /tmp/smpatchDebug.log to see if there's anything interesting logged:# smpatch analyze -C patchpro.internal.statustags=true -C patchpro.log.level=7 -C patchpro.debug=true -C patchpro.log.file=/tmp/smpatchDebug.log
# 13
Hello,
First, the call to smpatch you suggested produced a stack trace due to NPE. Second, there are two warnings in the debug logfile. Here the whole stuff:
# smpatch analyze -C patchpro.internal.statustags=true -C patchpro.log.level=7 -C patchpro.debug=true -C patchpro.log.file=/tmp/smpatchDebug.log
java.lang.NullPointerException
at com.sun.patchpro.log.ApplicationLog.print(ApplicationLog.java:199)
at com.sun.patchpro.log.PatchProLog.print(PatchProLog.java:118)
at com.sun.patchpro.log.PatchProLog.println(PatchProLog.java:127)
at com.sun.patchpro.analysis.HostAnalyzer.isDetectorCacheValid(HostAnalyzer.java:772)
at com.sun.patchpro.server.ServerPatchServiceProvider.downloadRealizationDetectors(ServerPatchServiceProvider.java:380)
at com.sun.patchpro.server.PatchServerProxy.downloadRealizationDetectors(PatchServerProxy.java:120)
at com.sun.patchpro.analysis.HostAnalyzer$RealizationSetAuto.downloadDetectorsWithPOST(HostAnalyzer.java:1215)
at com.sun.patchpro.analysis.HostAnalyzer$RealizationSetAuto.downloadDetectors(HostAnalyzer.java:1189)
at com.sun.patchpro.analysis.HostAnalyzer$RealizationSetAuto.prepare(HostAnalyzer.java:909)
at com.sun.patchpro.analysis.HostAnalyzer.downloadDetectors(HostAnalyzer.java:286)
at com.sun.patchpro.model.PatchProModel.downloadDetectors(PatchProModel.java:1764)
at com.sun.patchpro.model.PatchProStateMachine$4.run(PatchProStateMachine.java:245)
at com.sun.patchpro.util.State.run(State.java:266)
at java.lang.Thread.run(Thread.java:484)
# cat /tmp/smpatchDebug.log
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=setRunning()
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@283b8a <=State: initialized.
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@5d20f6 <=State: initialized.
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@1efb05 <=State: initialized.
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@723d7c <=State: initialized.
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@22c95b <=State: initialized.
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@51acd3 <=State: initialized.
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@2981ca <=State: initialized.
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@814e9 <=State: initialized.
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@1503a3 <=State: initialized.
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@21c887 <=State: initialized.
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@743399 <=State: initialized.
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@67b241 <=State: initialized.
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=start(...) w/ start state = 8 and end state = 8.
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=Starting 8 with state state 0
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@1503a3 <=State: State is reserved.
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@1503a3 <=State: State number is 8
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=StateMachine: Started state 8
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@1503a3 <=State: run() begins
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@1503a3 <=State: beginning action
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$9@67d940 <=runSequencer(): started
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$9@67d940 <=runSequencer(): no DOWNLOADPATCHDB
Fri Sep 15 17:50:12 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$14@683912 <=downloadOrStartOver(): returning 0
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=reading state 8
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=State 8 is not terminal.
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=nextStates =
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <= 0
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=Starting 0 with state state 0
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@283b8a <=State: State is reserved.
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@283b8a <=State: State number is 0
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=StateMachine: Started state 0
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@283b8a <=State: run() begins
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@283b8a <=State: beginning action
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$1@7d13b5 <=begin(): started
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$1@7d13b5 <=begin(): finished
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=reading state 0
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=State 0 is not terminal.
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=nextStates =
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <= 1
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=Starting 1 with state state 0
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@5d20f6 <=State: State is reserved.
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@5d20f6 <=State: State number is 1
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=StateMachine: Started state 1
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@5d20f6 <=State: run() begins
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@5d20f6 <=State: beginning action
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$2@18f375 <=runTargetInfo(): started
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.analysis.HostAnalyzer@58b649 <=analyzerName=com.sun.patchpro.analysis.SunOSTargetInfo
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.analysis.HostAnalyzer@58b649 <=classesDir=/usr/lib/patch
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$2@18f375 <=runTargetInfo(): finished
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$13@3bad12 <=baseIfAbbreviated(): returning 5
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=reading state 1
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=State 1 is not terminal.
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=nextStates =
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <= 5
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <= 3
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <= 4
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=Starting 5 with state state 0
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@51acd3 <=State: State is reserved.
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@51acd3 <=State: State number is 5
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=StateMachine: Started state 5
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@51acd3 <=State: run() begins
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=Starting 3 with state state 0
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@51acd3 <=State: beginning action
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@723d7c <=State: State is reserved.
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$6@632c2d <=runBaseDataDetectors(): started
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@723d7c <=State: State number is 3
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.analysis.HostAnalyzer@58b649 <=analyzerName=com.sun.patchpro.analysis.SunOSBaseData
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=StateMachine: Started state 3
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@723d7c <=State: run() begins
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.analysis.SunOSBaseData@540408 <=BaseDataDetector analyzerName=com.sun.patchpro.plugins.sunos.host.SunOSBaseDataExtension
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=Starting 4 with state state 0
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@22c95b <=State: State is reserved.
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@22c95b <=State: State number is 4
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=StateMachine: Started state 4
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.analysis.SunOSBaseData@540408 <=BaseDataDetector analyzerName=com.sun.patchpro.plugins.sunos.iostat.SunOSBaseDataExtension
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.analysis.SunOSBaseData@540408 <=BaseDataDetector analyzerName=com.sun.patchpro.plugins.sunos.patch.SunOSBaseDataExtension
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.analysis.SunOSBaseData@540408 <=BaseDataDetector analyzerName=com.sun.patchpro.plugins.sunos.pkg.SunOSBaseDataExtension
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@723d7c <=State: beginning action
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$4@113fe2 <=downloadDetectors(): started
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@22c95b <=State: run() begins
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@22c95b <=State: beginning action
Fri Sep 15 17:50:13 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$5@505c81 <=downloadPatchDB(): started
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.PatchServerProxy@20cc <=Adaptor is: com.sun.patchpro.server.ServerPatchServiceProvider
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.PatchServerProxy@2558d6 <=Adaptor is: com.sun.patchpro.server.ServerPatchServiceProvider
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.plugins.sunos.patch.SunOSBaseDataExtension@5a3d6b <=SunOSBaseDataExtension.makePatch(): lineAsString is
Fri Sep 15 17:50:14 CEST 2006(WARNING)=> com.sun.patchpro.util.LocalizedMessages@6fd0b7 <=Corrupt message catalog: locale="en" messageID="Could not decode patch entry" default english="Could not decode patch entry"
Fri Sep 15 17:50:14 CEST 2006(WARNING)=> com.sun.patchpro.plugins.sunos.patch.SunOSBaseDataExtension@5a3d6b <=SunOSBaseDataExtension.buildDatabase: com.sun.patchpro.patch.MalformedPatchException: Could not decode patch entry thrown trying to add to the host.
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@6f9f1d <=SunSolve user: belicapasswd: ID4jD0
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@3753f8 <=SunSolve user: belicapasswd: ID4jD0
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@6f9f1d <=Proxy user: proxyPasswd:
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@3753f8 <=Proxy user: proxyPasswd:
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@3753f8 <=patchsvr.source.0.url: https://updateserver.sun.com/solaris/
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@6f9f1d <=patchsvr.source.0.url: https://updateserver.sun.com/solaris/
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@3753f8 <=serverURLString: https://updateserver.sun.com/solaris/
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@6f9f1d <=serverURLString: https://updateserver.sun.com/solaris/
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@3753f8 <=isPatchServer: false
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@6f9f1d <=isPatchServer: false
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@3753f8 <=This server is SSL-enabled. https://updateserver.sun.com/solaris/
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@6f9f1d <=This server is SSL-enabled. https://updateserver.sun.com/solaris/
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@3753f8 <=cacheFile is: /var/sadm/spool/cache/https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fpatchdb.zip
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@6f9f1d <=cacheFile is: /var/sadm/spool/cache/https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fdetectors.jar
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@3753f8 <=POST String: action=downloadPatchDB&name=patchdb&version=2.1
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$4@113fe2 <=downloadDetectors(): finished
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@723d7c <=State 3 did not complete with null
Fri Sep 15 17:50:14 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=setDone()
#
Does this tell you anything? Thanks.
Best,
cb
_c_b_ at 2007-7-7 1:45:43 >

# 14
Could you post the output from:# locale
# 15
# locale
LANG=
LC_CTYPE="C"
LC_NUMERIC="C"
LC_TIME="C"
LC_COLLATE="C"
LC_MONETARY="C"
LC_MESSAGES="C"
LC_ALL=
#
cb
_c_b_a at 2007-7-21 15:24:20 >

# 16
Could you set the LANG variable before running smpatch:# export LANG=C# smpatch analyze
# 17
I've done three test runs. Each of the first two took a few minutes to finish. The NPE in the third run occured after aprox. 3 seconds (which was also the case in the first debug run above).
# LANG=C smpatch analyze
Realization integrity checks failed
No patches required.
# LANG=C LC_ALL=C smpatch analyze
Realization integrity checks failed
No patches required.
# LANG=C LC_ALL=C smpatch analyze -C patchpro.internal.statustags=true -C patchpro.log.level=7 -C patchpro.debug=true -C patchpro.log.file=/tmp/smpatchDebug.new.log
java.lang.NullPointerException
at com.sun.patchpro.log.ApplicationLog.print(ApplicationLog.java:199)
at com.sun.patchpro.log.PatchProLog.print(PatchProLog.java:118)
at com.sun.patchpro.log.PatchProLog.println(PatchProLog.java:127)
at com.sun.patchpro.analysis.HostAnalyzer.isDetectorCacheValid(HostAnalyzer.java:772)
at com.sun.patchpro.server.ServerPatchServiceProvider.downloadRealizationDetectors(ServerPatchServiceProvider.java:380)
at com.sun.patchpro.server.PatchServerProxy.downloadRealizationDetectors(PatchServerProxy.java:120)
at com.sun.patchpro.analysis.HostAnalyzer$RealizationSetAuto.downloadDetectorsWithPOST(HostAnalyzer.java:1215)
at com.sun.patchpro.analysis.HostAnalyzer$RealizationSetAuto.downloadDetectors(HostAnalyzer.java:1189)
at com.sun.patchpro.analysis.HostAnalyzer$RealizationSetAuto.prepare(HostAnalyzer.java:909)
at com.sun.patchpro.analysis.HostAnalyzer.downloadDetectors(HostAnalyzer.java:286)
at com.sun.patchpro.model.PatchProModel.downloadDetectors(PatchProModel.java:1764)
at com.sun.patchpro.model.PatchProStateMachine$4.run(PatchProStateMachine.java:245)
at com.sun.patchpro.util.State.run(State.java:266)
at java.lang.Thread.run(Thread.java:484)
# cat /tmp/smpatchDebug.new.logFri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=setRunning()
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@283b8a <=State: initialized.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@5d20f6 <=State: initialized.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@1efb05 <=State: initialized.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@723d7c <=State: initialized.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@22c95b <=State: initialized.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@51acd3 <=State: initialized.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@2981ca <=State: initialized.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@814e9 <=State: initialized.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@1503a3 <=State: initialized.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@21c887 <=State: initialized.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@743399 <=State: initialized.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@67b241 <=State: initialized.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=start(...) w/ start state = 8 and end state = 8.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=Starting 8 with state state 0
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@1503a3 <=State: State is reserved.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@1503a3 <=State: State number is 8
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=StateMachine: Started state 8
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@1503a3 <=State: run() begins
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@1503a3 <=State: beginning action
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$9@67d940 <=runSequencer(): started
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$9@67d940 <=runSequencer(): no DOWNLOADPATCHDB
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$14@683912 <=downloadOrStartOver(): returning 0
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=reading state 8
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=State 8 is not terminal.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=nextStates =
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <= 0
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=Starting 0 with state state 0
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@283b8a <=State: State is reserved.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@283b8a <=State: State number is 0
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=StateMachine: Started state 0
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@283b8a <=State: run() begins
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@283b8a <=State: beginning action
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$1@7d13b5 <=begin(): started
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$1@7d13b5 <=begin(): finished
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=reading state 0
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=State 0 is not terminal.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=nextStates =
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <= 1
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=Starting 1 with state state 0
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@5d20f6 <=State: State is reserved.
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@5d20f6 <=State: State number is 1
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=StateMachine: Started state 1
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@5d20f6 <=State: run() begins
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@5d20f6 <=State: beginning action
Fri Sep 15 19:01:52 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$2@18f375 <=runTargetInfo(): started
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.analysis.HostAnalyzer@58b649 <=analyzerName=com.sun.patchpro.analysis.SunOSTargetInfo
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.analysis.HostAnalyzer@58b649 <=classesDir=/usr/lib/patch
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$2@18f375 <=runTargetInfo(): finished
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$13@3bad12 <=baseIfAbbreviated(): returning 5
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=reading state 1
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=State 1 is not terminal.
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=nextStates =
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <= 5
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <= 3
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <= 4
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=Starting 5 with state state 0
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@51acd3 <=State: State is reserved.
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@51acd3 <=State: State number is 5
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=StateMachine: Started state 5
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@51acd3 <=State: run() begins
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=Starting 3 with state state 0
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@51acd3 <=State: beginning action
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@723d7c <=State: State is reserved.
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$6@632c2d <=runBaseDataDetectors(): started
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@723d7c <=State: State number is 3
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.analysis.HostAnalyzer@58b649 <=analyzerName=com.sun.patchpro.analysis.SunOSBaseData
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=StateMachine: Started state 3
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@723d7c <=State: run() begins
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.analysis.SunOSBaseData@540408 <=BaseDataDetector analyzerName=com.sun.patchpro.plugins.sunos.host.SunOSBaseDataExtension
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=Starting 4 with state state 0
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@22c95b <=State: State is reserved.
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@22c95b <=State: State number is 4
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=StateMachine: Started state 4
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.analysis.SunOSBaseData@540408 <=BaseDataDetector analyzerName=com.sun.patchpro.plugins.sunos.iostat.SunOSBaseDataExtension
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.analysis.SunOSBaseData@540408 <=BaseDataDetector analyzerName=com.sun.patchpro.plugins.sunos.patch.SunOSBaseDataExtension
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.analysis.SunOSBaseData@540408 <=BaseDataDetector analyzerName=com.sun.patchpro.plugins.sunos.pkg.SunOSBaseDataExtension
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@723d7c <=State: beginning action
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@22c95b <=State: run() begins
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$4@113fe2 <=downloadDetectors(): started
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@22c95b <=State: beginning action
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$5@714ceb <=downloadPatchDB(): started
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.PatchServerProxy@694e92 <=Adaptor is: com.sun.patchpro.server.ServerPatchServiceProvider
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.PatchServerProxy@6b7859 <=Adaptor is: com.sun.patchpro.server.ServerPatchServiceProvider
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@764be1 <=SunSolve user: belicapasswd: ID4jD0
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@6fd0b7 <=SunSolve user: belicapasswd: ID4jD0
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@764be1 <=Proxy user: proxyPasswd:
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@6fd0b7 <=Proxy user: proxyPasswd:
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.plugins.sunos.patch.SunOSBaseDataExtension@4413ee <=SunOSBaseDataExtension.makePatch(): lineAsString is
Fri Sep 15 19:01:53 CEST 2006(WARNING)=> com.sun.patchpro.util.LocalizedMessages@4b25f1 <=Corrupt message catalog: locale="en" messageID="Could not decode patch entry" default english="Could not decode patch entry"
Fri Sep 15 19:01:53 CEST 2006(WARNING)=> com.sun.patchpro.plugins.sunos.patch.SunOSBaseDataExtension@4413ee <=SunOSBaseDataExtension.buildDatabase: com.sun.patchpro.patch.MalformedPatchException: Could not decode patch entry thrown trying to add to the host.
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@6fd0b7 <=patchsvr.source.0.url: https://updateserver.sun.com/solaris/
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@764be1 <=patchsvr.source.0.url: https://updateserver.sun.com/solaris/
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@6fd0b7 <=serverURLString: https://updateserver.sun.com/solaris/
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@764be1 <=serverURLString: https://updateserver.sun.com/solaris/
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@6fd0b7 <=isPatchServer: false
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@764be1 <=isPatchServer: false
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@6fd0b7 <=This server is SSL-enabled. https://updateserver.sun.com/solaris/
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@764be1 <=This server is SSL-enabled. https://updateserver.sun.com/solaris/
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@764be1 <=cacheFile is: /var/sadm/spool/cache/https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fdetectors.jar
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@6fd0b7 <=cacheFile is: /var/sadm/spool/cache/https%3A%2F%2Fupdateserver.sun.com%2Fsolaris%2F%2Fpatchdb.zip
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.server.ServerPatchServiceProvider@6fd0b7 <=POST String: action=downloadPatchDB&name=patchdb&version=2.1
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$4@113fe2 <=downloadDetectors(): finished
Fri Sep 15 19:01:53 CEST 2006(DEBUG)=> com.sun.patchpro.util.State@723d7c <=State 3 did not complete with null
Fri Sep 15 19:01:54 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=setDone()
#
_c_b_a at 2007-7-21 15:24:20 >

# 18
Hi.Could you export the variable first:# export LANG=C# smpatch analyzeIf it's not exported, it won't propagate to subshells.
# 19
Yes, I noticed my mistake and did it the right way meanwhile. Nothing changed.
Then I combined your previous suggestions and deleted the cache prior to starting the debug smpatch run with LANG exported properly. The resulting logfile is 355 lines long -- should I post it here? There seems to appear something interesting at the end of the file:
# rm /var/sadm/spool/cache/*
# export LANG=C
# smpatch analyze -C patchpro.internal.statustags=true -C patchpro.log.level=7 -C patchpro.debug=true -C patchpro.log.file=/tmp/smpatchDebug.log
Realization integrity checks failed
No patches required.
# tail -17 /tmp/smpatchDebug.log
Fri Sep 15 20:04:30 CEST 2006(DEBUG)=> com.sun.patchpro.patch.PatchSequencer@3e0f0a <=PatchSequencer.removeObsoletedPatch(): The existing patch obsoletes list is
Fri Sep 15 20:04:30 CEST 2006(DEBUG)=> com.sun.patchpro.patch.PatchSequencer@3e0f0a <=PatchSequencer.removeObsoletedPatch(): The existing patch os is SunOS
Fri Sep 15 20:04:30 CEST 2006(DEBUG)=> com.sun.patchpro.patch.PatchSequencer@3e0f0a <=PatchSequencer.processPatch(): Patch 109571-02 is not installed on the system.
Fri Sep 15 20:04:30 CEST 2006(DEBUG)=> com.sun.patchpro.patch.PatchSequencer@3e0f0a <=PatchSequencer.getObsolescingIPatch(): Entered getObsolescingIPatch().
Fri Sep 15 20:04:30 CEST 2006(DEBUG)=> com.sun.patchpro.patch.PatchSequencer@3e0f0a <=PatchSequencer.getObsolescingIPatch(): The patch 109571-02 is obsolete.
Fri Sep 15 20:04:30 CEST 2006(DEBUG)=> com.sun.patchpro.patch.PatchSequencer@3e0f0a <=PatchSequencer.getObsolescingIPatch(): The patch 109571-02is obsolesced by 108528-05
Fri Sep 15 20:04:30 CEST 2006(DEBUG)=> com.sun.patchpro.patch.PatchSequencer@3e0f0a <=Installed patch 108528-05 is >= to current patch 108528-05
Fri Sep 15 20:04:30 CEST 2006(DEBUG)=> com.sun.patchpro.patch.PatchSequencer@3e0f0a <=PatchSequencer.getObsolescingIPatch(): Obsolecing patch is installed on the system.
Fri Sep 15 20:04:30 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$9@67d940 <=runSequencer(): finished, returning com.sun.patchpro.host.ExtendedHost@5663a2
Fri Sep 15 20:04:30 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine$14@683912 <=downloadOrStartOver(): returning 9
Fri Sep 15 20:04:31 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=reached the end state.
Fri Sep 15 20:04:31 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=reading state 8
Fri Sep 15 20:04:31 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=State 8 is not terminal.
Fri Sep 15 20:04:31 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=nextStates =
Fri Sep 15 20:04:31 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <= 9
Fri Sep 15 20:04:31 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=StateMachine is officially done.
Fri Sep 15 20:04:31 CEST 2006(DEBUG)=> com.sun.patchpro.model.PatchProStateMachine@389838 <=setDone()
#
Best,
cb
_c_b_a at 2007-7-21 15:24:20 >

# 20
Hi again.I have a feeling there may be some inconsistency in your install which is causing the failure. Do you have a contract you could raise a support case on?
# 21
Hello again,> Do you have a contract you could raise a support case on?Yes, we have. Are you suggesting I should that?I highly appreciate your kind assistance. Thank you very much indeed.Best,cb
_c_b_a at 2007-7-21 15:24:20 >

# 22
Hi.I think that would be a good idea - it's difficult to troubleshoot issues like these on public fora.Please do post back when a resolution is achieved.