[Mulgara-general] Exception on startup

Paul Gearon gearon at ieee.org
Wed Apr 23 23:09:47 UTC 2008


I haven't ever seen an IO exception during a force before. While
Windows has some issues with file mapping, this one seems really odd.
Is it a normal filesystem?

The error you are seeing is happening while an uncommitted transaction
is being committed. (Did the system shut down cleanly?)  Once you get
it up and running it might be fine to continue as normal. If that's
the case, then forcing the system to NOT use file mappings should get
you going again.  Do this by updating the command line to include the
following:
java -Dmulgara.xa.forceIOType=explicit -jar mulgara-1.x.x.jar

If this works, then you can either continue running like this, or else
shut down cleanly and start up again normally.

Regards,
Paul

On Wed, Apr 23, 2008 at 5:57 PM, jopaki <jopaki at gmail.com> wrote:
> Out of the blue, I am getting the following exception upon Mulgara startup
> running on my Windows XP dev machine:
> --------------
>      [java] INFO 11:59 MulgaraInternalTransaction> Committing Transaction:
> 4779445, state=ACTUNREF, inuse=1, using=0
>       [java] ERROR 12:00 XAStatementStoreImpl> I/O error while performing
> prepare.
>       [java] java.io.IOException
>      [java]     at java.nio.MappedByteBuffer
> .force0(Native Method)
>      [java]     at
> java.nio.MappedByteBuffer.force(MappedByteBuffer.java:144)
>       [java]     at
> org.mulgara.store.xa.MappedBlockFile.force(MappedBlockFile.java:218)
>       [java]     at
> org.mulgara.store.xa.ManagedBlockFile.force(ManagedBlockFile.java:147)
>      [java]     at org.mulgara.store.xa.AVLFile.force(AVLFile.java:170)
>      [java]     at
> org.mulgara.store.statement.xa.TripleAVLFile.force(TripleAVLFile.java:279)
>       [java]     at
> org.mulgara.store.statement.xa.XAStatementStoreImpl.prepare(XAStatementStoreImpl.java:817)
>      [java]     at
> org.mulgara.resolver.StringPoolSession.prepare(StringPoolSession.java:242)
>      [java]     at
> org.mulgara.resolver.store.StatementStoreXAResource.doPrepare(StatementStoreXAResource.java:123)
>       [java]     at
> org.mulgara.resolver.store.StatementStoreXAResource.doPrepare(StatementStoreXAResource.java:59)
>      [java]     at
> org.mulgara.resolver.spi.AbstractXAResource.commit(AbstractXAResource.java:216)
>       [java]     at
> org.objectweb.jotm.SubCoordinator.doOnePhaseCommit(SubCoordinator.java:1255)
>       [java]     at
> org.objectweb.jotm.SubCoordinator.commit_one_phase(SubCoordinator.java:451)
>      [java]     at
> org.objectweb.jotm.TransactionImpl.commit(TransactionImpl.java:239)
>      [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.commitTransaction(MulgaraInternalTransaction.java:377)
>       [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.deactivate(MulgaraInternalTransaction.java:206)
>      [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.execute(MulgaraInternalTransaction.java:583)
>       [java]     at
> org.mulgara.resolver.DatabaseSession.execute(DatabaseSession.java:625)
>      [java]     at
> org.mulgara.resolver.DatabaseSession.bootstrapSystemModel(DatabaseSession.java:283)
>      [java]     at org.mulgara.resolver.Database.<init>(Database.java:694)
>       [java]     at org.mulgara.resolver.Database.<init>(Database.java:320)
>      [java]     at
> sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
>      [java]     at
> sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
>       [java]     at
> sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
>      [java]     at
> java.lang.reflect.Constructor.newInstance(Constructor.java:513)
>      [java]     at
> org.mulgara.server.AbstractServer.init(AbstractServer.java:275)
>       [java]     at
> org.mulgara.server.EmbeddedMulgaraServer.startServer(EmbeddedMulgaraServer.java:1581)
>      [java]     at
> org.mulgara.server.EmbeddedMulgaraServer.main(EmbeddedMulgaraServer.java:500)
>      [java]     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native
> Method)
>       [java]     at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>      [java]     at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
>      [java]     at java.lang.reflect.Method.invoke(Method.java:597)
>       [java]     at
> org.mulgara.util.Bootstrap.invokeClass(Bootstrap.java:525)
>      [java]     at org.mulgara.util.Bootstrap.main(Bootstrap.java:229)
>      [java] ERROR 12:00 XAStatementStoreImpl> Prepare failed.
>       [java] ERROR 12:00 AbstractXAResource> Attempt to prepare in
> onePhaseCommit failed.
>      [java] org.mulgara.store.xa.SimpleXAResourceException: I/O error while
> performing prepare.
>      [java]     at
> org.mulgara.store.statement.xa.XAStatementStoreImpl.prepare(XAStatementStoreImpl.java:840)
>       [java]     at
> org.mulgara.resolver.StringPoolSession.prepare(StringPoolSession.java:242)
>      [java]     at
> org.mulgara.resolver.store.StatementStoreXAResource.doPrepare(StatementStoreXAResource.java:123)
>      [java]     at
> org.mulgara.resolver.store.StatementStoreXAResource.doPrepare(StatementStoreXAResource.java:59)
>       [java]     at
> org.mulgara.resolver.spi.AbstractXAResource.commit(AbstractXAResource.java:216)
>      [java]     at
> org.objectweb.jotm.SubCoordinator.doOnePhaseCommit(SubCoordinator.java:1255)
>      [java]     at
> org.objectweb.jotm.SubCoordinator.commit_one_phase(SubCoordinator.java:451)
>       [java]     at
> org.objectweb.jotm.TransactionImpl.commit(TransactionImpl.java:239)
>      [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.commitTransaction(MulgaraInternalTransaction.java:377)
>      [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.deactivate(MulgaraInternalTransaction.java:206)
>       [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.execute(MulgaraInternalTransaction.java:583)
>      [java]     at
> org.mulgara.resolver.DatabaseSession.execute(DatabaseSession.java:625)
>      [java]     at
> org.mulgara.resolver.DatabaseSession.bootstrapSystemModel(DatabaseSession.java:283)
>       [java]     at org.mulgara.resolver.Database.<init>(Database.java:694)
>      [java]     at org.mulgara.resolver.Database.<init>(Database.java:320)
>      [java]     at
> sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
>       [java]     at
> sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
>      [java]     at
> sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
>       [java]     at
> java.lang.reflect.Constructor.newInstance(Constructor.java:513)
>      [java]     at
> org.mulgara.server.AbstractServer.init(AbstractServer.java:275)
>      [java]     at
> org.mulgara.server.EmbeddedMulgaraServer.startServer(EmbeddedMulgaraServer.java:1581)
>       [java]     at
> org.mulgara.server.EmbeddedMulgaraServer.main(EmbeddedMulgaraServer.java:500)
>      [java]     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native
> Method)
>      [java]     at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>       [java]     at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
>      [java]     at java.lang.reflect.Method.invoke(Method.java:597)
>      [java]     at
> org.mulgara.util.Bootstrap.invokeClass(Bootstrap.java:525)
>       [java]     at org.mulgara.util.Bootstrap.main(Bootstrap.java:229)
>      [java] Caused by: java.io.IOException
>      [java]     at java.nio.MappedByteBuffer.force0(Native Method)
>      [java]     at
> java.nio.MappedByteBuffer.force(MappedByteBuffer.java:144)
>       [java]     at
> org.mulgara.store.xa.MappedBlockFile.force(MappedBlockFile.java:218)
>      [java]     at
> org.mulgara.store.xa.ManagedBlockFile.force(ManagedBlockFile.java:147)
>      [java]     at org.mulgara.store.xa.AVLFile.force(AVLFile.java:170)
>       [java]     at
> org.mulgara.store.statement.xa.TripleAVLFile.force(TripleAVLFile.java:279)
>      [java]     at
> org.mulgara.store.statement.xa.XAStatementStoreImpl.prepare(XAStatementStoreImpl.java:817)
>      [java]     ... 27 more
>       [java] ERROR 12:00 jotm> Got XAException from res.commit: Cannot send
> res.commit:javax.transaction.xa.XAException (error code = 100) --null
>      [java] INFO 12:00 MulgaraInternalTransaction> Implicit Rollback
> triggered: 4779445, state=ACTUNREF, inuse=1, using=0
>       [java] ERROR 12:00 jotm> rollback: bad status: STATUS_UNKNOWN
>      [java] INFO 12:00 MulgaraInternalTransaction> Leaving implicitRollback:
> 4779445, state=FAILED, inuse=1, using=0
>      [java] INFO 12:00 MulgaraInternalTransaction> Executed Operation:
> 4779445, state=FAILED, inuse=1, using=0
>       [java] INFO 12:00 DatabaseSession> Error executing operation: Failed
> to bootstrap system-model
>      [java] org.mulgara.query.MulgaraTransactionException: Transaction
> rollback triggered
>      [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.implicitRollback(MulgaraInternalTransaction.java:488)
>       [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.commitTransaction(MulgaraInternalTransaction.java:379)
>      [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.deactivate(MulgaraInternalTransaction.java:206)
>       [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.execute(MulgaraInternalTransaction.java:583)
>      [java]     at
> org.mulgara.resolver.DatabaseSession.execute(DatabaseSession.java:625)
>      [java]     at
> org.mulgara.resolver.DatabaseSession.bootstrapSystemModel(DatabaseSession.java:283)
>       [java]     at org.mulgara.resolver.Database.<init>(Database.java:694)
>      [java]     at org.mulgara.resolver.Database.<init>(Database.java:320)
>      [java]     at
> sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
>       [java]     at
> sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
>      [java]     at
> sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
>       [java]     at
> java.lang.reflect.Constructor.newInstance(Constructor.java:513)
>      [java]     at
> org.mulgara.server.AbstractServer.init(AbstractServer.java:275)
>      [java]     at
> org.mulgara.server.EmbeddedMulgaraServer.startServer(EmbeddedMulgaraServer.java:1581)
>       [java]     at
> org.mulgara.server.EmbeddedMulgaraServer.main(EmbeddedMulgaraServer.java:500)
>      [java]     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native
> Method)
>      [java]     at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>       [java]     at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
>      [java]     at java.lang.reflect.Method.invoke(Method.java:597)
>      [java]     at
> org.mulgara.util.Bootstrap.invokeClass(Bootstrap.java:525)
>       [java]     at org.mulgara.util.Bootstrap.main(Bootstrap.java:229)
>      [java] Caused by: javax.transaction.RollbackException
>      [java]     at
> org.objectweb.jotm.TransactionImpl.commit(TransactionImpl.java:245)
>       [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.commitTransaction(MulgaraInternalTransaction.java:377)
>      [java]     ... 19 more
>      [java] ERROR 12:00 EmbeddedMulgaraServer> Exception in main
>       [java] java.lang.reflect.InvocationTargetException
>      [java]     at
> sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
>      [java]     at
> sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
>       [java]     at
> sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
>      [java] java.lang.reflect.InvocationTargetException
>      [java]     at
> java.lang.reflect.Constructor.newInstance(Constructor.java:513)
>       [java]     at
> org.mulgara.server.AbstractServer.init(AbstractServer.java:275)
>      [java]     at
> org.mulgara.server.EmbeddedMulgaraServer.startServer(EmbeddedMulgaraServer.java:1581)
>      [java]     at
> org.mulgara.server.EmbeddedMulgaraServer.main(EmbeddedMulgaraServer.java:500)
>       [java]     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native
> Method)
>      [java]     at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>      [java]     at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
>       [java]     at java.lang.reflect.Method.invoke(Method.java:597)
>      [java]     at
> org.mulgara.util.Bootstrap.invokeClass(Bootstrap.java:525)
>      [java]     at org.mulgara.util.Bootstrap.main(Bootstrap.java:229)
>       [java] Caused by: org.mulgara.query.QueryException: Failed to
> bootstrap system-model
>      [java]     at
> org.mulgara.resolver.DatabaseSession.execute(DatabaseSession.java:628)
>      [java]     at
> org.mulgara.resolver.DatabaseSession.bootstrapSystemModel(DatabaseSession.java:283)
>       [java]     at
> sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
>      [java]     at org.mulgara.resolver.Database.<init>(Database.java:694)
>      [java]     at org.mulgara.resolver.Database.<init>(Database.java:320)
>       [java]     ... 13 more
>      [java] Caused by: org.mulgara.query.MulgaraTransactionException:
> Transaction rollback triggered
>      [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.implicitRollback(MulgaraInternalTransaction.java:488)
>       [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.commitTransaction(MulgaraInternalTransaction.java:379)
>      [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.deactivate(MulgaraInternalTransaction.java:206)
>       [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.execute(MulgaraInternalTransaction.java:583)
>      [java]     at
> org.mulgara.resolver.DatabaseSession.execute(DatabaseSession.java:625)
>      [java]     ... 16 more
>       [java] Caused by: javax.transaction.RollbackException
>      [java]     at
> org.objectweb.jotm.TransactionImpl.commit(TransactionImpl.java:245)
>      [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.commitTransaction(MulgaraInternalTransaction.java:377)
>       [java]     ... 19 more
>      [java]     at
> sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
>      [java]     at
> sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
>       [java]     at
> java.lang.reflect.Constructor.newInstance(Constructor.java:513)
>      [java]     at
> org.mulgara.server.AbstractServer.init(AbstractServer.java:275)
>      [java]     at
> org.mulgara.server.EmbeddedMulgaraServer.startServer(EmbeddedMulgaraServer.java:1581)
>       [java]     at
> org.mulgara.server.EmbeddedMulgaraServer.main(EmbeddedMulgaraServer.java:500)
>      [java]     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native
> Method)
>      [java]     at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>       [java]     at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
>      [java]     at java.lang.reflect.Method.invoke(Method.java:597)
>      [java]     at
> org.mulgara.util.Bootstrap.invokeClass(Bootstrap.java:525)
>       [java]     at org.mulgara.util.Bootstrap.main(Bootstrap.java:229)
>      [java] Caused by: org.mulgara.query.QueryException: Failed to bootstrap
> system-model
>      [java]     at
> org.mulgara.resolver.DatabaseSession.execute(DatabaseSession.java:628)
>       [java]     at
> org.mulgara.resolver.DatabaseSession.bootstrapSystemModel(DatabaseSession.java:283)
>      [java]     at org.mulgara.resolver.Database.<init>(Database.java:694)
>      [java]     at org.mulgara.resolver.Database.<init>(Database.java:320)
>       [java]     ... 13 more
>      [java] Caused by: org.mulgara.query.MulgaraTransactionException:
> Transaction rollback triggered
>      [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.implicitRollback(MulgaraInternalTransaction.java:488)
>       [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.commitTransaction(MulgaraInternalTransaction.java:379)
>      [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.deactivate(MulgaraInternalTransaction.java:206)
>       [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.execute(MulgaraInternalTransaction.java:583)
>      [java]     at
> org.mulgara.resolver.DatabaseSession.execute(DatabaseSession.java:625)
>      [java]     ... 16 more
>       [java] Caused by: javax.transaction.RollbackException
>      [java]     at
> org.objectweb.jotm.TransactionImpl.commit(TransactionImpl.java:245)
>      [java]     at
> org.mulgara.resolver.MulgaraInternalTransaction.commitTransaction(MulgaraInternalTransaction.java:377)
>       [java]     ... 19 more
> ---------------
>
> Any ideas appreciated.  -Jon
>
> _______________________________________________
>  Mulgara-general mailing list
>  Mulgara-general at mulgara.org
>  http://mulgara.org/mailman/listinfo/mulgara-general
>
>



More information about the Mulgara-general mailing list