2025-06-01 07:35:07.879 [main] INFO  Application - Autoreload is disabled because the development mode is off.
2025-06-01 07:35:08.178 [main] INFO  c.u.core.logging.LogbackAntiLog - [INFO] UmRestApplicationKt$umRestApplication - UstadServer dataDir=/home/jenkins/.jenkins/workspace/dev-simplified-onboarding-end-to-end-tests/test-end-to-end/webapp-cypress/../../testserver-controller/build/data/server-40240
2025-06-01 07:35:08.212 [main] WARN  c.u.core.logging.LogbackAntiLog - [WARN] UmRestApplicationKt$umRestApplication$invoke - WARNING: Email sending not configured
2025-06-01 07:35:08.212 [main] WARN  c.u.core.logging.LogbackAntiLog - [WARN] UmRestApplicationKt$umRestApplication$invoke - WARNING: SMS. sending not configured /home/jenkins/.jenkins/workspace/dev-simplified-onboarding-end-to-end-tests/test-end-to-end/webapp-cypress/../../app-ktor-server/src/main/resources/application.conf: 1: No configuration setting found for key 'sms'
2025-06-01 07:35:08.223 [main] DEBUG org.osjava.sj.jndi.MemoryContext - MemoryContext#lookup() java: not found in MemoryContext{namesToObjects={}, subContexts={}, env={java.naming.factory.initial=org.osjava.sj.MemoryContextFactory, org.osjava.sj.delimiter=/, jndi.syntax.direction=left_to_right, org.osjava.sj.root=, org.osjava.sj.space=java:/comp/env, java.naming.factory.object=org.apache.commons.dbcp2.BasicDataSourceFactory:org.apache.commons.dbcp2.BasicDataSourceFactory, org.osjava.sj.factory=org.osjava.sj.MemoryContextFactory, jndi.syntax.separator=/, org.osjava.sj.jndi.shared=true}, nameParser=org.osjava.sj.jndi.SimpleNameParser@44841b43, nameInNamespace=, nameLock=false}
2025-06-01 07:35:08.224 [main] DEBUG org.osjava.sj.jndi.MemoryContext - MemoryContext#lookup() comp not found in MemoryContext{namesToObjects={}, subContexts={}, env={java.naming.factory.initial=org.osjava.sj.MemoryContextFactory, org.osjava.sj.delimiter=/, jndi.syntax.direction=left_to_right, org.osjava.sj.root=, org.osjava.sj.space=java:/comp/env, java.naming.factory.object=org.apache.commons.dbcp2.BasicDataSourceFactory:org.apache.commons.dbcp2.BasicDataSourceFactory, org.osjava.sj.factory=org.osjava.sj.MemoryContextFactory, jndi.syntax.separator=/, org.osjava.sj.jndi.shared=true}, nameParser=org.osjava.sj.jndi.SimpleNameParser@6ac0b715, nameInNamespace=java:, nameLock=true}
2025-06-01 07:35:08.224 [main] DEBUG org.osjava.sj.jndi.MemoryContext - MemoryContext#lookup() env not found in MemoryContext{namesToObjects={}, subContexts={}, env={java.naming.factory.initial=org.osjava.sj.MemoryContextFactory, org.osjava.sj.delimiter=/, jndi.syntax.direction=left_to_right, org.osjava.sj.root=, org.osjava.sj.space=java:/comp/env, java.naming.factory.object=org.apache.commons.dbcp2.BasicDataSourceFactory:org.apache.commons.dbcp2.BasicDataSourceFactory, org.osjava.sj.factory=org.osjava.sj.MemoryContextFactory, jndi.syntax.separator=/, org.osjava.sj.jndi.shared=true}, nameParser=org.osjava.sj.jndi.SimpleNameParser@468be356, nameInNamespace=java:/comp, nameLock=true}
2025-06-01 07:35:08.225 [main] WARN  org.osjava.sj.SimpleJndi - Mistakenly no root provided?
2025-06-01 07:35:08.225 [main] DEBUG org.osjava.sj.jndi.MemoryContext - MemoryContext#lookup() jdbc not found in MemoryContext{namesToObjects={}, subContexts={}, env={java.naming.factory.initial=org.osjava.sj.MemoryContextFactory, org.osjava.sj.delimiter=/, jndi.syntax.direction=left_to_right, org.osjava.sj.root=, org.osjava.sj.space=java:/comp/env, java.naming.factory.object=org.apache.commons.dbcp2.BasicDataSourceFactory:org.apache.commons.dbcp2.BasicDataSourceFactory, org.osjava.sj.factory=org.osjava.sj.MemoryContextFactory, jndi.syntax.separator=/, org.osjava.sj.jndi.shared=true}, nameParser=org.osjava.sj.jndi.SimpleNameParser@7a93b263, nameInNamespace=java:/comp/env, nameLock=true}
2025-06-01 07:35:08.225 [main] DEBUG org.osjava.sj.jndi.MemoryContext - MemoryContext#lookup() jdbc not found in MemoryContext{namesToObjects={}, subContexts={}, env={java.naming.factory.initial=org.osjava.sj.MemoryContextFactory, org.osjava.sj.delimiter=/, jndi.syntax.direction=left_to_right, org.osjava.sj.root=, org.osjava.sj.space=java:/comp/env, java.naming.factory.object=org.apache.commons.dbcp2.BasicDataSourceFactory:org.apache.commons.dbcp2.BasicDataSourceFactory, org.osjava.sj.factory=org.osjava.sj.MemoryContextFactory, jndi.syntax.separator=/, org.osjava.sj.jndi.shared=true}, nameParser=org.osjava.sj.jndi.SimpleNameParser@7a93b263, nameInNamespace=java:/comp/env, nameLock=true}
2025-06-01 07:35:08.226 [main] DEBUG org.osjava.sj.jndi.MemoryContext - MemoryContext#lookup() quartzds not found in MemoryContext{namesToObjects={}, subContexts={}, env={java.naming.factory.initial=org.osjava.sj.MemoryContextFactory, org.osjava.sj.delimiter=/, jndi.syntax.direction=left_to_right, org.osjava.sj.root=, org.osjava.sj.space=java:/comp/env, java.naming.factory.object=org.apache.commons.dbcp2.BasicDataSourceFactory:org.apache.commons.dbcp2.BasicDataSourceFactory, org.osjava.sj.factory=org.osjava.sj.MemoryContextFactory, jndi.syntax.separator=/, org.osjava.sj.jndi.shared=true}, nameParser=org.osjava.sj.jndi.SimpleNameParser@1de6932a, nameInNamespace=java:/comp/env/jdbc, nameLock=true}
2025-06-01 07:35:08.407 [main] TRACE org.osjava.sj.jndi.MemoryContext - Unbind quartzds
2025-06-01 07:35:08.407 [main] INFO  org.quartz.impl.StdSchedulerFactory - Using default implementation for ThreadExecutor
2025-06-01 07:35:08.411 [main] INFO  o.quartz.core.SchedulerSignalerImpl - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
2025-06-01 07:35:08.411 [main] INFO  org.quartz.core.QuartzScheduler - Quartz Scheduler v.2.3.2 created.
2025-06-01 07:35:08.411 [main] INFO  o.q.impl.jdbcjobstore.JobStoreTX - Using thread monitor-based data access locking (synchronization).
2025-06-01 07:35:08.412 [main] INFO  o.q.impl.jdbcjobstore.JobStoreTX - JobStoreTX initialized.
2025-06-01 07:35:08.412 [main] INFO  org.quartz.core.QuartzScheduler - Scheduler meta-data: Quartz Scheduler (v2.3.2) 'MyScheduler' with instanceId 'NON_CLUSTERED'
  Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
  NOT STARTED.
  Currently in standby mode.
  Number of jobs executed: 0
  Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 3 threads.
  Using job-store 'org.quartz.impl.jdbcjobstore.JobStoreTX' - which supports persistence. and is not clustered.

