"ORA-20297: Call get_log_id need to follow set_log_auth"

Issue

• Changing channel subscriptions in System Set Manager doesn't finish, SSM task remains stuck "In Progress"
• In /var/log/tomcat6/catalina.out, for Satellite using external Oracle database:

com.redhat.rhncommon.db.WrappedSQLException: ORA-20297: Call get_log_id need to follow set_log_auth.
ORA-06512: at "RHNSAT.LOGGING", line 29
ORA-06512: at "RHNSAT.RHNSERVER_LOG_TRIG", line 5
ORA-04088: error during execution of trigger 'RHNSAT.RHNSERVER_LOG_TRIG'
ORA-06512: at "RHNSAT.RHN_CHANNEL", line 500
ORA-06512: at line 1
    at com.redhat.rhn.common.translation.SqlExceptionTranslator.oracleSQLException(SqlExceptionTranslator.java:82)
    at com.redhat.rhn.common.translation.SqlExceptionTranslator.sqlException(SqlExceptionTranslator.java:42)
    at com.redhat.rhn.common.db.datasource.CachedStatement.executeCallable(CachedStatement.java:543)
    at com.redhat.rhn.common.db.datasource.CallableMode.execute(CallableMode.java:35)
    at com.redhat.rhn.manager.ssm.SsmManager.unsubscribeChannel(SsmManager.java:213)
    at com.redhat.rhn.manager.ssm.SsmManager.performChannelActions(SsmManager.java:187)
    at com.redhat.rhn.frontend.events.SsmChangeChannelSubscriptionsAction.doExecute(SsmChangeChannelSubscriptionsAction.java:46)
    at com.redhat.rhn.frontend.events.AbstractDatabaseAction.execute(AbstractDatabaseAction.java:47)
    at com.redhat.rhn.common.messaging.ActionExecutor.run(ActionExecutor.java:69)
    at com.redhat.rhn.common.messaging.MessageDispatcher.run(MessageDispatcher.java:58)
    at java.lang.Thread.run(Thread.java:761)

• In /var/log/tomcat6/catalina.out, for Satellite using Postgresql database:

May 8, 2014 11:10:00 AM com.mchange.v2.c3p0.impl.NewPooledConnection handleThrowable
WARNING: [c3p0] A PooledConnection that has already signalled a Connection error is still in use!
May 8, 2014 11:10:00 AM com.mchange.v2.c3p0.impl.NewPooledConnection handleThrowable
WARNING: [c3p0] Another error has occurred [ org.postgresql.util.PSQLException: ERROR: current transaction is aborted, commands ignored until end of transaction block ] which will not be reported to listeners!
Throwable occurred: org.postgresql.util.PSQLException: ERROR: current transaction is aborted, commands ignored until end of transaction block
    at org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2062)
    at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1795)
    at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:257)
    at org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:479)
    at org.postgresql.jdbc2.AbstractJdbc2Statement.executeWithFlags(AbstractJdbc2Statement.java:367)
    at org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:360)
    at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.execute(NewProxyPreparedStatement.java:651)
    at com.redhat.rhn.common.db.NamedPreparedStatement.execute(NamedPreparedStatement.java:140)
    at com.redhat.rhn.common.db.datasource.CachedStatement.execute(CachedStatement.java:467)
    at com.redhat.rhn.common.db.datasource.CachedStatement.execute(CachedStatement.java:443)
    at com.redhat.rhn.common.db.datasource.CachedStatement.executeUpdate(CachedStatement.java:276)
    at com.redhat.rhn.common.db.datasource.WriteMode.executeUpdate(WriteMode.java:33)
    at com.redhat.rhn.manager.ssm.SsmOperationManager.completeOperation(SsmOperationManager.java:217)
    at com.redhat.rhn.frontend.events.SsmChangeChannelSubscriptionsAction.doExecute(SsmChangeChannelSubscriptionsAction.java:50)
    at com.redhat.rhn.frontend.events.AbstractDatabaseAction.execute(AbstractDatabaseAction.java:47)
    at com.redhat.rhn.common.messaging.ActionExecutor.run(ActionExecutor.java:69)
    at com.redhat.rhn.common.messaging.MessageDispatcher.run(MessageDispatcher.java:58)
    at java.lang.Thread.run(Thread.java:761)
2014-05-08 11:10:00,161 [RHN Message Dispatcher] ERROR com.redhat.rhn.common.db.datasource.CachedStatement - Error while processing cached statement sql: UPDATE rhnSsmOperation
         SET status = ?,
             modified = current_timestamp
       WHERE id = ?
         AND user_id = ?
