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 14:12:26.589 [Test worker @coroutine#62] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46119/api/school/respect/schoolpermissiongrant 2026-09-16 14:12:26.589 [Test worker @coroutine#62] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46119/api/school/respect/schoolpermissiongrant 2026-09-16 14:12:26.589 [Test worker @coroutine#62] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46119/api/school/respect/schoolpermissiongrant 2026-09-16 14:12:26.591 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:46119/api/school/respect/schoolpermissiongrant. 2026-09-16 14:12:26.657 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46119/api/school/respect/person?includeRelated=false 2026-09-16 14:12:26.657 [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:46119/api/school/respect/person?includeRelated=false. 2026-09-16 14:12:26.659 [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 14:12:26.680 [Test worker @coroutine#62] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46119/api/school/respect/person?includeRelated=false 2026-09-16 14:12:26.680 [Test worker @coroutine#62] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46119/api/school/respect/person?includeRelated=false 2026-09-16 14:12:26.680 [Test worker @coroutine#62] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46119/api/school/respect/person?includeRelated=false 2026-09-16 14:12:26.681 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:46119/api/school/respect/person?includeRelated=false. 2026-09-16 14:12:26.687 [Test worker @coroutine#62] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46119/api/school/respect/person?includeRelated=false 2026-09-16 14:12:26.687 [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:46119/api/school/respect/person?includeRelated=false. 2026-09-16 14:12:26.689 [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 14:12:26.690 [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 14:12:26.691 [Test worker @coroutine#62] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46119/api/school/respect/person?includeRelated=false 2026-09-16 14:12:26.691 [Test worker @coroutine#62] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46119/api/school/respect/person?includeRelated=false 2026-09-16 14:12:26.691 [Test worker @coroutine#62] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46119/api/school/respect/person?includeRelated=false 2026-09-16 14:12:27.801 [Test worker @coroutine#90] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-09-16 14:12:27.802 [Test worker @coroutine#90] INFO io.ktor.server.Application - Application started in 0.049 seconds. 2026-09-16 14:12:27.803 [DefaultDispatcher-worker-5 @coroutine#99] INFO io.ktor.server.Application - Responding at http://0.0.0.0:44601 2026-09-16 14:12:27.804 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44601/api/school/respect/schoolpermissiongrant 2026-09-16 14:12:27.804 [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:44601/api/school/respect/schoolpermissiongrant. 2026-09-16 14:12:27.806 [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 14:12:27.808 [Test worker @coroutine#90] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44601/api/school/respect/schoolpermissiongrant 2026-09-16 14:12:27.808 [Test worker @coroutine#90] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44601/api/school/respect/schoolpermissiongrant 2026-09-16 14:12:27.808 [Test worker @coroutine#90] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44601/api/school/respect/schoolpermissiongrant 2026-09-16 14:12:27.809 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:44601/api/school/respect/schoolpermissiongrant. 2026-09-16 14:12:27.856 [DefaultDispatcher-worker-5 @coroutine#108] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44601/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-09-16 14:12:27.856 [DefaultDispatcher-worker-5 @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:44601/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100. 2026-09-16 14:12:27.858 [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 14:12:27.861 [DefaultDispatcher-worker-1 @coroutine#108] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44601/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-09-16 14:12:27.861 [DefaultDispatcher-worker-1 @coroutine#108] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44601/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-09-16 14:12:27.861 [DefaultDispatcher-worker-1 @coroutine#108] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44601/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-09-16 14:12:27.862 [DefaultDispatcher-worker-1 @coroutine#108] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:44601/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100. 2026-09-16 14:12:28.962 [Test worker @coroutine#117] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-09-16 14:12:28.963 [Test worker @coroutine#117] INFO io.ktor.server.Application - Application started in 0.039 seconds. 2026-09-16 14:12:28.964 [DefaultDispatcher-worker-5 @coroutine#126] INFO io.ktor.server.Application - Responding at http://0.0.0.0:33497 2026-09-16 14:12:28.967 [Test worker @coroutine#117] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33497/api/school/respect/person?includeRelated=false 2026-09-16 14:12:28.967 [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:33497/api/school/respect/person?includeRelated=false. 2026-09-16 14:12:28.970 [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 14:12:28.973 [Test worker @coroutine#117] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33497/api/school/respect/person?includeRelated=false 2026-09-16 14:12:28.973 [Test worker @coroutine#117] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33497/api/school/respect/person?includeRelated=false 2026-09-16 14:12:28.973 [Test worker @coroutine#117] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33497/api/school/respect/person?includeRelated=false 2026-09-16 14:12:28.974 [Test worker @coroutine#117] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:33497/api/school/respect/person?includeRelated=false. DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-09-16T10:12:28.873Z, stored=2026-09-16T10:12:29.012Z, 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=1789553548873, lastStored=1789553549012, etag=null, url=http://localhost:33497/api/school/respect/person?includeRelated=false, consistentThrough=2026-09-16T10:12:29.013572031Z, validationInfoKey=0, varyHeader=null, headers=null, permissionsLastModified=null), localState=DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-09-16T10:12:28.873Z, stored=2026-09-16T10:12:29.012Z, 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=1789553548873, lastStored=1789553549012, etag=null, url=null, consistentThrough=2026-09-16T10:12:29.013572031Z, 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-16T10:12:28.873Z, stored=2026-09-16T10:12:28.923Z, 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-16T10:12:28.871Z, stored=2026-09-16T10:12:28.965Z, 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=1789553548000, lastStored=-1, etag=null, url=http://localhost:33497/api/school/respect/person?includeRelated=false, consistentThrough=2026-09-16T10:12:28.971040086Z, validationInfoKey=7092960727423664838, varyHeader=Authorization, headers=io.ktor.client.engine.okhttp.OkUtilsKt$fromOkHttp$1@48b3b1b8, permissionsLastModified=null), localState=null, remoteState=null)) 2026-09-16 14:12:29.024 [Test worker @coroutine#117] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33497/api/school/respect/person?since=2026-09-16T10%3A12%3A28.971040086Z&includeRelated=false 2026-09-16 14:12:29.024 [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:33497/api/school/respect/person?since=2026-09-16T10%3A12%3A28.971040086Z&includeRelated=false. 2026-09-16 14:12:29.025 [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 14:12:29.027 [Test worker @coroutine#117] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33497/api/school/respect/person?since=2026-09-16T10%3A12%3A28.971040086Z&includeRelated=false 2026-09-16 14:12:29.027 [Test worker @coroutine#117] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33497/api/school/respect/person?since=2026-09-16T10%3A12%3A28.971040086Z&includeRelated=false 2026-09-16 14:12:29.027 [Test worker @coroutine#117] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33497/api/school/respect/person?since=2026-09-16T10%3A12%3A28.971040086Z&includeRelated=false 2026-09-16 14:12:29.027 [Test worker @coroutine#117] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:33497/api/school/respect/person?since=2026-09-16T10%3A12%3A28.971040086Z&includeRelated=false. Run time: 63.374138ms 2026-09-16 14:12:30.143 [Test worker @coroutine#142] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-09-16 14:12:30.144 [Test worker @coroutine#142] INFO io.ktor.server.Application - Application started in 0.039 seconds. 2026-09-16 14:12:30.145 [DefaultDispatcher-worker-5 @coroutine#151] INFO io.ktor.server.Application - Responding at http://0.0.0.0:38979 2026-09-16 14:12:30.189 [DefaultDispatcher-worker-1 @coroutine#149] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38979/api/school/respect/person 2026-09-16 14:12:30.189 [DefaultDispatcher-worker-1 @coroutine#149] TRACE i.k.c.p.c.ContentNegotiation - Converted request body using io.ktor.serialization.kotlinx.KotlinxSerializationConverter@56246685 for http://localhost:38979/api/school/respect/person 2026-09-16 14:12:30.190 [DefaultDispatcher-worker-1 @coroutine#149] TRACE i.k.c.plugins.defaultTransformers - Transformed with default transformers request body for http://localhost:38979/api/school/respect/person from class io.ktor.http.content.TextContent 2026-09-16 14:12:30.193 [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 14:12:30.196 [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 14:12:30.197 [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 14:12:30.198 [DefaultDispatcher-worker-5 @coroutine#149] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38979/api/school/respect/person 2026-09-16 14:12:30.198 [DefaultDispatcher-worker-5 @coroutine#149] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38979/api/school/respect/person 2026-09-16 14:12:30.198 [DefaultDispatcher-worker-5 @coroutine#149] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38979/api/school/respect/person 2026-09-16 14:12:31.304 [Test worker @coroutine#165] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-09-16 14:12:31.305 [Test worker @coroutine#165] INFO io.ktor.server.Application - Application started in 0.039 seconds. 2026-09-16 14:12:31.307 [DefaultDispatcher-worker-5 @coroutine#174] INFO io.ktor.server.Application - Responding at http://0.0.0.0:43915 2026-09-16 14:12:31.307 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:43915/api/school/respect/schoolpermissiongrant 2026-09-16 14:12:31.307 [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:43915/api/school/respect/schoolpermissiongrant. 2026-09-16 14:12:31.310 [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 14:12:31.312 [Test worker @coroutine#165] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:43915/api/school/respect/schoolpermissiongrant 2026-09-16 14:12:31.312 [Test worker @coroutine#165] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:43915/api/school/respect/schoolpermissiongrant 2026-09-16 14:12:31.312 [Test worker @coroutine#165] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:43915/api/school/respect/schoolpermissiongrant 2026-09-16 14:12:31.312 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:43915/api/school/respect/schoolpermissiongrant. 2026-09-16 14:12:31.353 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:43915/api/school/respect/person?includeRelated=false 2026-09-16 14:12:31.353 [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:43915/api/school/respect/person?includeRelated=false. 2026-09-16 14:12:31.354 [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 14:12:31.360 [Test worker @coroutine#165] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:43915/api/school/respect/person?includeRelated=false 2026-09-16 14:12:31.360 [Test worker @coroutine#165] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:43915/api/school/respect/person?includeRelated=false 2026-09-16 14:12:31.360 [Test worker @coroutine#165] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:43915/api/school/respect/person?includeRelated=false 2026-09-16 14:12:31.361 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:43915/api/school/respect/person?includeRelated=false. 2026-09-16 14:12:33.366 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:43915/api/school/respect/person?includeRelated=false 2026-09-16 14:12:33.366 [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:43915/api/school/respect/person?includeRelated=false. 2026-09-16 14:12:33.368 [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 14:12:33.370 [Test worker @coroutine#165] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:43915/api/school/respect/person?includeRelated=false 2026-09-16 14:12:33.370 [Test worker @coroutine#165] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:43915/api/school/respect/person?includeRelated=false 2026-09-16 14:12:33.370 [Test worker @coroutine#165] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:43915/api/school/respect/person?includeRelated=false 2026-09-16 14:12:33.370 [Test worker @coroutine#165] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:43915/api/school/respect/person?includeRelated=false. 2026-09-16 14:12:34.475 [Test worker @coroutine#196] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-09-16 14:12:34.476 [Test worker @coroutine#196] INFO io.ktor.server.Application - Application started in 0.038 seconds. 2026-09-16 14:12:34.477 [DefaultDispatcher-worker-4 @coroutine#205] INFO io.ktor.server.Application - Responding at http://0.0.0.0:41877 2026-09-16 14:12:34.480 [Test worker @coroutine#196] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41877/api/school/respect/person?includeRelated=false 2026-09-16 14:12:34.480 [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:41877/api/school/respect/person?includeRelated=false. 2026-09-16 14:12:34.482 [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 14:12:34.486 [Test worker @coroutine#196] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41877/api/school/respect/person?includeRelated=false 2026-09-16 14:12:34.486 [Test worker @coroutine#196] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41877/api/school/respect/person?includeRelated=false 2026-09-16 14:12:34.486 [Test worker @coroutine#196] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41877/api/school/respect/person?includeRelated=false 2026-09-16 14:12:34.487 [Test worker @coroutine#196] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:41877/api/school/respect/person?includeRelated=false. DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-09-16T10:12:34.377Z, stored=2026-09-16T10:12:34.524Z, 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=1789553554377, lastStored=1789553554524, etag=null, url=http://localhost:41877/api/school/respect/person?includeRelated=false, consistentThrough=2026-09-16T10:12:34.525161146Z, validationInfoKey=0, varyHeader=null, headers=null, permissionsLastModified=null), localState=DataReadyState(data=[Person(guid=4242, userActive=true, status=ACTIVE, lastModified=2026-09-16T10:12:34.377Z, stored=2026-09-16T10:12:34.524Z, 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=1789553554377, lastStored=1789553554524, etag=null, url=null, consistentThrough=2026-09-16T10:12:34.525161146Z, 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-16T10:12:34.377Z, stored=2026-09-16T10:12:34.437Z, 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-16T10:12:34.376Z, stored=2026-09-16T10:12:34.478Z, 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=1789553554000, lastStored=-1, etag=null, url=http://localhost:41877/api/school/respect/person?includeRelated=false, consistentThrough=2026-09-16T10:12:34.482960211Z, validationInfoKey=7092960727423664838, varyHeader=Authorization, headers=io.ktor.client.engine.okhttp.OkUtilsKt$fromOkHttp$1@2c2aab92, permissionsLastModified=null), localState=null, remoteState=null)) 2026-09-16 14:12:34.528 [Test worker @coroutine#196] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41877/api/school/respect/person?since=2026-09-16T10%3A12%3A34.482960211Z&includeRelated=false 2026-09-16 14:12:34.529 [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:41877/api/school/respect/person?since=2026-09-16T10%3A12%3A34.482960211Z&includeRelated=false. 2026-09-16 14:12:34.530 [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 14:12:34.531 [Test worker @coroutine#196] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41877/api/school/respect/person?since=2026-09-16T10%3A12%3A34.482960211Z&includeRelated=false 2026-09-16 14:12:34.532 [Test worker @coroutine#196] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41877/api/school/respect/person?since=2026-09-16T10%3A12%3A34.482960211Z&includeRelated=false 2026-09-16 14:12:34.532 [Test worker @coroutine#196] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41877/api/school/respect/person?since=2026-09-16T10%3A12%3A34.482960211Z&includeRelated=false 2026-09-16 14:12:34.532 [Test worker @coroutine#196] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:41877/api/school/respect/person?since=2026-09-16T10%3A12%3A34.482960211Z&includeRelated=false. Run time: 55.125389ms ]]>