2025-06-01 07:35:08.412 [main] INFO  org.quartz.impl.StdSchedulerFactory - Quartz scheduler 'MyScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
2025-06-01 07:35:08.412 [main] INFO  org.quartz.impl.StdSchedulerFactory - Quartz scheduler version: 2.3.2
2025-06-01 07:35:08.412 [main] DEBUG o.q.i.jdbcjobstore.SimpleSemaphore - Lock 'TRIGGER_ACCESS' is desired by: main
2025-06-01 07:35:08.412 [main] DEBUG o.q.i.jdbcjobstore.SimpleSemaphore - Lock 'TRIGGER_ACCESS' is being obtained: main
2025-06-01 07:35:08.412 [main] DEBUG o.q.i.jdbcjobstore.SimpleSemaphore - Lock 'TRIGGER_ACCESS' given to: main
2025-06-01 07:35:08.417 [main] DEBUG o.q.impl.jdbcjobstore.JobStoreTX - Adding TriggerPersistenceDelegate of type: org.quartz.impl.jdbcjobstore.SimpleTriggerPersistenceDelegate
2025-06-01 07:35:08.417 [main] DEBUG o.q.impl.jdbcjobstore.JobStoreTX - Adding TriggerPersistenceDelegate of type: org.quartz.impl.jdbcjobstore.CronTriggerPersistenceDelegate
2025-06-01 07:35:08.417 [main] DEBUG o.q.impl.jdbcjobstore.JobStoreTX - Adding TriggerPersistenceDelegate of type: org.quartz.impl.jdbcjobstore.CalendarIntervalTriggerPersistenceDelegate
2025-06-01 07:35:08.418 [main] DEBUG o.q.impl.jdbcjobstore.JobStoreTX - Adding TriggerPersistenceDelegate of type: org.quartz.impl.jdbcjobstore.DailyTimeIntervalTriggerPersistenceDelegate
2025-06-01 07:35:08.422 [main] INFO  o.q.impl.jdbcjobstore.JobStoreTX - Freed 0 triggers from 'acquired' / 'blocked' state.
2025-06-01 07:35:08.423 [main] DEBUG o.q.impl.jdbcjobstore.JobStoreTX - Found 0 triggers that missed their scheduled fire-time.
2025-06-01 07:35:08.423 [main] INFO  o.q.impl.jdbcjobstore.JobStoreTX - Recovering 0 jobs that were in-progress at the time of the last shut-down.
2025-06-01 07:35:08.423 [main] INFO  o.q.impl.jdbcjobstore.JobStoreTX - Recovery complete.
2025-06-01 07:35:08.424 [main] INFO  o.q.impl.jdbcjobstore.JobStoreTX - Removed 0 'complete' triggers.
2025-06-01 07:35:08.424 [main] INFO  o.q.impl.jdbcjobstore.JobStoreTX - Removed 0 stale fired job entries.
2025-06-01 07:35:08.424 [main] DEBUG o.q.i.jdbcjobstore.SimpleSemaphore - Lock 'TRIGGER_ACCESS' retuned by: main
2025-06-01 07:35:08.431 [main] DEBUG o.q.impl.jdbcjobstore.JobStoreTX - JobStore background threads started (as scheduler was started).
2025-06-01 07:35:08.431 [main] INFO  org.quartz.core.QuartzScheduler - Scheduler MyScheduler_$_NON_CLUSTERED started.
2025-06-01 07:35:08.431 [QuartzScheduler_MyScheduler-NON_CLUSTERED_MisfireHandler] DEBUG o.q.impl.jdbcjobstore.JobStoreTX - MisfireHandler: scanning for misfires...
2025-06-01 07:35:08.432 [QuartzScheduler_MyScheduler-NON_CLUSTERED_MisfireHandler] DEBUG o.q.impl.jdbcjobstore.JobStoreTX - Found 0 triggers that missed their scheduled fire-time.
2025-06-01 07:35:08.439 [MyScheduler_QuartzSchedulerThread] DEBUG o.quartz.core.QuartzSchedulerThread - batch acquisition of 0 triggers
2025-06-01 07:35:08.479 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] pragma busy_timeout=3000
2025-06-01 07:35:08.481 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] CREATE TABLE LearningSpaceEntity (
    lsUid INTEGER PRIMARY KEY NOT NULL,
    lsUrl TEXT NOT NULL,
    lsName TEXT NOT NULL,
    lsDescription TEXT NOT NULL,
    lsLastModified INTEGER NOT NULL DEFAULT 0,
    lsDbUrl TEXT NOT NULL,
    lsDbUsername TEXT,
    lsDbPassword TEXT
)
2025-06-01 07:35:08.508 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] begin;
2025-06-01 07:35:08.508 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] commit;
2025-06-01 07:35:08.510 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] pragma busy_timeout=3000
2025-06-01 07:35:08.510 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] CREATE TABLE SystemConfigAuth(
    scaUid INTEGER PRIMARY KEY NOT NULL,
    scaAuthType INTEGER NOT NULL DEFAULT 0,
    scaAuthId TEXT NOT NULL,
    scaAuthCredential TEXT NOT NULL,
    scaAuthSalt TEXT NOT NULL
)
2025-06-01 07:35:08.536 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] begin;
2025-06-01 07:35:08.536 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] commit;
2025-06-01 07:35:08.575 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] pragma busy_timeout=3000
2025-06-01 07:35:08.575 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] INSERT INTO SystemConfigAuth(scaUid, scaAuthType, scaAuthId , scaAuthCredential, scaAuthSalt)
VALUES (?, ?, ?, ?, ?)
2025-06-01 07:35:08.601 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] begin;
2025-06-01 07:35:08.601 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] commit;
2025-06-01 07:35:08.601 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] SELECT last_insert_rowid();
2025-06-01 07:35:08.646 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] pragma busy_timeout=3000
2025-06-01 07:35:08.646 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] SELECT LearningSpaceEntity.lsUid, LearningSpaceEntity.lsUrl, LearningSpaceEntity.lsName, LearningSpaceEntity.lsDescription, LearningSpaceEntity.lsLastModified, LearningSpaceEntity.lsDbUrl, LearningSpaceEntity.lsDbUsername, LearningSpaceEntity.lsDbPassword
FROM LearningSpaceEntity
2025-06-01 07:35:08.646 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] begin;
2025-06-01 07:35:08.646 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] commit;
2025-06-01 07:35:08.657 [main] DEBUG com.zaxxer.hikari.HikariConfig - HikariPool-1 - configuration:
2025-06-01 07:35:08.658 [main] DEBUG com.zaxxer.hikari.HikariConfig - allowPoolSuspension.............false
2025-06-01 07:35:08.658 [main] DEBUG com.zaxxer.hikari.HikariConfig - autoCommit......................true
2025-06-01 07:35:08.658 [main] DEBUG com.zaxxer.hikari.HikariConfig - catalog.........................none
2025-06-01 07:35:08.658 [main] DEBUG com.zaxxer.hikari.HikariConfig - connectionInitSql...............none
2025-06-01 07:35:08.658 [main] DEBUG com.zaxxer.hikari.HikariConfig - connectionTestQuery.............none
2025-06-01 07:35:08.658 [main] DEBUG com.zaxxer.hikari.HikariConfig - connectionTimeout...............30000
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - dataSource......................org.sqlite.SQLiteDataSource@3aaa3c39
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - dataSourceClassName.............none
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - dataSourceJNDI..................none
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - dataSourceProperties............{password=<masked>}
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - driverClassName.................none
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - healthCheckProperties...........{}
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - healthCheckRegistry.............none
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - idleTimeout.....................600000
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - initializationFailFast..........true
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - initializationFailTimeout.......1
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - isolateInternalQueries..........false
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - jdbc4ConnectionTest.............false
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - jdbcUrl.........................none
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - leakDetectionThreshold..........0
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - maxLifetime.....................1800000
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - maximumPoolSize.................10
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - metricRegistry..................none
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - metricsTrackerFactory...........none
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - minimumIdle.....................10
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - password........................<masked>
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - poolName........................"HikariPool-1"
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - readOnly........................false
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - registerMbeans..................false
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - scheduledExecutor...............none
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - scheduledExecutorService........internal
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - threadFactory...................internal
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - transactionIsolation............default
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - username........................none
2025-06-01 07:35:08.659 [main] DEBUG com.zaxxer.hikari.HikariConfig - validationTimeout...............5000
2025-06-01 07:35:08.660 [main] INFO  com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Starting...
2025-06-01 07:35:08.663 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] pragma synchronous=OFF
2025-06-01 07:35:08.663 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] begin;
2025-06-01 07:35:08.663 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] commit;
2025-06-01 07:35:08.663 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] pragma busy_timeout=30000
2025-06-01 07:35:08.663 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] pragma journal_mode=WAL
2025-06-01 07:35:08.663 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] pragma recursive_triggers=true
2025-06-01 07:35:08.663 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] pragma transaction_mode=DEFERRED
2025-06-01 07:35:08.663 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] select 1
2025-06-01 07:35:08.664 [main] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@b0fd744
2025-06-01 07:35:08.664 [main] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@b0fd744
2025-06-01 07:35:08.664 [main] INFO  com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Start completed.
2025-06-01 07:35:08.671 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] SELECT
  NULL AS TABLE_CAT,
  NULL AS TABLE_SCHEM,
  NAME AS TABLE_NAME,
  TYPE AS TABLE_TYPE,
  NULL AS REMARKS,
  NULL AS TYPE_CAT,
  NULL AS TYPE_SCHEM,
  NULL AS TYPE_NAME,
  NULL AS SELF_REFERENCING_COL_NAME,
  NULL AS REF_GENERATION