com.redhat.rhn.common.db.WrappedSQLException: ERROR: current transaction is aborted, commands ignored until end of transaction block
    at com.redhat.rhn.common.translation.SqlExceptionTranslator.postgreSqlException(SqlExceptionTranslator.java:54)
    at com.redhat.rhn.common.translation.SqlExceptionTranslator.sqlException(SqlExceptionTranslator.java:44)
    at com.redhat.rhn.common.db.NamedPreparedStatement.execute(NamedPreparedStatement.java:143)
    at com.redhat.rhn.common.db.datasource.CachedStatement.execute(CachedStatement.java:467)
    at com.redhat.rhn.common.db.datasource.CachedStatement.execute(CachedStatement.java:443)
    at com.redhat.rhn.common.db.datasource.CachedStatement.executeUpdate(CachedStatement.java:276)
    at com.redhat.rhn.common.db.datasource.WriteMode.executeUpdate(WriteMode.java:33)
    at com.redhat.rhn.manager.ssm.SsmOperationManager.completeOperation(SsmOperationManager.java:217)
    at com.redhat.rhn.frontend.events.SsmChangeChannelSubscriptionsAction.doExecute(SsmChangeChannelSubscriptionsAction.java:50)
    at com.redhat.rhn.frontend.events.AbstractDatabaseAction.execute(AbstractDatabaseAction.java:47)
    at com.redhat.rhn.common.messaging.ActionExecutor.run(ActionExecutor.java:69)
    at com.redhat.rhn.common.messaging.MessageDispatcher.run(MessageDispatcher.java:58)
    at java.lang.Thread.run(Thread.java:761)
Caused by: 
org.postgresql.util.PSQLException: ERROR: current transaction is aborted, commands ignored until end of transaction block
    at org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2062)
    at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1795)
    at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:257)
    at org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:479)
    at org.postgresql.jdbc2.AbstractJdbc2Statement.executeWithFlags(AbstractJdbc2Statement.java:367)
    at org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:360)
    at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.execute(NewProxyPreparedStatement.java:651)
    at com.redhat.rhn.common.db.NamedPreparedStatement.execute(NamedPreparedStatement.java:140)
    ... 10 more
com.redhat.rhn.common.db.WrappedSQLException: ERROR: current transaction is aborted, commands ignored until end of transaction block
    at com.redhat.rhn.common.translation.SqlExceptionTranslator.postgreSqlException(SqlExceptionTranslator.java:54)
    at com.redhat.rhn.common.translation.SqlExceptionTranslator.sqlException(SqlExceptionTranslator.java:44)
    at com.redhat.rhn.common.db.NamedPreparedStatement.execute(NamedPreparedStatement.java:143)
    at com.redhat.rhn.common.db.datasource.CachedStatement.execute(CachedStatement.java:467)
    at com.redhat.rhn.common.db.datasource.CachedStatement.execute(CachedStatement.java:443)
    at com.redhat.rhn.common.db.datasource.CachedStatement.executeUpdate(CachedStatement.java:276)
    at com.redhat.rhn.common.db.datasource.WriteMode.executeUpdate(WriteMode.java:33)
    at com.redhat.rhn.manager.ssm.SsmOperationManager.completeOperation(SsmOperationManager.java:217)
    at com.redhat.rhn.frontend.events.SsmChangeChannelSubscriptionsAction.doExecute(SsmChangeChannelSubscriptionsAction.java:50)
    at com.redhat.rhn.frontend.events.AbstractDatabaseAction.execute(AbstractDatabaseAction.java:47)
    at com.redhat.rhn.common.messaging.ActionExecutor.run(ActionExecutor.java:69)
    at com.redhat.rhn.common.messaging.MessageDispatcher.run(MessageDispatcher.java:58)
    at java.lang.Thread.run(Thread.java:761)
Caused by: org.postgresql.util.PSQLException: ERROR: current transaction is aborted, commands ignored until end of transaction block
    at org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2062)
    at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1795)
    at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:257)
    at org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:479)
    at org.postgresql.jdbc2.AbstractJdbc2Statement.executeWithFlags(AbstractJdbc2Statement.java:367)
    at org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:360)
    at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.execute(NewProxyPreparedStatement.java:651)
    at com.redhat.rhn.common.db.NamedPreparedStatement.execute(NamedPreparedStatement.java:140)
    ... 10 more

• Error in postgresql log /var/lib/pgsql/data/pg_log/postgresql-.log:

2014-05-08 11:10:00.158 EDT ERROR:  can't read "the_log_id": no such variable
2014-05-08 11:10:00.158 EDT CONTEXT:  can't read "the_log_id": no such variable
        while executing
    "return $the_log_id"
        (procedure "__PLTcl_proc_23717" line 4)
        invoked from within
    "__PLTcl_proc_23717"
    in PL/Tcl function "_get_log_id"
    PL/pgSQL function "get_log_id" line 6 at assignment
    PL/pgSQL function "rhnserver_log_trig_fun" line 4 at assignment
    SQL statement "update rhnServer set channels_changed = current_timestamp where id =  $1 "
    PL/pgSQL function "subscribe_server" line 102 at SQL statement
2014-05-08 11:10:00.158 EDT STATEMENT:  select * from rhn_channel.subscribe_server($1, $2, 1, $3) as result
2014-05-08 11:10:00.159 EDT ERROR:  current transaction is aborted, commands ignored until end of transaction block
2014-05-08 11:10:00.159 EDT STATEMENT:  select 'c3p0 ping' from dual
2014-05-08 11:10:00.159 EDT ERROR:  current transaction is aborted, commands ignored until end of transaction block
2014-05-08 11:10:00.159 EDT STATEMENT:  UPDATE rhnSsmOperation
             SET status = $1,
                 modified = current_timestamp
           WHERE id = $2
             AND user_id = $3
2014-05-08 11:10:00.159 EDT ERROR:  current transaction is aborted, commands ignored until end of transaction block
2014-05-08 11:10:00.159 EDT STATEMENT:  select 'c3p0 ping' from dual