hibernate - 为什么使用 Hibernate Interceptor 的审计日志会在 SocketInputStream.socketRead0 处挂起？

Question

这对我来说是莫名其妙。我已经搜索了许多论坛以寻找线索。在 postFlush 方法中，我使用不同的 session 工厂打开一个新的 hibernate (v. 3.3 - 也尝试过 v.3.5) session 。我使用 c3p0 v. 0.9 进行连接池。我开始一个新事务，保存 auditLog 对象，并提交事务。这对我的所有实体都非常有效，除了一个。在删除 ChemoRegimen 实体后尝试提交审计日志时，应用程序挂起(这也会发生在创建和更新时)。没有抛出异常，但在挂起线程时，我发现以下堆栈跟踪(这是一个 Swing 应用程序):

Thread [AWT-EventQueue-0] (Suspended)   
SocketInputStream.socketRead0(FileDescriptor, byte[], int, int, int) line: not available [native method]    
SocketInputStream.read(byte[], int, int) line: 129  
VisibleBufferedInputStream.readMore(int) line: 145  
VisibleBufferedInputStream.ensureBytes(int) line: 114   
VisibleBufferedInputStream.read() line: 73  
PGStream.ReceiveChar() line: 274    
QueryExecutorImpl.processResults(ResultHandler, int) line: 1660 
QueryExecutorImpl.execute(Query[], ParameterList[], ResultHandler, int, int, int) line: 407 
Jdbc4PreparedStatement(AbstractJdbc2Statement).executeBatch() line: 2737    
NewProxyPreparedStatement.executeBatch() line: 1723 
BatchingBatcher.doExecuteBatch(PreparedStatement) line: 70  
BatchingBatcher(AbstractBatcher).executeBatch() line: 268   
ActionQueue.executeActions(List) line: 266  
ActionQueue.executeActions() line: 167  
DefaultFlushEventListener(AbstractFlushingEventListener).performExecutions(EventSource) line: 321   
DefaultFlushEventListener.onFlush(FlushEvent) line: 50  
SessionImpl.flush() line: 1027  
SessionImpl.managedFlush() line: 365    
JDBCTransaction.commit() line: 137  **[This is where I commit the auditLog]**
MomsInterceptor.postFlush(Iterator) line: 254   
DefaultFlushEventListener(AbstractFlushingEventListener).postFlush(SessionImplementor) line: 375    
DefaultFlushEventListener.onFlush(FlushEvent) line: 51  
SessionImpl.flush() line: 1027  
SessionImpl.managedFlush() line: 365    
JDBCTransaction.commit() line: 137  
HibernateDAO.makeTransient(Entity) line: 119    
ChemoServices.deleteChemoRegimen(ChemoRegimen, String, Session) line: 290   

我使用 PostgreSQL 8.4 作为后端和 9.0 jdbc4 驱动程序。 postgresql.log 显示 [括号内是我的评论]:

【首先删除chemo_regimen】

2011-05-11 12:19:06 CDT moms postgres LOG:  00000: execute <unnamed>: delete from moms_chemo_regimen where crxreg_id=$1<BR>

....
【大量级联删除】
...
【然后，我的交易从拦截器 session 开始】

2011-05-11 12:19:06 CDT moms postgres LOG:  00000: execute S_1: BEGIN
2011-05-11 12:19:06 CDT moms postgres LOG:  00000: execute <unnamed>: select nextval ('moms_patient_change_log_seq')

[插入审计日志记录]

2011-05-11 12:19:06 CDT moms postgres LOG:  00000: execute <unnamed>: insert into moms_patient_change_log (patclog_pat_id, patclog_action, patclog_reason, patclog_date, patclog_user_name, patclog_guid, patclog_id) values ($1, $2, $3, $4, $5, $6, $7)
2011-05-11 12:19:06 CDT moms postgres DETAIL:  parameters: $1 = '17108', $2 = 'Deleted ChemoRegimen ABVD', $3 = NULL, $4 = '2011-05-11 12:19:06.813', $5 = 'daver', $6 = 'BFAA9D91-7A4E-835E-7A57-B72B2A79A4F1', $7 = '520'

