Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
INFO: INFO DoorLog - [RoomJdbcImplHelper - jdbc:sqlite::memory:] - useNewConnectionAsyncInternal connection #1 Attempting to rollback transaction #1
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
INFO: INFO DoorLog - [RoomJdbcImplHelper - jdbc:sqlite::memory:] - useNewConnectionAsyncInternal connection #1 Attempting to rollback transaction #1
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
INFO: INFO DoorLog - [RoomJdbcImplHelper - jdbc:sqlite::memory:] - useNewConnectionAsyncInternal connection #1 Rolled back changes
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
INFO: INFO DoorLog - [RoomJdbcImplHelper - jdbc:sqlite::memory:] - useNewConnectionAsyncInternal connection #1 Rolled back changes
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
SEVERE: SEVERE DoorLog - [prepareAndUseStatementAsync - jdbc:sqlite::memory:] - Exception running INSERT INTO UserSession (usUid, usPcsn, usLcsn, usLcb, usLct, usPersonUid, usClientNodeId, usStartTime, usEndTime, usStatus, usReason, usAuth, usSessionType) VALUES(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
java.lang.NullPointerException: Cannot invoke "java.sql.Connection.setAutoCommit(boolean)" because "connection" is null
at com.zaxxer.hikari.pool.PoolBase.resetConnectionState(PoolBase.java:206)
at com.zaxxer.hikari.pool.PoolEntry.resetConnectionState(PoolEntry.java:108)
at com.zaxxer.hikari.pool.ProxyConnection.close(ProxyConnection.java:236)
at com.ustadmobile.door.room.RoomDatabaseJdbcImplHelperCommon.useNewConnectionAsyncInternal(RoomDatabaseJdbcImplHelperCommon.kt:197)
at com.ustadmobile.door.room.RoomDatabaseJdbcImplHelperCommon.access$useNewConnectionAsyncInternal(RoomDatabaseJdbcImplHelperCommon.kt:21)
at com.ustadmobile.door.room.RoomDatabaseJdbcImplHelperCommon$useNewConnectionAsyncInternal$1.invokeSuspend(RoomDatabaseJdbcImplHelperCommon.kt)
at kotlin.coroutines.jvm.internal.BaseContinuationImpl.resumeWith(ContinuationImpl.kt:33)
at kotlinx.coroutines.DispatchedTask.run(DispatchedTask.kt:102)
at kotlinx.coroutines.scheduling.CoroutineScheduler.runSafely(CoroutineScheduler.kt:585)
at kotlinx.coroutines.scheduling.CoroutineScheduler$Worker.executeTask(CoroutineScheduler.kt:802)
at kotlinx.coroutines.scheduling.CoroutineScheduler$Worker.runWorker(CoroutineScheduler.kt:706)
at kotlinx.coroutines.scheduling.CoroutineScheduler$Worker.run(CoroutineScheduler.kt:693)
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
SEVERE: SEVERE DoorLog - [prepareAndUseStatementAsync - jdbc:sqlite::memory:] - Exception running INSERT INTO UserSession (usUid, usPcsn, usLcsn, usLcb, usLct, usPersonUid, usClientNodeId, usStartTime, usEndTime, usStatus, usReason, usAuth, usSessionType) VALUES(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
java.lang.NullPointerException: Cannot invoke "java.sql.Connection.setAutoCommit(boolean)" because "connection" is null
at com.zaxxer.hikari.pool.PoolBase.resetConnectionState(PoolBase.java:206)
at com.zaxxer.hikari.pool.PoolEntry.resetConnectionState(PoolEntry.java:108)
at com.zaxxer.hikari.pool.ProxyConnection.close(ProxyConnection.java:236)
at com.ustadmobile.door.room.RoomDatabaseJdbcImplHelperCommon.useNewConnectionAsyncInternal(RoomDatabaseJdbcImplHelperCommon.kt:197)
at com.ustadmobile.door.room.RoomDatabaseJdbcImplHelperCommon.access$useNewConnectionAsyncInternal(RoomDatabaseJdbcImplHelperCommon.kt:21)
at com.ustadmobile.door.room.RoomDatabaseJdbcImplHelperCommon$useNewConnectionAsyncInternal$1.invokeSuspend(RoomDatabaseJdbcImplHelperCommon.kt)
at kotlin.coroutines.jvm.internal.BaseContinuationImpl.resumeWith(ContinuationImpl.kt:33)
at kotlinx.coroutines.DispatchedTask.run(DispatchedTask.kt:102)
at kotlinx.coroutines.scheduling.CoroutineScheduler.runSafely(CoroutineScheduler.kt:585)
at kotlinx.coroutines.scheduling.CoroutineScheduler$Worker.executeTask(CoroutineScheduler.kt:802)
at kotlinx.coroutines.scheduling.CoroutineScheduler$Worker.runWorker(CoroutineScheduler.kt:706)
at kotlinx.coroutines.scheduling.CoroutineScheduler$Worker.run(CoroutineScheduler.kt:693)
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
FINE: FINER DoorLog - [RoomJdbcImplHelper - jdbc:sqlite::memory:] - useNewConnectionAsyncInternal connection #1 : SQLite Change Tracker: Changed tables=[UserSession]
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
FINE: FINER DoorLog - [RoomJdbcImplHelper - jdbc:sqlite::memory:] - useNewConnectionAsyncInternal connection #1 : SQLite Change Tracker: Changed tables=[Person, PersonGroup, PersonGroupMember, ScopedGrant, UserSession]
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
FINE: FINER DoorLog - Closed HikariDataSource connection pool
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
FINE: FINER DoorLog - Closed HikariDataSource connection pool
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
FINE: FINER DoorLog - [RoomJdbcImplHelper - jdbc:sqlite::memory:] - useNewConnectionAsyncInternal connection #1 : SQLite Change Tracker: Changed tables=[UserSession]
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
FINE: FINER DoorLog - [RoomJdbcImplHelper - jdbc:sqlite::memory:] - useNewConnectionAsyncInternal connection #1 : SQLite Change Tracker: Changed tables=[Person, PersonGroup, PersonGroupMember, ScopedGrant, UserSession]
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
FINE: FINER DoorLog - Closed HikariDataSource connection pool
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
FINE: FINER DoorLog - Closed HikariDataSource connection pool
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
WARNING: WARNING DoorLog - [NodeEventSseClient localNodeId=5732659720899484409 remoteEndpoint=http://localhost.localdomain:33835/UmAppDatabase/] : onError
java.io.IOException: other event source error
at com.ustadmobile.door.sse.DoorEventSource$eventSourceListener$1.onFailure(DoorEventSource.kt:44)
at okhttp3.internal.sse.RealEventSource.processResponse(RealEventSource.kt:52)
at okhttp3.internal.sse.RealEventSource.onResponse(RealEventSource.kt:46)
at okhttp3.internal.connection.RealCall$AsyncCall.run(RealCall.kt:519)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
WARNING: WARNING DoorLog - [NodeEventSseClient localNodeId=5732659720899484409 remoteEndpoint=http://localhost.localdomain:33835/UmAppDatabase/] : onError
java.io.IOException: other event source error
at com.ustadmobile.door.sse.DoorEventSource$eventSourceListener$1.onFailure(DoorEventSource.kt:44)
at okhttp3.internal.sse.RealEventSource.processResponse(RealEventSource.kt:52)
at okhttp3.internal.sse.RealEventSource.onResponse(RealEventSource.kt:46)
at okhttp3.internal.connection.RealCall$AsyncCall.run(RealCall.kt:519)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
SEVERE: SEVERE DoorEventSource$eventSourceListener$1$onFailure$invokeSuspend - [DoorEventSource@com.ustadmobile.door.sse.DoorEventSource@3720f2e4 - http://localhost.localdomain:33835/UmAppDatabase/replication/sse?door-node=5732659720899484409%2F8d9085ae-4cc6-4bee-a211-4f76294c3172] error: java.io.IOException: other event source error . Attempting to reconnect after 2000ms
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
SEVERE: SEVERE DoorEventSource$eventSourceListener$1$onFailure$invokeSuspend - [DoorEventSource@com.ustadmobile.door.sse.DoorEventSource@3720f2e4 - http://localhost.localdomain:33835/UmAppDatabase/replication/sse?door-node=5732659720899484409%2F8d9085ae-4cc6-4bee-a211-4f76294c3172] error: java.io.IOException: other event source error . Attempting to reconnect after 2000ms
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
FINE: FINER DoorLog - [RoomJdbcImplHelper - jdbc:sqlite::memory:] - useNewConnectionAsyncInternal connection #1 : SQLite Change Tracker: Changed tables=[UserSession]
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
FINE: FINER DoorLog - [RoomJdbcImplHelper - jdbc:sqlite::memory:] - useNewConnectionAsyncInternal connection #1 : SQLite Change Tracker: Changed tables=[Person, PersonGroup, PersonGroupMember, ScopedGrant, UserSession]
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
FINE: FINER DoorLog - Closed HikariDataSource connection pool
Jun 12, 2025 7:09:11 AM com.ustadmobile.core.util.NapierAntilogJvm performLog
FINE: FINER DoorLog - Closed HikariDataSource connection pool