FROM
  (
    SELECT
      'sqlite_schema' AS NAME,
      'SYSTEM TABLE' AS TYPE    UNION ALL
    SELECT
      NAME,
      UPPER(TYPE) AS TYPE
    FROM
      sqlite_schema
    WHERE
      NAME NOT LIKE 'sqlite\_%' ESCAPE '\'
      AND UPPER(TYPE) IN ('TABLE', 'VIEW')
    UNION ALL
    SELECT
      NAME,
      'GLOBAL TEMPORARY' AS TYPE
    FROM
      sqlite_temp_master
    UNION ALL
    SELECT
      NAME,
      'SYSTEM TABLE' AS TYPE
    FROM
      sqlite_schema
    WHERE
      NAME LIKE 'sqlite\_%' ESCAPE '\'
  )
 WHERE TABLE_NAME LIKE '%' ESCAPE '\' AND TABLE_TYPE IN ('TABLE') ORDER BY TABLE_TYPE, TABLE_NAME;
2025-06-01 07:35:08.672 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] CREATE TABLE IF NOT EXISTS _doorwayinfo (dbVersion int primary key, dbHash varchar(255))
2025-06-01 07:35:08.673 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] INSERT INTO _doorwayinfo VALUES (9, '')
2025-06-01 07:35:08.673 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] SELECT last_insert_rowid();
2025-06-01 07:35:08.673 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] CREATE TABLE IF NOT EXISTS CacheEntry (  key  TEXT  PRIMARY KEY  NOT NULL , url  TEXT  NOT NULL , message  TEXT  NOT NULL , statusCode  INTEGER  NOT NULL , cacheFlags  INTEGER  NOT NULL , method  INTEGER  NOT NULL , lastAccessed  INTEGER  NOT NULL , lastValidated  INTEGER  NOT NULL , integrity  TEXT , responseHeaders  TEXT  NOT NULL , storageUri  TEXT  NOT NULL , storageSize  INTEGER  NOT NULL , uncompressedSize  INTEGER  NOT NULL  DEFAULT 0 )
2025-06-01 07:35:08.673 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] CREATE INDEX idx_lastAccessed ON CacheEntry (lastAccessed)
2025-06-01 07:35:08.673 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] CREATE TABLE IF NOT EXISTS RequestedEntry (  requestSha256  TEXT  NOT NULL , requestedKey  TEXT  NOT NULL , batchId  INTEGER  NOT NULL , id  INTEGER  PRIMARY KEY  AUTOINCREMENT  NOT NULL )
2025-06-01 07:35:08.673 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] CREATE TABLE IF NOT EXISTS RetentionLock (  lockKey  TEXT  NOT NULL , lockRemark  TEXT  NOT NULL , lockId  INTEGER  PRIMARY KEY  AUTOINCREMENT  NOT NULL )
2025-06-01 07:35:08.673 [main] TRACE org.sqlite.core.NativeDB - DriverManager [main] [SQLite EXEC] CREATE INDEX idx_lockKey ON RetentionLock (lockKey)
2025-06-01 07:35:08.679 [main] INFO  c.u.core.logging.LogbackAntiLog - [INFO] DoorLog - [DatabaseBuilder.build - jdbc:sqlite:/home/jenkins/.jenkins/workspace/dev-simplified-onboarding-end-to-end-tests/test-end-to-end/webapp-cypress/../../testserver-controller/build/data/server-40240/ustadcache.db] database build complete
2025-06-01 07:35:08.689 [main] INFO  Application - Application started in 0.824 seconds.
2025-06-01 07:35:08.765 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Pool stats (total=1, active=0, idle=1, waiting=0)
2025-06-01 07:35:08.765 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma synchronous=OFF
2025-06-01 07:35:08.766 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] begin;
2025-06-01 07:35:08.766 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] commit;
2025-06-01 07:35:08.766 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma busy_timeout=30000
2025-06-01 07:35:08.766 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma journal_mode=WAL
2025-06-01 07:35:08.766 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma recursive_triggers=true
2025-06-01 07:35:08.766 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma transaction_mode=DEFERRED
2025-06-01 07:35:08.766 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@3e806dc8
2025-06-01 07:35:08.766 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@3e806dc8
2025-06-01 07:35:08.766 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma synchronous=OFF
2025-06-01 07:35:08.766 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] begin;
2025-06-01 07:35:08.767 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] commit;
2025-06-01 07:35:08.767 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma busy_timeout=30000
2025-06-01 07:35:08.767 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma journal_mode=WAL
2025-06-01 07:35:08.767 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma recursive_triggers=true
2025-06-01 07:35:08.767 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma transaction_mode=DEFERRED
2025-06-01 07:35:08.767 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@30478468
2025-06-01 07:35:08.767 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@30478468
2025-06-01 07:35:08.767 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma synchronous=OFF
2025-06-01 07:35:08.767 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] begin;
2025-06-01 07:35:08.767 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] commit;
2025-06-01 07:35:08.767 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma busy_timeout=30000
2025-06-01 07:35:08.767 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma journal_mode=WAL
2025-06-01 07:35:08.767 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma recursive_triggers=true
2025-06-01 07:35:08.767 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma transaction_mode=DEFERRED
2025-06-01 07:35:08.767 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@347806b8
2025-06-01 07:35:08.767 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@347806b8
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma synchronous=OFF
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] begin;
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] commit;
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma busy_timeout=30000
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma journal_mode=WAL
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma recursive_triggers=true
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma transaction_mode=DEFERRED
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@717a8e53
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@717a8e53
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma synchronous=OFF
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] begin;
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] commit;
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma busy_timeout=30000
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma journal_mode=WAL
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma recursive_triggers=true
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma transaction_mode=DEFERRED
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@4c294399
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@4c294399
2025-06-01 07:35:08.768 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma synchronous=OFF
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] begin;
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] commit;
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma busy_timeout=30000
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma journal_mode=WAL
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma recursive_triggers=true
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma transaction_mode=DEFERRED
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@4041fab0
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@4041fab0
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma synchronous=OFF
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] begin;
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] commit;
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma busy_timeout=30000
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma journal_mode=WAL
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma recursive_triggers=true
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma transaction_mode=DEFERRED
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@34f869c6
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@34f869c6
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma synchronous=OFF
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] begin;
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] commit;
2025-06-01 07:35:08.769 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma busy_timeout=30000
2025-06-01 07:35:08.770 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma journal_mode=WAL
2025-06-01 07:35:08.770 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma recursive_triggers=true
2025-06-01 07:35:08.770 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma transaction_mode=DEFERRED
2025-06-01 07:35:08.770 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@1155711c
2025-06-01 07:35:08.770 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@1155711c
2025-06-01 07:35:08.770 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma synchronous=OFF
2025-06-01 07:35:08.770 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] begin;
2025-06-01 07:35:08.770 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] commit;
2025-06-01 07:35:08.770 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma busy_timeout=30000
2025-06-01 07:35:08.770 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma journal_mode=WAL
2025-06-01 07:35:08.770 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma recursive_triggers=true
2025-06-01 07:35:08.770 [HikariPool-1 connection adder] TRACE org.sqlite.core.NativeDB - DriverManager [HikariPool-1 connection adder] [SQLite EXEC] pragma transaction_mode=DEFERRED
2025-06-01 07:35:08.770 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@17e6dd24
2025-06-01 07:35:08.770 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@17e6dd24
2025-06-01 07:35:08.770 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After adding stats (total=10, active=0, idle=10, waiting=0)
2025-06-01 07:35:13.623 [Thread-8] INFO  c.u.core.logging.LogbackAntiLog - [INFO] UmRestApplicationKt$umRestApplication$invoke$lambda$0 - UmRestApplication: Shutdown hook
2025-06-01 07:35:13.623 [Thread-8] INFO  org.quartz.core.QuartzScheduler - Scheduler MyScheduler_$_NON_CLUSTERED shutting down.
2025-06-01 07:35:13.623 [Thread-8] INFO  org.quartz.core.QuartzScheduler - Scheduler MyScheduler_$_NON_CLUSTERED paused.
2025-06-01 07:35:13.623 [Thread-8] DEBUG org.quartz.simpl.SimpleThreadPool - Shutting down threadpool...
2025-06-01 07:35:13.624 [Thread-8] DEBUG org.quartz.simpl.SimpleThreadPool - Shutdown of threadpool complete.
2025-06-01 07:35:13.624 [Thread-8] DEBUG o.q.impl.jdbcjobstore.JobStoreTX - JobStore background threads shutdown.
2025-06-01 07:35:13.624 [Thread-8] INFO  org.quartz.core.QuartzScheduler - Scheduler MyScheduler_$_NON_CLUSTERED shutdown complete.
2025-06-01 07:35:13.909 [MyScheduler_Worker-3] DEBUG org.quartz.simpl.SimpleThreadPool - WorkerThread is shut down.
2025-06-01 07:35:13.909 [MyScheduler_Worker-1] DEBUG org.quartz.simpl.SimpleThreadPool - WorkerThread is shut down.
2025-06-01 07:35:13.909 [MyScheduler_Worker-2] DEBUG org.quartz.simpl.SimpleThreadPool - WorkerThread is shut down.