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:3 -> FAILURE "Selector didn't match" @ /api/school/respect/person /api/school/respect/schoolpermissiongrant, segment:4 -> SUCCESS @ /api/school/respect/schoolpermissiongrant /api/school/respect/schoolpermissiongrant [(method:GET)], segment:4 -> SUCCESS @ /api/school/respect/schoolpermissiongrant [(method:GET)] Matched routes: "" -> "api" -> "school" -> "respect" -> "schoolpermissiongrant" -> "(method:GET)" Routing resolve result: SUCCESS @ /api/school/respect/schoolpermissiongrant [(method:GET)] 2026-09-16 21:09:29.165 [Test worker @coroutine#62] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42913/api/school/respect/schoolpermissiongrant 2026-09-16 21:09:29.165 [Test worker @coroutine#62] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42913/api/school/respect/schoolpermissiongrant 2026-09-16 21:09:29.166 [Test worker @coroutine#62] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42913/api/school/respect/schoolpermissiongrant 2026-09-16 21:09:29.168 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42913/api/school/respect/schoolpermissiongrant. 2026-09-16 21:09:29.231 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42913/api/school/respect/person?includeRelated=false 2026-09-16 21:09:29.232 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:42913/api/school/respect/person?includeRelated=false. 2026-09-16 21:09:29.233 [eventLoopGroupProxy-13-1 @call-handler#80] 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)] /api/school/respect/schoolpermissiongrant, segment:3 -> FAILURE "Selector didn't match" @ /api/school/respect/schoolpermissiongrant Matched routes: "" -> "api" -> "school" -> "respect" -> "person" -> "(method:GET)" Routing resolve result: SUCCESS @ /api/school/respect/person [(method:GET)] 2026-09-16 21:09:29.253 [Test worker @coroutine#62] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42913/api/school/respect/person?includeRelated=false 2026-09-16 21:09:29.254 [Test worker @coroutine#62] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42913/api/school/respect/person?includeRelated=false 2026-09-16 21:09:29.254 [Test worker @coroutine#62] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42913/api/school/respect/person?includeRelated=false 2026-09-16 21:09:29.255 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42913/api/school/respect/person?includeRelated=false. 2026-09-16 21:09:29.260 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42913/api/school/respect/person?includeRelated=false 2026-09-16 21:09:29.260 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:42913/api/school/respect/person?includeRelated=false. 2026-09-16 21:09:29.262 [eventLoopGroupProxy-13-1 @call-handler#86] 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)] /api/school/respect/schoolpermissiongrant, segment:3 -> FAILURE "Selector didn't match" @ /api/school/respect/schoolpermissiongrant Matched routes: "" -> "api" -> "school" -> "respect" -> "person" -> "(method:GET)" Routing resolve result: SUCCESS @ /api/school/respect/person [(method:GET)] 2026-09-16 21:09:29.263 [eventLoopGroupProxy-13-1 @call-handler#86] TRACE i.k.s.p.c.ContentNegotiation - Skipping response body transformation from HttpStatusCode to OutgoingContent for the GET /api/school/respect/person?includeRelated=false request because the HttpStatusCode type is ignored. See [ContentNegotiationConfig::ignoreType]. 2026-09-16 21:09:29.264 [Test worker @coroutine#62] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42913/api/school/respect/person?includeRelated=false 2026-09-16 21:09:29.264 [Test worker @coroutine#62] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42913/api/school/respect/person?includeRelated=false 2026-09-16 21:09:29.264 [Test worker @coroutine#62] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42913/api/school/respect/person?includeRelated=false 2026-09-16 21:09:30.376 [Test worker @coroutine#90] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-09-16 21:09:30.377 [Test worker @coroutine#90] INFO io.ktor.server.Application - Application started in 0.038 seconds. 2026-09-16 21:09:30.379 [DefaultDispatcher-worker-1 @coroutine#99] INFO io.ktor.server.Application - Responding at http://0.0.0.0:34415 2026-09-16 21:09:30.379 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34415/api/school/respect/schoolpermissiongrant 2026-09-16 21:09:30.379 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:34415/api/school/respect/schoolpermissiongrant. 2026-09-16 21:09:30.382 [eventLoopGroupProxy-16-1 @call-handler#102] TRACE io.ktor.server.routing.Routing - Trace for [api, school, respect, schoolpermissiongrant] /, 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:3 -> FAILURE "Selector didn't match" @ /api/school/respect/person /api/school/respect/schoolpermissiongrant, segment:4 -> SUCCESS @ /api/school/respect/schoolpermissiongrant /api/school/respect/schoolpermissiongrant [(method:GET)], segment:4 -> SUCCESS @ /api/school/respect/schoolpermissiongrant [(method:GET)] Matched routes: "" -> "api" -> "school" -> "respect" -> "schoolpermissiongrant" -> "(method:GET)" Routing resolve result: SUCCESS @ /api/school/respect/schoolpermissiongrant [(method:GET)] 2026-09-16 21:09:30.384 [Test worker @coroutine#90] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34415/api/school/respect/schoolpermissiongrant 2026-09-16 21:09:30.384 [Test worker @coroutine#90] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34415/api/school/respect/schoolpermissiongrant 2026-09-16 21:09:30.384 [Test worker @coroutine#90] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34415/api/school/respect/schoolpermissiongrant 2026-09-16 21:09:30.384 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:34415/api/school/respect/schoolpermissiongrant. 2026-09-16 21:09:30.432 [DefaultDispatcher-worker-4 @coroutine#108] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34415/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-09-16 21:09:30.432 [DefaultDispatcher-worker-4 @coroutine#108] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:34415/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100. 2026-09-16 21:09:30.433 [eventLoopGroupProxy-16-1 @call-handler#112] 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)] /api/school/respect/schoolpermissiongrant, segment:3 -> FAILURE "Selector didn't match" @ /api/school/respect/schoolpermissiongrant Matched routes: "" -> "api" -> "school" -> "respect" -> "person" -> "(method:GET)" Routing resolve result: SUCCESS @ /api/school/respect/person [(method:GET)] 2026-09-16 21:09:30.437 [DefaultDispatcher-worker-3 @coroutine#108] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34415/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-09-16 21:09:30.437 [DefaultDispatcher-worker-3 @coroutine#108] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34415/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-09-16 21:09:30.437 [DefaultDispatcher-worker-3 @coroutine#108] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34415/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-09-16 21:09:30.438 [DefaultDispatcher-worker-3 @coroutine#108] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:34415/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100. 2026-09-16 21:09:31.551 [Test worker @coroutine#117] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-09-16 21:09:31.552 [Test worker @coroutine#117] INFO io.ktor.server.Application - Application started in 0.038 seconds. 2026-09-16 21:09:31.553 [DefaultDispatcher-worker-5 @coroutine#126] INFO io.ktor.server.Application - Responding at http://0.0.0.0:40409 2026-09-16 21:09:31.556 [Test worker @coroutine#117] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40409/api/school/respect/person?includeRelated=false 2026-09-16 21:09:31.556 [Test worker @coroutine#117] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:40409/api/school/respect/person?includeRelated=false. 2026-09-16 21:09:31.558 [eventLoopGroupProxy-19-1 @call-handler#130] 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-09-16 21:09:31.561 [Test worker @coroutine#117] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40409/api/school/respect/person?includeRelated=false 2026-09-16 21:09:31.562 [Test worker @coroutine#117] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40409/api/school/respect/person?includeRelated=false 2026-09-16 21:09:31.562 [Test worker @coroutine#117] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40409/api/school/respect/person?includeRelated=false 2026-09-16 21:09:31.562 [Test worker @coroutine#117] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:40409/api/school/respect/person?includeRelated=false. DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-09-16T17:09:31.447Z, stored=2026-09-16T17:09:31.608Z, 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=1789578571447, lastStored=1789578571608, etag=null, url=http://localhost:40409/api/school/respect/person?includeRelated=false, consistentThrough=2026-09-16T17:09:31.608970065Z, validationInfoKey=0, varyHeader=null, headers=null, permissionsLastModified=null), localState=DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-09-16T17:09:31.447Z, stored=2026-09-16T17:09:31.608Z, 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=1789578571447, lastStored=1789578571608, etag=null, url=null, consistentThrough=2026-09-16T17:09:31.608970065Z, validationInfoKey=0, varyHeader=null, headers=null, permissionsLastModified=null), localState=null, remoteState=null), remoteState=DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-09-16T17:09:31.447Z, stored=2026-09-16T17:09:31.513Z, 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-09-16T17:09:31.445Z, stored=2026-09-16T17:09:31.554Z, 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=1789578571000, lastStored=-1, etag=null, url=http://localhost:40409/api/school/respect/person?includeRelated=false, consistentThrough=2026-09-16T17:09:31.559015604Z, validationInfoKey=7092960727423664838, varyHeader=Authorization, headers=io.ktor.client.engine.okhttp.OkUtilsKt$fromOkHttp$1@29c244e, permissionsLastModified=null), localState=null, remoteState=null)) 2026-09-16 21:09:31.619 [Test worker @coroutine#117] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40409/api/school/respect/person?since=2026-09-16T17%3A09%3A31.559015604Z&includeRelated=false 2026-09-16 21:09:31.619 [Test worker @coroutine#117] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:40409/api/school/respect/person?since=2026-09-16T17%3A09%3A31.559015604Z&includeRelated=false. 2026-09-16 21:09:31.620 [eventLoopGroupProxy-19-1 @call-handler#136] 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-09-16 21:09:31.622 [Test worker @coroutine#117] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40409/api/school/respect/person?since=2026-09-16T17%3A09%3A31.559015604Z&includeRelated=false 2026-09-16 21:09:31.622 [Test worker @coroutine#117] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40409/api/school/respect/person?since=2026-09-16T17%3A09%3A31.559015604Z&includeRelated=false 2026-09-16 21:09:31.622 [Test worker @coroutine#117] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40409/api/school/respect/person?since=2026-09-16T17%3A09%3A31.559015604Z&includeRelated=false 2026-09-16 21:09:31.622 [Test worker @coroutine#117] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:40409/api/school/respect/person?since=2026-09-16T17%3A09%3A31.559015604Z&includeRelated=false. Run time: 69.296457ms 2026-09-16 21:09:32.731 [Test worker @coroutine#142] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-09-16 21:09:32.732 [Test worker @coroutine#142] INFO io.ktor.server.Application - Application started in 0.039 seconds. 2026-09-16 21:09:32.733 [DefaultDispatcher-worker-4 @coroutine#151] INFO io.ktor.server.Application - Responding at http://0.0.0.0:44771 2026-09-16 21:09:32.778 [DefaultDispatcher-worker-1 @coroutine#149] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44771/api/school/respect/person 2026-09-16 21:09:32.778 [DefaultDispatcher-worker-1 @coroutine#149] TRACE i.k.c.p.c.ContentNegotiation - Converted request body using io.ktor.serialization.kotlinx.KotlinxSerializationConverter@19a17c17 for http://localhost:44771/api/school/respect/person 2026-09-16 21:09:32.778 [DefaultDispatcher-worker-1 @coroutine#149] TRACE i.k.c.plugins.defaultTransformers - Transformed with default transformers request body for http://localhost:44771/api/school/respect/person from class io.ktor.http.content.TextContent 2026-09-16 21:09:32.782 [eventLoopGroupProxy-22-1 @call-handler#160] 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 -> FAILURE "Selector didn't match" @ /api/school/respect/person [(method:GET)] /api/school/respect/person [(method:POST)], segment:4 -> SUCCESS @ /api/school/respect/person [(method:POST)] Matched routes: "" -> "api" -> "school" -> "respect" -> "person" -> "(method:POST)" Routing resolve result: SUCCESS @ /api/school/respect/person [(method:POST)] 2026-09-16 21:09:32.785 [eventLoopGroupProxy-22-1 @call-handler#160] TRACE i.k.server.engine.DefaultTransform - No Default Transformations found for class io.ktor.utils.io.ByteChannel and expected type TypeInfo(kotlin.collections.List) for call /api/school/respect/person 2026-09-16 21:09:32.787 [eventLoopGroupProxy-22-1 @call-handler#160] TRACE i.k.s.p.c.ContentNegotiation - Skipping response body transformation from HttpStatusCode to OutgoingContent for the POST /api/school/respect/person request because the HttpStatusCode type is ignored. See [ContentNegotiationConfig::ignoreType]. 2026-09-16 21:09:32.787 [DefaultDispatcher-worker-4 @coroutine#149] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44771/api/school/respect/person 2026-09-16 21:09:32.787 [DefaultDispatcher-worker-4 @coroutine#149] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44771/api/school/respect/person 2026-09-16 21:09:32.787 [DefaultDispatcher-worker-4 @coroutine#149] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44771/api/school/respect/person 2026-09-16 21:09:33.882 [Test worker @coroutine#165] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-09-16 21:09:33.883 [Test worker @coroutine#165] INFO io.ktor.server.Application - Application started in 0.038 seconds. 2026-09-16 21:09:33.884 [DefaultDispatcher-worker-4 @coroutine#174] INFO io.ktor.server.Application - Responding at http://0.0.0.0:36193 2026-09-16 21:09:33.885 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:36193/api/school/respect/schoolpermissiongrant 2026-09-16 21:09:33.885 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:36193/api/school/respect/schoolpermissiongrant. 2026-09-16 21:09:33.887 [eventLoopGroupProxy-25-1 @call-handler#177] TRACE io.ktor.server.routing.Routing - Trace for [api, school, respect, schoolpermissiongrant] /, 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:3 -> FAILURE "Selector didn't match" @ /api/school/respect/person /api/school/respect/schoolpermissiongrant, segment:4 -> SUCCESS @ /api/school/respect/schoolpermissiongrant /api/school/respect/schoolpermissiongrant [(method:GET)], segment:4 -> SUCCESS @ /api/school/respect/schoolpermissiongrant [(method:GET)] Matched routes: "" -> "api" -> "school" -> "respect" -> "schoolpermissiongrant" -> "(method:GET)" Routing resolve result: SUCCESS @ /api/school/respect/schoolpermissiongrant [(method:GET)] 2026-09-16 21:09:33.889 [Test worker @coroutine#165] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:36193/api/school/respect/schoolpermissiongrant 2026-09-16 21:09:33.889 [Test worker @coroutine#165] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:36193/api/school/respect/schoolpermissiongrant 2026-09-16 21:09:33.889 [Test worker @coroutine#165] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:36193/api/school/respect/schoolpermissiongrant 2026-09-16 21:09:33.889 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:36193/api/school/respect/schoolpermissiongrant. 2026-09-16 21:09:34.012 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:36193/api/school/respect/person?includeRelated=false 2026-09-16 21:09:34.012 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:36193/api/school/respect/person?includeRelated=false. 2026-09-16 21:09:34.014 [eventLoopGroupProxy-25-1 @call-handler#183] 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)] /api/school/respect/schoolpermissiongrant, segment:3 -> FAILURE "Selector didn't match" @ /api/school/respect/schoolpermissiongrant Matched routes: "" -> "api" -> "school" -> "respect" -> "person" -> "(method:GET)" Routing resolve result: SUCCESS @ /api/school/respect/person [(method:GET)] 2026-09-16 21:09:34.019 [Test worker @coroutine#165] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:36193/api/school/respect/person?includeRelated=false 2026-09-16 21:09:34.019 [Test worker @coroutine#165] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:36193/api/school/respect/person?includeRelated=false 2026-09-16 21:09:34.019 [Test worker @coroutine#165] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:36193/api/school/respect/person?includeRelated=false 2026-09-16 21:09:34.020 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:36193/api/school/respect/person?includeRelated=false. 2026-09-16 21:09:36.026 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:36193/api/school/respect/person?includeRelated=false 2026-09-16 21:09:36.026 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:36193/api/school/respect/person?includeRelated=false. 2026-09-16 21:09:36.027 [eventLoopGroupProxy-25-1 @call-handler#190] 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)] /api/school/respect/schoolpermissiongrant, segment:3 -> FAILURE "Selector didn't match" @ /api/school/respect/schoolpermissiongrant Matched routes: "" -> "api" -> "school" -> "respect" -> "person" -> "(method:GET)" Routing resolve result: SUCCESS @ /api/school/respect/person [(method:GET)] 2026-09-16 21:09:36.029 [Test worker @coroutine#165] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:36193/api/school/respect/person?includeRelated=false 2026-09-16 21:09:36.030 [Test worker @coroutine#165] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:36193/api/school/respect/person?includeRelated=false 2026-09-16 21:09:36.030 [Test worker @coroutine#165] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:36193/api/school/respect/person?includeRelated=false 2026-09-16 21:09:36.030 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:36193/api/school/respect/person?includeRelated=false. 2026-09-16 21:09:37.143 [Test worker @coroutine#196] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-09-16 21:09:37.144 [Test worker @coroutine#196] INFO io.ktor.server.Application - Application started in 0.043 seconds. 2026-09-16 21:09:37.145 [DefaultDispatcher-worker-5 @coroutine#205] INFO io.ktor.server.Application - Responding at http://0.0.0.0:39049 2026-09-16 21:09:37.148 [Test worker @coroutine#196] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39049/api/school/respect/person?includeRelated=false 2026-09-16 21:09:37.148 [Test worker @coroutine#196] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39049/api/school/respect/person?includeRelated=false. 2026-09-16 21:09:37.150 [eventLoopGroupProxy-28-1 @call-handler#209] 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-09-16 21:09:37.154 [Test worker @coroutine#196] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39049/api/school/respect/person?includeRelated=false 2026-09-16 21:09:37.154 [Test worker @coroutine#196] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39049/api/school/respect/person?includeRelated=false 2026-09-16 21:09:37.154 [Test worker @coroutine#196] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39049/api/school/respect/person?includeRelated=false 2026-09-16 21:09:37.155 [Test worker @coroutine#196] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39049/api/school/respect/person?includeRelated=false. DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-09-16T17:09:37.037Z, stored=2026-09-16T17:09:37.194Z, 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=1789578577037, lastStored=1789578577194, etag=null, url=http://localhost:39049/api/school/respect/person?includeRelated=false, consistentThrough=2026-09-16T17:09:37.195234270Z, validationInfoKey=0, varyHeader=null, headers=null, permissionsLastModified=null), localState=DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-09-16T17:09:37.037Z, stored=2026-09-16T17:09:37.194Z, 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=1789578577037, lastStored=1789578577194, etag=null, url=null, consistentThrough=2026-09-16T17:09:37.195234270Z, validationInfoKey=0, varyHeader=null, headers=null, permissionsLastModified=null), localState=null, remoteState=null), remoteState=DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-09-16T17:09:37.037Z, stored=2026-09-16T17:09:37.100Z, 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-09-16T17:09:37.035Z, stored=2026-09-16T17:09:37.146Z, 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=1789578577000, lastStored=-1, etag=null, url=http://localhost:39049/api/school/respect/person?includeRelated=false, consistentThrough=2026-09-16T17:09:37.151194215Z, validationInfoKey=7092960727423664838, varyHeader=Authorization, headers=io.ktor.client.engine.okhttp.OkUtilsKt$fromOkHttp$1@66b31d46, permissionsLastModified=null), localState=null, remoteState=null)) 2026-09-16 21:09:37.198 [Test worker @coroutine#196] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39049/api/school/respect/person?since=2026-09-16T17%3A09%3A37.151194215Z&includeRelated=false 2026-09-16 21:09:37.198 [Test worker @coroutine#196] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39049/api/school/respect/person?since=2026-09-16T17%3A09%3A37.151194215Z&includeRelated=false. 2026-09-16 21:09:37.199 [eventLoopGroupProxy-28-1 @call-handler#216] 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-09-16 21:09:37.201 [Test worker @coroutine#196] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39049/api/school/respect/person?since=2026-09-16T17%3A09%3A37.151194215Z&includeRelated=false 2026-09-16 21:09:37.201 [Test worker @coroutine#196] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39049/api/school/respect/person?since=2026-09-16T17%3A09%3A37.151194215Z&includeRelated=false 2026-09-16 21:09:37.201 [Test worker @coroutine#196] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39049/api/school/respect/person?since=2026-09-16T17%3A09%3A37.151194215Z&includeRelated=false 2026-09-16 21:09:37.202 [Test worker @coroutine#196] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39049/api/school/respect/person?since=2026-09-16T17%3A09%3A37.151194215Z&includeRelated=false. Run time: 56.291490ms ]]>