就是这样。审计日志插入事务永远不会完成。删除化疗方案的事务也永远不会提交。不审计时，我可以在 ChemoRegimen 上执行 CRUD。 ChemoRegimen 实体的片段如下:

public class ChemoRegimen extends MOMSEntity implements Auditable
{
  public static final String UNSCHEDULED = "UNSCHEDULED";

  private Date date = new Date();
  private Patient patient;
  private WorkingProtocol protocol;
  private Physician approvingPhysician;
  private boolean canChangeCycles;
  private List<ChemoEncounter> chemoEncounters = new ArrayList<ChemoEncounter>();
  private boolean complete;
  private Icdm icdm;<BR>
  ...<BR>
}

这是我的拦截器:

public class MomsInterceptor extends EmptyInterceptor
{
  private static Logger logger = Logger.getLogger( MomsInterceptor.class.getName() );
  private static Configuration configuration;
  private static SessionFactory sessionFactory;

//Create the initial SessionFactory from the default configuration files
  static
  {
    initSessionFactory();
  }

  public static void initSessionFactory()
  {
    try
    {
      configuration = new Configuration().configure();
      sessionFactory = configuration.buildSessionFactory();
    }
    catch ( Throwable ex )
    {
      // We have to catch Throwable, otherwise we will miss
      // NoClassDefFoundError and other subclasses of Error
      logger.severe( "Building SessionFactory failed - " + ex.getMessage() );
      System.err.println( "Building SessionFactory failed - " + ex.getMessage() );
      throw new ExceptionInInitializerError( ex.getMessage() );
    }
  }

  private Set<Auditable> inserts = new HashSet<Auditable>();
  private Set<UpdatedEntity> updates = new HashSet<UpdatedEntity>();
  private Set<Auditable> deletes = new HashSet<Auditable>();
  private boolean audit;

  public MomsInterceptor(boolean audit)
  {
    super();
    this.audit = audit;
  }

  private class UpdatedEntity
  {
    private Auditable auditable;
    private String[] propertyNames;
    private Object[] currentState;
    private Object[] previousState;
    private Type[] types;

    public UpdatedEntity( Auditable auditable, String[] propertyNames, Type[] types, Object[] currentState, Object[] previousState )
    {
      super();
      this.auditable = auditable;
      this.propertyNames = propertyNames;
      this.currentState = currentState;
      this.previousState = previousState;
      this.types = types;
    }

    public Auditable getAuditable()
    {
      return auditable;
    }

    public String[] getPropertyNames()
    {
      return propertyNames;
    }

    public Object[] getCurrentState()
    {
      return currentState;
    }

    public Object[] getPreviousState()
    {
      return previousState;
    }

    public Type[] getTypes()
    {
      return types;
    }

    /**
     * Return the previous value of the property name prop or null if the property name is not found.
     * @param prop
     * @return
     */
    public Object getPrevious( String prop )
    {
      int i = 0;
      for ( String name : propertyNames )
      {
        if ( prop.equals( name ) )
          return previousState[i];
        i++;
      }

      return null;
    }
  }

  public boolean onSave( Object entity, Serializable id, Object[] state, String[] propertyNames, Type[] types ) throws CallbackException
  {
    boolean modified = false;
    if ( entity instanceof MutableEntity ) // Update create info.
    {
      MutableEntity me = (MutableEntity)entity;
      int i = findPropertyNameIndex( "createUser", propertyNames );
      if ( i >= 0 )
        state[i] = SessionController.userName;
      i = findPropertyNameIndex( "modifyUser", propertyNames );
      if ( i >= 0 )
        state[i] = SessionController.userName;
      modified = true;

      if ( audit && entity instanceof Auditable ) 
        inserts.add( (Auditable)entity );
    }

    return modified;
  }

  private int findPropertyNameIndex( String name, String[] propertyNames )
  {
    int i = -1;
    if ( propertyNames.length == 0 )
      return i;

    for ( String p : propertyNames )
    {
      i++;
      if ( p.equals( name ) )
        return i;
    }

    return -1;
  }

