4.7279997false07400com.ustadmobile.lib.rest.InsertDefaultSiteCallbackTest0.3010givenDatabaseCreated_thenWhenOpened_itShouldBloodyWellBeThereFfsfalsePASSED16:38:14,807 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.6 16:38:14,808 |-INFO in ch.qos.logback.classic.util.ContextInitializer@58ffcbd7 - No custom configurators were discovered as a service. 16:38:14,808 |-INFO in ch.qos.logback.classic.util.ContextInitializer@58ffcbd7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 16:38:14,809 |-INFO in ch.qos.logback.classic.util.ContextInitializer@58ffcbd7 - Constructed ...[truncated 3442 chars]... level of logger [io.netty] to INFO 16:38:14,900 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3c9bfddc - End of configuration. 16:38:14,900 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@1a9c38eb - Registering current configuration as safe fallback point 16:38:14,900 |-INFO in ch.qos.logback.classic.util.ContextInitializer@58ffcbd7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 86 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY 0.301com.ustadmobile.lib.rest.InsertDefaultSiteCallbackTest16:38:14,807 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.6 16:38:14,808 |-INFO in ch.qos.logback.classic.util.ContextInitializer@58ffcbd7 - No custom configurators were discovered as a service. 16:38:14,808 |-INFO in ch.qos.logback.classic.util.ContextInitializer@58ffcbd7 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 16:38:14,809 |-INFO in ch.qos.logback.classic.util.ContextInitializer@58ffcbd7 - Constructed ...[truncated 3442 chars]... level of logger [io.netty] to INFO 16:38:14,900 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3c9bfddc - End of configuration. 16:38:14,900 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@1a9c38eb - Registering current configuration as safe fallback point 16:38:14,900 |-INFO in ch.qos.logback.classic.util.ContextInitializer@58ffcbd7 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 86 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY 2025-06-27T14:38:14.764Z0com.ustadmobile.lib.rest.PersonAuthRegisterRouteTest1.0520givenValidCredentials_whenLoginCalled_thenShouldReturnAccountfalsePASSEDJun 27, 2025 4:38:15 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [DatabaseBuilder.build - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] : created tables Jun 27, 2025 4:38:15 PM io.github.aakira.napier.DebugAntilog performLog INFO: [INFO] DoorLog - [DatabaseBuilder.build - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] database build complete Jun 27, 2025 4:38:15 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - InvalidationTracker: table ...[truncated 34289 chars]... 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [prepareAndUseStatementAsync - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] - prepare " SELECT PersonAuth2.* FROM PersonAuth2 where PersonAuth2.pauthUid = ? " Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [RoomJdbcImplHelper - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] - useNewConnectionAsyncInternal connection #4 : end transaction #4 0com.ustadmobile.lib.rest.PersonAuthRegisterRouteTest0.1220givenInvalidCredentials_whenLoginCalled_thenShouldRespondForbiddenfalsePASSEDJun 27, 2025 4:38:15 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [DatabaseBuilder.build - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] : created tables Jun 27, 2025 4:38:15 PM io.github.aakira.napier.DebugAntilog performLog INFO: [INFO] DoorLog - [DatabaseBuilder.build - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] database build complete Jun 27, 2025 4:38:15 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - InvalidationTracker: table ...[truncated 34289 chars]... 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [prepareAndUseStatementAsync - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] - prepare " SELECT PersonAuth2.* FROM PersonAuth2 where PersonAuth2.pauthUid = ? " Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [RoomJdbcImplHelper - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] - useNewConnectionAsyncInternal connection #4 : end transaction #4 0com.ustadmobile.lib.rest.PersonAuthRegisterRouteTest0.1070givenRegisterPersonWithAuth_whenRegisterCalled_thenShouldGenerateAuthfalsePASSEDJun 27, 2025 4:38:15 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [DatabaseBuilder.build - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] : created tables Jun 27, 2025 4:38:15 PM io.github.aakira.napier.DebugAntilog performLog INFO: [INFO] DoorLog - [DatabaseBuilder.build - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] database build complete Jun 27, 2025 4:38:15 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - InvalidationTracker: table ...[truncated 34289 chars]... 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [prepareAndUseStatementAsync - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] - prepare " SELECT PersonAuth2.* FROM PersonAuth2 where PersonAuth2.pauthUid = ? " Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [RoomJdbcImplHelper - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] - useNewConnectionAsyncInternal connection #4 : end transaction #4 1.281com.ustadmobile.lib.rest.PersonAuthRegisterRouteTestJun 27, 2025 4:38:15 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [DatabaseBuilder.build - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] : created tables Jun 27, 2025 4:38:15 PM io.github.aakira.napier.DebugAntilog performLog INFO: [INFO] DoorLog - [DatabaseBuilder.build - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] database build complete Jun 27, 2025 4:38:15 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - InvalidationTracker: table ...[truncated 34289 chars]... 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [prepareAndUseStatementAsync - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] - prepare " SELECT PersonAuth2.* FROM PersonAuth2 where PersonAuth2.pauthUid = ? " Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [RoomJdbcImplHelper - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] - useNewConnectionAsyncInternal connection #4 : end transaction #4 2025-06-27T14:38:15.068Z0com.ustadmobile.lib.rest.TestContentEntryVersionRoute0.0190givenEntryNotCached_whenRequested_thenShouldReturn404falsePASSEDJun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] : created tables Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog INFO: [INFO] DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] : created tables Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog INFO: [INFO] DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - InvalidationTracker: tables invalidated: RequestedEntry 0com.ustadmobile.lib.rest.TestContentEntryVersionRoute0.0670givenEntryIsCached_whenRequested_thenResponseShouldMatchfalsePASSEDJun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] : created tables Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog INFO: [INFO] DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] : created tables Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog INFO: [INFO] DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - InvalidationTracker: tables invalidated: RequestedEntry 0.086com.ustadmobile.lib.rest.TestContentEntryVersionRouteJun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] : created tables Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog INFO: [INFO] DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] : created tables Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog INFO: [INFO] DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - InvalidationTracker: tables invalidated: RequestedEntry 2025-06-27T14:38:16.351Z0com.ustadmobile.lib.rest.TestSiteRoute0.0250givenAvailableWorkSpace_whenRequested_thenShouldReturnWorkSpaceObjectfalsePASSEDJun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog INFO: [INFO] DoorLog - [DatabaseBuilder.build - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] database build complete Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - InvalidationTracker: tables invalidated: Person, SyncNode, Site, PersonAuth2, SystemPermission Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - InvalidationTracker: tables invalidated: SyncNode Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - InvalidationTracker: tables invalidated: Site 0.025com.ustadmobile.lib.rest.TestSiteRouteJun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog INFO: [INFO] DoorLog - [DatabaseBuilder.build - jdbc:sqlite:build/tmp/UmAppDatabase.sqlite] database build complete Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - InvalidationTracker: tables invalidated: Person, SyncNode, Site, PersonAuth2, SystemPermission Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - InvalidationTracker: tables invalidated: SyncNode Jun 27, 2025 4:38:16 PM io.github.aakira.napier.DebugAntilog performLog FINEST: [VERBOSE] DoorLog - InvalidationTracker: tables invalidated: Site 2025-06-27T14:38:16.437Z0com.ustadmobile.lib.rest.VerifySignInWithPasskeyTest0.1540verifyPasskeySignInWithValidDatafalsePASSED0com.ustadmobile.lib.rest.VerifySignInWithPasskeyTest0.0080verifyPasskeySignInWithWrongSignaturefalsePASSED0com.ustadmobile.lib.rest.VerifySignInWithPasskeyTest0.0060verifyPasskeySignInWithWrongChallengefalsePASSED0.168com.ustadmobile.lib.rest.VerifySignInWithPasskeyTest2025-06-27T14:38:16.463Z0com.ustadmobile.jsmodules.JsIntlWrapperTest0.00givenValidLocale_whenFormatCalled_thenShouldFormatDate[js, browser, ChromeHeadless137.0.0.0, Linuxx86_64]falsePASSED[log] Now: 6/27/2025 0.0com.ustadmobile.jsmodules.JsIntlWrapperTest[log] Now: 6/27/2025 2025-06-27T14:37:56.101Z0com.ustadmobile.util.WebEndpointResolverTest0.00givenApiUrlInSearchParams_whenEndpointResolved_thenShouldReturnSearchParam[js, browser, ChromeHeadless137.0.0.0, Linuxx86_64]falsePASSED0com.ustadmobile.util.WebEndpointResolverTest0.00givenRootHref_whenEndpointIsResolved_thenShouldReturnHref[js, browser, ChromeHeadless137.0.0.0, Linuxx86_64]falsePASSED0com.ustadmobile.util.WebEndpointResolverTest0.0010givenHrefWithDevelopmentLink_whenEndpointIsResolved_thenShouldReturnHref[js, browser, ChromeHeadless137.0.0.0, Linuxx86_64]falsePASSED0com.ustadmobile.util.WebEndpointResolverTest0.00givenHrefWithProductionLink_whenEndpointIsResolved_thenShouldReturnHref[js, browser, ChromeHeadless137.0.0.0, Linuxx86_64]falsePASSED0.001com.ustadmobile.util.WebEndpointResolverTest2025-06-27T14:37:56.101Z0com.ustadmobile.core.navigation.SavedStateHandle2Test0.0010givenStateSavedToHistory_whenNewHandleCreated_thenValueShouldBeRestored[js, browser, ChromeHeadless137.0.0.0, Linuxx86_64]falsePASSED0.001com.ustadmobile.core.navigation.SavedStateHandle2Test2025-06-27T14:36:42.194Z0com.ustadmobile.libcache.UstadCacheJvmTest0.4630givenResponseIsUpdated_whenRetrieved_thenLatestResponseWillBeReturned[jvm]falsePASSEDSLF4J: No SLF4J providers were found. SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See https://www.slf4j.org/codes.html#noProviders for further details. 0com.ustadmobile.libcache.UstadCacheJvmTest0.0260givenEmptyFileStored_whenRequestMade_thenWillBeRetrievedAsCacheHit[jvm]falsePASSEDSLF4J: No SLF4J providers were found. SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See https://www.slf4j.org/codes.html#noProviders for further details. 0com.ustadmobile.libcache.UstadCacheJvmTest0.0050givenEntryNotStored_whenRetrieved_thenWillReturnNull[jvm]falsePASSEDSLF4J: No SLF4J providers were found. SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See https://www.slf4j.org/codes.html#noProviders for further details. 0com.ustadmobile.libcache.UstadCacheJvmTest0.0720givenEntryNotLocked_whenLockAdded_thenWillBeMovedToPersistentDir[jvm]falsePASSEDSLF4J: No SLF4J providers were found. SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See https://www.slf4j.org/codes.html#noProviders for further details. 0com.ustadmobile.libcache.UstadCacheJvmTest0.0220givenNonCompressableFileStored_whenRequestMade_thenWillBeRetrievedAsCacheHitAndNotCompressed[jvm]falsePASSEDSLF4J: No SLF4J providers were found. SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See https://www.slf4j.org/codes.html#noProviders for further details. 0com.ustadmobile.libcache.UstadCacheJvmTest0.0160givenEntryLocked_whenLockRemoved_thenWillBeMovedToCacheDir[jvm]falsePASSEDSLF4J: No SLF4J providers were found. SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See https://www.slf4j.org/codes.html#noProviders for further details. 0com.ustadmobile.libcache.UstadCacheJvmTest0.0150givenResponseIsNotUpdated_whenStored_thenWillUpdateLastAccessAndValidationTime[jvm]falsePASSEDSLF4J: No SLF4J providers were found. SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See https://www.slf4j.org/codes.html#noProviders for further details. 0com.ustadmobile.libcache.UstadCacheJvmTest0.0130givenCompressableFileStored_whenRequestMadeWithoutAcceptEncoding_thenWillBeRetrievedAsCacheHitAndBeCompressed[jvm]falsePASSEDSLF4J: No SLF4J providers were found. SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See https://www.slf4j.org/codes.html#noProviders for further details. 0com.ustadmobile.libcache.UstadCacheJvmTest0.0140givenCompressableFileStored_whenRequestMade_thenWillBeRetrievedAsCacheHitAndBeCompressed[jvm]falsePASSEDSLF4J: No SLF4J providers were found. SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See https://www.slf4j.org/codes.html#noProviders for further details. 0com.ustadmobile.libcache.UstadCacheJvmTest0.0150givenFileCachedAndStored_whenPartialRequestMadeIfRangeNotMatched_thenWillReceiveFullResponse[jvm]falsePASSEDSLF4J: No SLF4J providers were found. SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See https://www.slf4j.org/codes.html#noProviders for further details. 0com.ustadmobile.libcache.UstadCacheJvmTest0.0130givenLockedEntryStored_whenRequestMade_thenWillBeRetrievedAsCacheHitAndSavedInPersistentPath[jvm]falsePASSEDSLF4J: No SLF4J providers were found. SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See https://www.slf4j.org/codes.html#noProviders for further details. 0com.ustadmobile.libcache.UstadCacheJvmTest0.0350givenFileCachedAndStored_whenPartialRequestMade_thenWillReceivePartialData[jvm]falsePASSEDSLF4J: No SLF4J providers were found. SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See https://www.slf4j.org/codes.html#noProviders for further details. 0.711com.ustadmobile.libcache.UstadCacheJvmTestSLF4J: No SLF4J providers were found. SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See https://www.slf4j.org/codes.html#noProviders for further details. 2025-06-27T14:34:31.547Z0com.ustadmobile.libcache.UstadCacheTrimmerTest0.1030givenEntriesExceedSize_whenTrimRun_thenWillEvictRequiredEntries[jvm]falsePASSED0.103com.ustadmobile.libcache.UstadCacheTrimmerTest2025-06-27T14:34:32.260Z0com.ustadmobile.libcache.cachecontrol.CacheControlFreshnessCheckerImplTest0.0020givenResponseWithinMaxAge_whenChecked_isFresh[jvm]falsePASSED0com.ustadmobile.libcache.cachecontrol.CacheControlFreshnessCheckerImplTest0.00givenResponseHasMustRevalidate_whenChecked_isStale[jvm]falsePASSED0com.ustadmobile.libcache.cachecontrol.CacheControlFreshnessCheckerImplTest0.0010givenResponseIsImmutable_whenChecked_isFresh[jvm]falsePASSED0com.ustadmobile.libcache.cachecontrol.CacheControlFreshnessCheckerImplTest0.0010givenRequestHasNoCacheDirective_whenChecked_isStale[jvm]falsePASSED0.004com.ustadmobile.libcache.cachecontrol.CacheControlFreshnessCheckerImplTest2025-06-27T14:34:32.366Z0com.ustadmobile.libcache.cachecontrol.ResponseCacheabilityCheckerImplTest0.00givenPartialResponse_whenChecked_notCacheable[jvm]falsePASSED0com.ustadmobile.libcache.cachecontrol.ResponseCacheabilityCheckerImplTest0.0010given200OkResponse_whenChecked_isCacheable[jvm]falsePASSED0com.ustadmobile.libcache.cachecontrol.ResponseCacheabilityCheckerImplTest0.00givenNoStoreInHeader_whenChecked_notCacheable[jvm]falsePASSED0.002com.ustadmobile.libcache.cachecontrol.ResponseCacheabilityCheckerImplTest2025-06-27T14:34:32.372Z0com.ustadmobile.libcache.io.RangeInputStreamTest0.0020givenValidRange_whenReadInSameIncrementAsBuffer_thenWillMatch[jvm]falsePASSED0com.ustadmobile.libcache.io.RangeInputStreamTest0.0020givenValidRange_whenRead_thenWillMatch[jvm]falsePASSED0.004com.ustadmobile.libcache.io.RangeInputStreamTest2025-06-27T14:34:32.375Z0com.ustadmobile.libcache.okhttp.UstadCacheInterceptorTest1.2450givenImmutableEntryWasCached_whenRequested_thenCacheWillHit[jvm]falsePASSEDJun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localh ...[truncated 19531 chars]... ile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localhost.localdomain:36677/testfile1.png 0com.ustadmobile.libcache.okhttp.UstadCacheInterceptorTest0.0170givenCompressableEntryNotYetCachedAlreadyEncoded_whenRequested_thenWillRespondAndCacheIt[jvm]falsePASSEDJun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localh ...[truncated 19531 chars]... ile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localhost.localdomain:36677/testfile1.png 0com.ustadmobile.libcache.okhttp.UstadCacheInterceptorTest0.0110givenResponseHasNoStoreHeader_whenRequested_thenIsNotStored[jvm]falsePASSEDJun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localh ...[truncated 19531 chars]... ile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localhost.localdomain:36677/testfile1.png 0com.ustadmobile.libcache.okhttp.UstadCacheInterceptorTest0.0860givenResponsePartiallyStored_whenRequestedWithResumeUuid_thenWillResume[jvm]falsePASSEDJun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localh ...[truncated 19531 chars]... ile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localhost.localdomain:36677/testfile1.png 0com.ustadmobile.libcache.okhttp.UstadCacheInterceptorTest0.0240givenEntryNotYetCached_whenRequested_thenWillRespondAndCacheIt[jvm]falsePASSEDJun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localh ...[truncated 19531 chars]... ile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localhost.localdomain:36677/testfile1.png 0com.ustadmobile.libcache.okhttp.UstadCacheInterceptorTest0.0260givenEntryIsStaleAndValidatable_whenRequested_thenIsValidated[jvm]falsePASSEDJun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localh ...[truncated 19531 chars]... ile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localhost.localdomain:36677/testfile1.png 0com.ustadmobile.libcache.okhttp.UstadCacheInterceptorTest0.0130givenCompressableEntryNotYetCachedNotEncoded_whenRequested_thenWillRespondAndCacheIt[jvm]falsePASSEDJun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localh ...[truncated 19531 chars]... ile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localhost.localdomain:36677/testfile1.png 0com.ustadmobile.libcache.okhttp.UstadCacheInterceptorTest0.0060givenRequestNotStorable_whenRequested_thenIsNotStored[jvm]falsePASSEDJun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localh ...[truncated 19531 chars]... ile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localhost.localdomain:36677/testfile1.png 1.429com.ustadmobile.libcache.okhttp.UstadCacheInterceptorTestJun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localh ...[truncated 19531 chars]... ile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog INFO: INFO DoorLog - [DatabaseBuilder.build - jdbc:sqlite::memory:] database build complete Jun 27, 2025 4:34:33 PM com.ustadmobile.core.util.NapierAntilogJvm performLog FINEST: FINEST UstadCache - OKHttp-CacheInterceptor: intercept: GET http://localhost.localdomain:36677/testfile1.png 2025-06-27T14:34:32.383Z0com.ustadmobile.libcache.partial.ContentRangeTest0.00givenRangeWithStartToEnd_whenParsed_thenMatches[jvm]falsePASSED0com.ustadmobile.libcache.partial.ContentRangeTest0.0020givenRangeWhereLastByteAfterTotalBytes_whenParsed_thenThrowsRangeNotSatisfiableException[jvm]falsePASSED0com.ustadmobile.libcache.partial.ContentRangeTest0.00givenRangeWhereStartIsAfterLast_whenParsed_thenThrowsRangeNotSatisfiableException[jvm]falsePASSED0com.ustadmobile.libcache.partial.ContentRangeTest0.00givenBlankStart_whenParsed_thenMatches[jvm]falsePASSED0com.ustadmobile.libcache.partial.ContentRangeTest0.00givenValidRange_whenParsed_thenMatches[jvm]falsePASSED0.002com.ustadmobile.libcache.partial.ContentRangeTest2025-06-27T14:34:33.814Z0com.ustadmobile.lib.util.TestAcceptEncodingUtil0.0080givenEncodingNotSupported_whenIsEncodingAcceptableCalled_thenShouldBeFalse[jvm]falsePASSED0com.ustadmobile.lib.util.TestAcceptEncodingUtil0.00givenEncodingSupportedByStar_whenIsEncodingAcceptableCalled_thenShouldBeTrue[jvm]falsePASSED0com.ustadmobile.lib.util.TestAcceptEncodingUtil0.00givenEncodingDirectlySupported_whenIsEncodingAcceptableCalled_thenShouldBeTrue[jvm]falsePASSED0com.ustadmobile.lib.util.TestAcceptEncodingUtil0.00givenEncodingWithQualityValue_whenParsed_thenShouldBeInMap[jvm]falsePASSED0com.ustadmobile.lib.util.TestAcceptEncodingUtil0.00givenEncodingWithoutQualityValue_whenParsed_thenShouldBeInMap[jvm]falsePASSED0.009com.ustadmobile.lib.util.TestAcceptEncodingUtil2025-06-27T14:33:10.614Z0com.ustadmobile.lib.util.TestRangeUtil0.0080givenZeroToEndRangeHeader_whenParseRangeRequestHeaderCalled_thenShouldReturnWholeFileRange[jvm]falsePASSED0com.ustadmobile.lib.util.TestRangeUtil0.00givenUnsatisfiableRangeHeader_whenParseRangeRequestHeaderCalled_tehnShouldReturnRangeNotSatisfiable[jvm]falsePASSED0com.ustadmobile.lib.util.TestRangeUtil0.00givenValidRangeHeader_whenParseRangeRequestHeaderCalled_thenShouldReturnRange[jvm]falsePASSED0com.ustadmobile.lib.util.TestRangeUtil0.00givenInvalidHeader_whenParseRangeRequestCalled_thenShouldReturnBadRequest[jvm]falsePASSED0.008com.ustadmobile.lib.util.TestRangeUtil2025-06-27T14:33:10.624Z0com.ustadmobile.lib.util.TestSysPathUtil0.0060givenCommandInPathOnWindows_whenCommandInPathCalled_thenReturnsFile[jvm]falsePASSED0com.ustadmobile.lib.util.TestSysPathUtil0.0010givenCommandNotInPathOnWindows_whenCommandInPathCalled_thenReturnsNull[jvm]falsePASSED0com.ustadmobile.lib.util.TestSysPathUtil0.00givenCommandNotInPathOnUnix_whenFindCommandInPathCalled_thenReturnNull[jvm]falsePASSED0com.ustadmobile.lib.util.TestSysPathUtil0.0010givenCommandInPathOnUnix_whenFindCommandInPathCalled_thenReturnsFile[jvm]falsePASSED0.008com.ustadmobile.lib.util.TestSysPathUtil2025-06-27T14:33:10.635Z0com.ustadmobile.xxhashkmp.commonjvmimpl.XXHashJvmTest0.0120givenString_whenInvoked_willHash[jvm]falsePASSEDa96086d5c2cb52a9 0com.ustadmobile.xxhashkmp.commonjvmimpl.XXHashJvmTest0.0080givenLongArray_whenInvoked_thenWillHash[jvm]falsePASSEDa96086d5c2cb52a9 0.02com.ustadmobile.xxhashkmp.commonjvmimpl.XXHashJvmTesta96086d5c2cb52a9 2025-06-27T14:33:10.876Z0com.ustadmobile.xxhashkmp.jsimpl.XXHashJsTest0.00test[js, browser, ChromeHeadless137.0.0.0, Linuxx86_64]falsePASSED0com.ustadmobile.xxhashkmp.jsimpl.XXHashJsTest0.0010givenLongArray_whenInvoked_thenWillHash[js, browser, ChromeHeadless137.0.0.0, Linuxx86_64]falsePASSED0.001com.ustadmobile.xxhashkmp.jsimpl.XXHashJsTest2025-06-27T14:33:18.132Z0com.ustadmobile.sharedse.impl.http.IHTTPSessionExtTest0.4290givenPutRequestWithBody_whenParseRequestBodyCalled_thenShouldReturnContent[jvm]falsePASSED16:38:13,993 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.6 16:38:13,994 |-INFO in ch.qos.logback.classic.util.ContextInitializer@5af3a0f - No custom configurators were discovered as a service. 16:38:13,994 |-INFO in ch.qos.logback.classic.util.ContextInitializer@5af3a0f - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 16:38:13,994 |-INFO in ch.qos.logback.classic.util.ContextInitializer@5af3a0f - Constructed con ...[truncated 3708 chars]... level of logger [io.netty] to INFO 16:38:14,083 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@24b6b8f6 - End of configuration. 16:38:14,084 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@72cf2de5 - Registering current configuration as safe fallback point 16:38:14,084 |-INFO in ch.qos.logback.classic.util.ContextInitializer@5af3a0f - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 84 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY 0com.ustadmobile.sharedse.impl.http.IHTTPSessionExtTest0.0160givenLargerPostRequestWithBody_whenParseRequestCalled_thenShouldReturnContent[jvm]falsePASSED16:38:13,993 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.6 16:38:13,994 |-INFO in ch.qos.logback.classic.util.ContextInitializer@5af3a0f - No custom configurators were discovered as a service. 16:38:13,994 |-INFO in ch.qos.logback.classic.util.ContextInitializer@5af3a0f - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 16:38:13,994 |-INFO in ch.qos.logback.classic.util.ContextInitializer@5af3a0f - Constructed con ...[truncated 3708 chars]... level of logger [io.netty] to INFO 16:38:14,083 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@24b6b8f6 - End of configuration. 16:38:14,084 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@72cf2de5 - Registering current configuration as safe fallback point 16:38:14,084 |-INFO in ch.qos.logback.classic.util.ContextInitializer@5af3a0f - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 84 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY 0com.ustadmobile.sharedse.impl.http.IHTTPSessionExtTest0.0150givenSmallPostRequestWithBody_whenParseRequestCalled_thenShouldReturnContent[jvm]falsePASSED16:38:13,993 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.6 16:38:13,994 |-INFO in ch.qos.logback.classic.util.ContextInitializer@5af3a0f - No custom configurators were discovered as a service. 16:38:13,994 |-INFO in ch.qos.logback.classic.util.ContextInitializer@5af3a0f - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 16:38:13,994 |-INFO in ch.qos.logback.classic.util.ContextInitializer@5af3a0f - Constructed con ...[truncated 3708 chars]... level of logger [io.netty] to INFO 16:38:14,083 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@24b6b8f6 - End of configuration. 16:38:14,084 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@72cf2de5 - Registering current configuration as safe fallback point 16:38:14,084 |-INFO in ch.qos.logback.classic.util.ContextInitializer@5af3a0f - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 84 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY 0.461com.ustadmobile.sharedse.impl.http.IHTTPSessionExtTest16:38:13,993 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.6 16:38:13,994 |-INFO in ch.qos.logback.classic.util.ContextInitializer@5af3a0f - No custom configurators were discovered as a service. 16:38:13,994 |-INFO in ch.qos.logback.classic.util.ContextInitializer@5af3a0f - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 16:38:13,994 |-INFO in ch.qos.logback.classic.util.ContextInitializer@5af3a0f - Constructed con ...[truncated 3708 chars]... level of logger [io.netty] to INFO 16:38:14,083 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@24b6b8f6 - End of configuration. 16:38:14,084 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@72cf2de5 - Registering current configuration as safe fallback point 16:38:14,084 |-INFO in ch.qos.logback.classic.util.ContextInitializer@5af3a0f - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 84 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY 2025-06-27T14:38:13.929Z0com.ustadmobile.sharedse.impl.http.TestHtmlFilterSerializer0.0420givenXhtmlWithEntities_whenFiltered_thenShouldParseSuccessfully[jvm]falsePASSED0com.ustadmobile.sharedse.impl.http.TestHtmlFilterSerializer0.0080givenValidXhtmlPage_whenFiltered_thenShouldParseSuccessfully[jvm]falsePASSED0.051com.ustadmobile.sharedse.impl.http.TestHtmlFilterSerializer2025-06-27T14:38:14.391Z0com.ustadmobile.sharedse.util.UmZipUtilTest0.0520givenAnEpubFile_whenUnzipped_thenAllFilesShouldBeAvailable[jvm]falsePASSED0.052com.ustadmobile.sharedse.util.UmZipUtilTest2025-06-27T14:38:14.443Z