givenRequestMade_whenDataChangedAndNextRequestSinceParamSetToPreviousConsistentThroughValue_thenRemoteResultShouldBeUpdated[jvm]

2026-10-05 09:21:47.050 [Test worker @coroutine#270] INFO  io.ktor.server.Application - Autoreload is disabled because the development mode is off.
2026-10-05 09:21:47.051 [Test worker @coroutine#270] INFO  io.ktor.server.Application - Application started in 0.048 seconds.
2026-10-05 09:21:47.052 [DefaultDispatcher-worker-4 @coroutine#279] INFO  io.ktor.server.Application - Responding at http://0.0.0.0:39373
2026-10-05 09:21:47.054 [Test worker @coroutine#270] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39373/api/school/respect/person?includeRelated=false
2026-10-05 09:21:47.055 [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:39373/api/school/respect/person?includeRelated=false.
2026-10-05 09:21:47.057 [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 09:21:47.061 [Test worker @coroutine#270] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39373/api/school/respect/person?includeRelated=false
2026-10-05 09:21:47.061 [Test worker @coroutine#270] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39373/api/school/respect/person?includeRelated=false
2026-10-05 09:21:47.061 [Test worker @coroutine#270] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39373/api/school/respect/person?includeRelated=false
2026-10-05 09:21:47.062 [Test worker @coroutine#270] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39373/api/school/respect/person?includeRelated=false.
DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-10-05T05:21:46.940Z, stored=2026-10-05T05:21:47.109Z, 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=1791177706940, lastStored=1791177707109, etag=null, url=http://localhost:39373/api/school/respect/person?includeRelated=false, consistentThrough=2026-10-05T05:21:47.111103411Z, validationInfoKey=0, varyHeader=null, headers=Headers [], permissionsLastModified=null), localState=DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-10-05T05:21:46.940Z, stored=2026-10-05T05:21:47.109Z, 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=1791177706940, lastStored=1791177707109, etag=null, url=null, consistentThrough=2026-10-05T05:21:47.111103411Z, 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-05T05:21:46.940Z, stored=2026-10-05T05:21:47.002Z, 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-05T05:21:46.933Z, stored=2026-10-05T05:21:47.052Z, 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=1791177707000, lastStored=-1, etag=null, url=http://localhost:39373/api/school/respect/person?includeRelated=false, consistentThrough=2026-10-05T05:21:47.057800896Z, validationInfoKey=7092960727423664838, varyHeader=Authorization, headers=io.ktor.client.engine.okhttp.OkUtilsKt$fromOkHttp$1@2f6f4ac4, permissionsLastModified=null), localState=null, remoteState=null))
2026-10-05 09:21:47.129 [Test worker @coroutine#270] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39373/api/school/respect/person?since=2026-10-05T05%3A21%3A47.057800896Z&includeRelated=false
2026-10-05 09:21:47.129 [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:39373/api/school/respect/person?since=2026-10-05T05%3A21%3A47.057800896Z&includeRelated=false.
2026-10-05 09:21:47.135 [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 09:21:47.146 [Test worker @coroutine#270] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39373/api/school/respect/person?since=2026-10-05T05%3A21%3A47.057800896Z&includeRelated=false
2026-10-05 09:21:47.146 [Test worker @coroutine#270] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39373/api/school/respect/person?since=2026-10-05T05%3A21%3A47.057800896Z&includeRelated=false
2026-10-05 09:21:47.147 [Test worker @coroutine#270] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39373/api/school/respect/person?since=2026-10-05T05%3A21%3A47.057800896Z&includeRelated=false
2026-10-05 09:21:47.154 [Test worker @coroutine#270] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39373/api/school/respect/person?since=2026-10-05T05%3A21%3A47.057800896Z&includeRelated=false.
Run time: 108.884438ms

Oct 05, 2026 9:21:47 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))): load Refresh(key=0, loadSize=1000)
Oct 05, 2026 9:21:47 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))) src invalidated
Oct 05, 2026 9:21:47 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-05T05:21:47.057800896Z, sinceIfPermissionsNotChangedSince=null, includeDeleted=null), filterByClazzUid=null, filterByEnrolmentRole=null, filterByName=null, filterByPersonStatus=null, filterByPersonRole=null, includeRelated=false, inClassOnDay=null))): load Refresh(key=0, loadSize=1000)