givenRequestMade_whenDataChangedAndNextRequestSinceParamSetToPreviousConsistentThroughValue_thenRemoteResultShouldBeUpdated[jvm]

2026-10-05 00:26:43.116 [Test worker @coroutine#270] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-10-05 00:26:43.117 [Test worker @coroutine#270] INFO  io.ktor.server.Application - Application started in 0.029 seconds.
2026-10-05 00:26:43.118 [DefaultDispatcher-worker-7 @coroutine#279] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:43545
2026-10-05 00:26:43.121 [Test worker @coroutine#270] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:43545/api/school/respect/person?includeRelated=false
2026-10-05 00:26:43.121 [Test worker @coroutine#270] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:43545/api/school/respect/person?includeRelated=false.
2026-10-05 00:26:43.123 [eventLoopGroupProxy-28-1 @call-handler#283] TRACE io.ktor.server.routing.Routing - Trace for [api, school, respect, person]
/, segment:0 -> SUCCESS @ /
  /api, segment:1 -> SUCCESS @ /api
    /api/school, segment:2 -> SUCCESS @ /api/school
      /api/school/respect, segment:3 -> SUCCESS @ /api/school/respect
        /api/school/respect/person, segment:4 -> SUCCESS @ /api/school/respect/person
          /api/school/respect/person [(method:GET)], segment:4 -> SUCCESS @ /api/school/respect/person [(method:GET)]
          /api/school/respect/person [(method:POST)], segment:4 -> FAILURE "Selector didn't match" @ /api/school/respect/person [(method:POST)]
Matched routes:
  "" -> "api" -> "school" -> "respect" -> "person" -> "(method:GET)"
Routing resolve result:
  SUCCESS @ /api/school/respect/person [(method:GET)]
2026-10-05 00:26:43.127 [Test worker @coroutine#270] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:43545/api/school/respect/person?includeRelated=false
2026-10-05 00:26:43.127 [Test worker @coroutine#270] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:43545/api/school/respect/person?includeRelated=false
2026-10-05 00:26:43.127 [Test worker @coroutine#270] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:43545/api/school/respect/person?includeRelated=false
2026-10-05 00:26:43.128 [Test worker @coroutine#270] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:43545/api/school/respect/person?includeRelated=false.
DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-10-04T20:26:43.041Z, stored=2026-10-04T20:26:43.156Z, metadata=null, userMasterIdentifier=null, username=null, givenName=Admin, familyName=User, middleName=null, gender=UNSPECIFIED, preferredFirstName=null, preferredMiddleName=null, preferredLastName=null, pronouns=null, roles=[PersonRole(isPrimaryRole=true, roleEnum=SYSTEM_ADMINISTRATOR, beginDate=null, endDate=null)], relatedPersonUids=[], dateOfBirth=null, email=null, phoneNumber=null)], metaInfo=DataLoadMetaInfo(lastModified=1791145603041, lastStored=1791145603156, etag=null, url=http://localhost:43545/api/school/respect/person?includeRelated=false, consistentThrough=2026-10-04T20:26:43.156897187Z, validationInfoKey=0, varyHeader=null, headers=Headers [], permissionsLastModified=null), localState=DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-10-04T20:26:43.041Z, stored=2026-10-04T20:26:43.156Z, metadata=null, userMasterIdentifier=null, username=null, givenName=Admin, familyName=User, middleName=null, gender=UNSPECIFIED, preferredFirstName=null, preferredMiddleName=null, preferredLastName=null, pronouns=null, roles=[PersonRole(isPrimaryRole=true, roleEnum=SYSTEM_ADMINISTRATOR, beginDate=null, endDate=null)], relatedPersonUids=[], dateOfBirth=null, email=null, phoneNumber=null)], metaInfo=DataLoadMetaInfo(lastModified=1791145603041, lastStored=1791145603156, etag=null, url=null, consistentThrough=2026-10-04T20:26:43.156897187Z, validationInfoKey=0, varyHeader=null, headers=Headers [], permissionsLastModified=null), localState=null, remoteState=null), remoteState=DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-10-04T20:26:43.041Z, stored=2026-10-04T20:26:43.087Z, metadata=null, userMasterIdentifier=null, username=null, givenName=Admin, familyName=User, middleName=null, gender=UNSPECIFIED, preferredFirstName=null, preferredMiddleName=null, preferredLastName=null, pronouns=null, roles=[PersonRole(isPrimaryRole=true, roleEnum=SYSTEM_ADMINISTRATOR, beginDate=null, endDate=null)], relatedPersonUids=[], dateOfBirth=null, email=null, phoneNumber=null), Person(guid=42, userActive=true, status=ACTIVE, lastModified=2026-10-04T20:26:43.040Z, stored=2026-10-04T20:26:43.119Z, metadata=null, userMasterIdentifier=null, username=test, givenName=test, familyName=test, middleName=null, gender=FEMALE, preferredFirstName=null, preferredMiddleName=null, preferredLastName=null, pronouns=null, roles=[PersonRole(isPrimaryRole=true, roleEnum=SITE_ADMINISTRATOR, beginDate=null, endDate=null)], relatedPersonUids=[], dateOfBirth=null, email=null, phoneNumber=null)], metaInfo=DataLoadMetaInfo(lastModified=1791145603000, lastStored=-1, etag=null, url=http://localhost:43545/api/school/respect/person?includeRelated=false, consistentThrough=2026-10-04T20:26:43.123721867Z, validationInfoKey=7092960727423664838, varyHeader=Authorization, headers=io.ktor.client.engine.okhttp.OkUtilsKt$fromOkHttp$1@319aa9ee, permissionsLastModified=null), localState=null, remoteState=null))
2026-10-05 00:26:43.160 [Test worker @coroutine#270] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:43545/api/school/respect/person?since=2026-10-04T20%3A26%3A43.123721867Z&includeRelated=false
2026-10-05 00:26:43.161 [Test worker @coroutine#270] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:43545/api/school/respect/person?since=2026-10-04T20%3A26%3A43.123721867Z&includeRelated=false.
2026-10-05 00:26:43.162 [eventLoopGroupProxy-28-1 @call-handler#290] TRACE io.ktor.server.routing.Routing - Trace for [api, school, respect, person]
/, segment:0 -> SUCCESS @ /
  /api, segment:1 -> SUCCESS @ /api
    /api/school, segment:2 -> SUCCESS @ /api/school
      /api/school/respect, segment:3 -> SUCCESS @ /api/school/respect
        /api/school/respect/person, segment:4 -> SUCCESS @ /api/school/respect/person
          /api/school/respect/person [(method:GET)], segment:4 -> SUCCESS @ /api/school/respect/person [(method:GET)]
          /api/school/respect/person [(method:POST)], segment:4 -> FAILURE "Selector didn't match" @ /api/school/respect/person [(method:POST)]
Matched routes:
  "" -> "api" -> "school" -> "respect" -> "person" -> "(method:GET)"
Routing resolve result:
  SUCCESS @ /api/school/respect/person [(method:GET)]
2026-10-05 00:26:43.164 [Test worker @coroutine#270] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:43545/api/school/respect/person?since=2026-10-04T20%3A26%3A43.123721867Z&includeRelated=false
2026-10-05 00:26:43.164 [Test worker @coroutine#270] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:43545/api/school/respect/person?since=2026-10-04T20%3A26%3A43.123721867Z&includeRelated=false
2026-10-05 00:26:43.164 [Test worker @coroutine#270] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:43545/api/school/respect/person?since=2026-10-04T20%3A26%3A43.123721867Z&includeRelated=false
2026-10-05 00:26:43.165 [Test worker @coroutine#270] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:43545/api/school/respect/person?since=2026-10-04T20%3A26%3A43.123721867Z&includeRelated=false.
Run time: 46.579081ms

Oct 05, 2026 12:26:43 AM io.github.aakira.napier.DebugAntilog performLog
FINE: [DEBUG] MapPagingSource$load - MapPagingSource(tag=PersonDataSourceDb/listAsPagingSource(params=GetListParams(common=GetListCommonParams(guid=null, searchQuery=null, since=null, sinceIfPermissionsNotChangedSince=null, includeDeleted=null), filterByClazzUid=null, filterByEnrolmentRole=null, filterByName=null, filterByPersonStatus=null, filterByPersonRole=null, includeRelated=false, inClassOnDay=null, excludeSharedSchoolDevice=false))): load Refresh(key=0, loadSize=1000)
Oct 05, 2026 12:26:43 AM io.github.aakira.napier.DebugAntilog performLog
FINE: [DEBUG] DelegatedInvalidationPagingSource$onSrcInvalidated - RPaging/DelegatedInvalidationPagingSource(extra=PersonDataSourceDb/listAsPagingSource(params=GetListParams(common=GetListCommonParams(guid=null, searchQuery=null, since=null, sinceIfPermissionsNotChangedSince=null, includeDeleted=null), filterByClazzUid=null, filterByEnrolmentRole=null, filterByName=null, filterByPersonStatus=null, filterByPersonRole=null, includeRelated=false, inClassOnDay=null, excludeSharedSchoolDevice=false))) src invalidated
Oct 05, 2026 12:26:43 AM io.github.aakira.napier.DebugAntilog performLog
FINE: [DEBUG] MapPagingSource$load - MapPagingSource(tag=PersonDataSourceDb/listAsPagingSource(params=GetListParams(common=GetListCommonParams(guid=null, searchQuery=null, since=2026-10-04T20:26:43.123721867Z, sinceIfPermissionsNotChangedSince=null, includeDeleted=null), filterByClazzUid=null, filterByEnrolmentRole=null, filterByName=null, filterByPersonStatus=null, filterByPersonRole=null, includeRelated=false, inClassOnDay=null, excludeSharedSchoolDevice=false))): load Refresh(key=0, loadSize=1000)