  public boolean onFlushDirty( Object entity, Serializable id, Object[] currentState, Object[] previousState, String[] propertyNames, Type[] types )
      throws CallbackException
  {
    boolean modified = false;

    if ( entity instanceof MutableEntity ) // Update modify info.
    {
      MutableEntity me = (MutableEntity)entity;
      int i = findPropertyNameIndex( "modifyUser", propertyNames );
      if ( i >= 0 )
        currentState[i] = SessionController.userName;
      i = findPropertyNameIndex( "modifyDate", propertyNames );
      if ( i >= 0 )
        currentState[i] = new Date();
      modified = true;

      if ( audit && entity instanceof Auditable )
        updates.add( new UpdatedEntity( (Auditable)entity, propertyNames, types, currentState, previousState ) );
    }

    return modified;
  }

  @Override
  public void onDelete( Object entity, Serializable id, Object[] state, String[] propertyNames, Type[] types )
  {
    if ( audit && entity instanceof Auditable )
      deletes.add( (Auditable)entity );
  }

  @Override
  public void postFlush( Iterator iterator ) throws CallbackException
  {
    if ( inserts.isEmpty() && deletes.isEmpty() && updates.isEmpty() )
      return;

    Session session = sessionFactory.openSession();
    session.setFlushMode( FlushMode.COMMIT );
    session.beginTransaction();

    try
    {
      String action = null;
      for ( Auditable entity : inserts )
      {
        action = "Created " + entity.getClass().getSimpleName() + " " + entity.toString();
        session.save( new PatientChangeLog( entity.getPatient(), action, entity.getReason(), SessionController.userName ) );
      }
      for ( Auditable entity : deletes )
      {
        action = "Deleted " + entity.getClass().getSimpleName() + " " + entity.toString();
        session.save( new PatientChangeLog( entity.getPatient(), action, entity.getReason(), SessionController.userName ) );
      }
      for ( UpdatedEntity entity : updates )
      {
        Auditable a = entity.getAuditable();
        StringBuffer actionBuf = new StringBuffer( "Updated " + a.getClass().getSimpleName() + " " + a.toString() + ": changed " );
        int count = 0;
        for ( int i = 0; i < entity.getPropertyNames().length; i++ )
        {
          String prop = entity.getPropertyNames()[i];
          Type type = entity.getTypes()[i];
          Object curValue = entity.getCurrentState()[i];
          Object prevValue = entity.getPreviousState()[i];

          //Don't consider the id field or the metadata fields.
          if ( prop.equals( "id" ) || prop.equals( "createUser" ) || prop.equals( "createDate" ) || prop.equals( "modifyUser" ) 
              || prop.equals( "modifyDate" ) || prop.equals( "guid" ) )
            continue;

          if ( prevValue == null )
            prevValue = new String( "" );
          if ( curValue == null )
            curValue = new String( "" );
          if ( !prevValue.equals( curValue ) )
          {
            if ( count > 0 )
              actionBuf.append( " and " );
            actionBuf.append( prop ).append( " from '" ).append( prevValue ).append( "' to '" ).append( curValue ).append( "'" );
            count++;
          }   
        }

        Patient p = (Patient)entity.getPrevious( "patient" );  //In case the patient is changed, tie it to the previous patient.
        session.save( new PatientChangeLog( p, actionBuf.toString(), a.getReason(), SessionController.userName ) );
      }

      session.getTransaction().commit();
    }
    catch ( HibernateException e )
    {
      try
      {
        session.getTransaction().rollback();
      }
      catch ( Exception hex )
      {
        throw new RuntimeException( hex );
      }
      throw new RuntimeException( e );
    }
    finally
    {
      inserts.clear();
      updates.clear();
      deletes.clear();
      session.close();
    }
  }
}

任何帮助将不胜感激。

Answer 1

无法找到合适的文档来使用 Hibernate 事件代替 Envers，我决定将代码从 MyInterceptor.postFlush() 移走。至 MyInterceptor.afterTransactionCompletion() .通过这种方式，在我为审计日志记录打开新 session /事务之前提交了当前事务。感谢您帮助我们走到这一步。