2026-01-27 04:07:32.622 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 04:07:32.743 [Test worker] INFO io.ktor.server.Application - Application started in 0.298 seconds. 2026-01-27 04:07:32.850 [DefaultDispatcher-worker-1 @coroutine#2] INFO io.ktor.server.Application - Responding at http://0.0.0.0:43001 2026-01-27 04:07:33.136 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:43001/resources/index.json 2026-01-27 04:07:33.137 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:43001/resources/index.json. 2026-01-27 04:07:33.137 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:43001/resources/index.json 2026-01-27 04:07:33.355 [eventLoopGroupProxy-4-1 @call-handler#12] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 04:07:33.420 [DefaultDispatcher-worker-2 @call-handler#12] INFO io.ktor.server.Application - 200 OK: GET - /resources/index.json in 72ms 2026-01-27 04:07:33.425 [DefaultDispatcher-worker-4 @coroutine#8] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:43001/resources/index.json 2026-01-27 04:07:33.428 [DefaultDispatcher-worker-4 @coroutine#8] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:43001/resources/index.json 2026-01-27 04:07:33.429 [DefaultDispatcher-worker-4 @coroutine#8] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:43001/resources/index.json 2026-01-27 04:07:33.453 [DefaultDispatcher-worker-4 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsFeed for http://localhost:43001/resources/index.json. 2026-01-27 04:07:33.495 [DefaultDispatcher-worker-4 @coroutine#20] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:43001/resources/index.json 2026-01-27 04:07:33.495 [DefaultDispatcher-worker-4 @coroutine#20] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:43001/resources/index.json. 2026-01-27 04:07:33.496 [DefaultDispatcher-worker-4 @coroutine#20] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:43001/resources/index.json 2026-01-27 04:07:33.500 [eventLoopGroupProxy-4-1 @call-handler#22] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 04:07:33.503 [eventLoopGroupProxy-4-1 @call-handler#22] INFO io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 3ms 2026-01-27 04:07:33.504 [DefaultDispatcher-worker-4 @coroutine#20] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:43001/resources/index.json 2026-01-27 04:07:33.505 [DefaultDispatcher-worker-4 @coroutine#20] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:43001/resources/index.json 2026-01-27 04:07:33.505 [DefaultDispatcher-worker-4 @coroutine#20] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:43001/resources/index.json 2026-01-27 04:07:35.528 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 04:07:35.528 [Test worker] INFO io.ktor.server.Application - Application started in 0.0 seconds. 2026-01-27 04:07:35.530 [DefaultDispatcher-worker-4 @coroutine#26] INFO io.ktor.server.Application - Responding at http://0.0.0.0:45019 2026-01-27 04:07:35.582 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45019/resources/index.json 2026-01-27 04:07:35.583 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:45019/resources/index.json. 2026-01-27 04:07:35.583 [Test worker @coroutine#30] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45019/resources/index.json 2026-01-27 04:07:35.587 [eventLoopGroupProxy-7-1 @call-handler#35] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 04:07:35.588 [DefaultDispatcher-worker-4 @call-handler#35] INFO io.ktor.server.Application - 200 OK: GET - /resources/index.json in 2ms 2026-01-27 04:07:35.590 [Test worker @coroutine#30] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45019/resources/index.json 2026-01-27 04:07:35.590 [Test worker @coroutine#30] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45019/resources/index.json 2026-01-27 04:07:35.590 [Test worker @coroutine#30] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45019/resources/index.json 2026-01-27 04:07:35.591 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsFeed for http://localhost:45019/resources/index.json. 2026-01-27 04:07:35.598 [DefaultDispatcher-worker-4 @coroutine#43] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45019/resources/index.json 2026-01-27 04:07:35.598 [DefaultDispatcher-worker-4 @coroutine#43] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:45019/resources/index.json. 2026-01-27 04:07:35.598 [DefaultDispatcher-worker-4 @coroutine#43] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45019/resources/index.json 2026-01-27 04:07:35.607 [eventLoopGroupProxy-7-1 @call-handler#45] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 04:07:35.608 [eventLoopGroupProxy-7-1 @call-handler#45] INFO io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 8ms 2026-01-27 04:07:35.610 [DefaultDispatcher-worker-3 @coroutine#43] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45019/resources/index.json 2026-01-27 04:07:35.610 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45019/resources/index.json 2026-01-27 04:07:35.610 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45019/resources/index.json 2026-01-27 04:07:37.622 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 04:07:37.622 [Test worker] INFO io.ktor.server.Application - Application started in 0.0 seconds. 2026-01-27 04:07:37.624 [DefaultDispatcher-worker-3 @coroutine#49] INFO io.ktor.server.Application - Responding at http://0.0.0.0:39983 2026-01-27 04:07:37.628 [Test worker @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39983/resources/lesson001.json 2026-01-27 04:07:37.629 [Test worker @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39983/resources/lesson001.json. 2026-01-27 04:07:37.629 [Test worker @coroutine#52] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39983/resources/lesson001.json 2026-01-27 04:07:37.638 [eventLoopGroupProxy-10-1 @call-handler#56] TRACE io.ktor.server.routing.Routing - Trace for [resources, lesson001.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[lesson001.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[lesson001.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 04:07:37.639 [DefaultDispatcher-worker-4 @call-handler#56] INFO io.ktor.server.Application - 200 OK: GET - /resources/lesson001.json in 1ms 2026-01-27 04:07:37.640 [DefaultDispatcher-worker-2 @coroutine#52] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39983/resources/lesson001.json 2026-01-27 04:07:37.640 [DefaultDispatcher-worker-2 @coroutine#52] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39983/resources/lesson001.json 2026-01-27 04:07:37.640 [DefaultDispatcher-worker-2 @coroutine#52] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39983/resources/lesson001.json 2026-01-27 04:07:37.662 [DefaultDispatcher-worker-2 @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsPublication for http://localhost:39983/resources/lesson001.json. 2026-01-27 04:07:40.827 [Test worker @coroutine#61] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 04:07:40.892 [Test worker @coroutine#61] INFO io.ktor.server.Application - Application started in 1.103 seconds. 2026-01-27 04:07:40.905 [DefaultDispatcher-worker-2 @coroutine#70] INFO io.ktor.server.Application - Responding at http://0.0.0.0:39861 2026-01-27 04:07:40.934 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39861/api/school/respect/schoolpermissiongrant 2026-01-27 04:07:40.935 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39861/api/school/respect/schoolpermissiongrant. 2026-01-27 04:07:40.935 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39861/api/school/respect/schoolpermissiongrant 2026-01-27 04:07:40.955 [eventLoopGroupProxy-13-1 @call-handler#74] 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-01-27 04:07:41.038 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39861/api/school/respect/schoolpermissiongrant 2026-01-27 04:07:41.038 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39861/api/school/respect/schoolpermissiongrant 2026-01-27 04:07:41.039 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39861/api/school/respect/schoolpermissiongrant 2026-01-27 04:07:41.045 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39861/api/school/respect/schoolpermissiongrant. 2026-01-27 04:07:41.246 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39861/api/school/respect/person?includeRelated=false 2026-01-27 04:07:41.246 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39861/api/school/respect/person?includeRelated=false. 2026-01-27 04:07:41.246 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39861/api/school/respect/person?includeRelated=false 2026-01-27 04:07:41.270 [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-01-27 04:07:41.300 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39861/api/school/respect/person?includeRelated=false 2026-01-27 04:07:41.300 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39861/api/school/respect/person?includeRelated=false 2026-01-27 04:07:41.301 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39861/api/school/respect/person?includeRelated=false 2026-01-27 04:07:41.304 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39861/api/school/respect/person?includeRelated=false. 2026-01-27 04:07:41.314 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39861/api/school/respect/person?includeRelated=false 2026-01-27 04:07:41.315 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39861/api/school/respect/person?includeRelated=false. 2026-01-27 04:07:41.315 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39861/api/school/respect/person?includeRelated=false 2026-01-27 04:07:41.318 [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-01-27 04:07:41.322 [DefaultDispatcher-worker-6 @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-01-27 04:07:41.324 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39861/api/school/respect/person?includeRelated=false 2026-01-27 04:07:41.324 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39861/api/school/respect/person?includeRelated=false 2026-01-27 04:07:41.324 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39861/api/school/respect/person?includeRelated=false 2026-01-27 04:07:43.437 [Test worker @coroutine#90] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 04:07:43.438 [Test worker @coroutine#90] INFO io.ktor.server.Application - Application started in 0.037 seconds. 2026-01-27 04:07:43.439 [DefaultDispatcher-worker-6 @coroutine#99] INFO io.ktor.server.Application - Responding at http://0.0.0.0:41199 2026-01-27 04:07:43.440 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41199/api/school/respect/schoolpermissiongrant 2026-01-27 04:07:43.440 [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:41199/api/school/respect/schoolpermissiongrant. 2026-01-27 04:07:43.440 [Test worker @coroutine#90] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:41199/api/school/respect/schoolpermissiongrant 2026-01-27 04:07:43.443 [eventLoopGroupProxy-16-1 @call-handler#103] 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-01-27 04:07:43.445 [Test worker @coroutine#90] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41199/api/school/respect/schoolpermissiongrant 2026-01-27 04:07:43.445 [Test worker @coroutine#90] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41199/api/school/respect/schoolpermissiongrant 2026-01-27 04:07:43.445 [Test worker @coroutine#90] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41199/api/school/respect/schoolpermissiongrant 2026-01-27 04:07:43.445 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:41199/api/school/respect/schoolpermissiongrant. 2026-01-27 04:07:43.482 [DefaultDispatcher-worker-3 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41199/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 04:07:43.482 [DefaultDispatcher-worker-3 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:41199/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100. 2026-01-27 04:07:43.482 [DefaultDispatcher-worker-3 @coroutine#109] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:41199/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 04:07:43.484 [eventLoopGroupProxy-16-1 @call-handler#113] 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-01-27 04:07:43.486 [DefaultDispatcher-worker-6 @coroutine#109] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41199/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 04:07:43.487 [DefaultDispatcher-worker-6 @coroutine#109] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41199/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 04:07:43.487 [DefaultDispatcher-worker-6 @coroutine#109] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41199/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 04:07:43.487 [DefaultDispatcher-worker-6 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:41199/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100. 2026-01-27 04:07:45.609 [Test worker @coroutine#118] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 04:07:45.610 [Test worker @coroutine#118] INFO io.ktor.server.Application - Application started in 0.036 seconds. 2026-01-27 04:07:45.612 [DefaultDispatcher-worker-6 @coroutine#127] INFO io.ktor.server.Application - Responding at http://0.0.0.0:45357 2026-01-27 04:07:45.615 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45357/api/school/respect/person?includeRelated=false 2026-01-27 04:07:45.615 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:45357/api/school/respect/person?includeRelated=false. 2026-01-27 04:07:45.615 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45357/api/school/respect/person?includeRelated=false 2026-01-27 04:07:45.619 [eventLoopGroupProxy-19-1 @call-handler#132] 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-01-27 04:07:45.624 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45357/api/school/respect/person?includeRelated=false 2026-01-27 04:07:45.624 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45357/api/school/respect/person?includeRelated=false 2026-01-27 04:07:45.624 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45357/api/school/respect/person?includeRelated=false 2026-01-27 04:07:45.626 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:45357/api/school/respect/person?includeRelated=false. 2026-01-27 04:07:45.693 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45357/api/school/respect/person?since=2026-01-27T03%3A07%3A45.619593405Z&includeRelated=false 2026-01-27 04:07:45.694 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:45357/api/school/respect/person?since=2026-01-27T03%3A07%3A45.619593405Z&includeRelated=false. 2026-01-27 04:07:45.694 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45357/api/school/respect/person?since=2026-01-27T03%3A07%3A45.619593405Z&includeRelated=false 2026-01-27 04:07:45.699 [eventLoopGroupProxy-19-1 @call-handler#138] 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-01-27 04:07:45.704 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45357/api/school/respect/person?since=2026-01-27T03%3A07%3A45.619593405Z&includeRelated=false 2026-01-27 04:07:45.705 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45357/api/school/respect/person?since=2026-01-27T03%3A07%3A45.619593405Z&includeRelated=false 2026-01-27 04:07:45.705 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45357/api/school/respect/person?since=2026-01-27T03%3A07%3A45.619593405Z&includeRelated=false 2026-01-27 04:07:45.706 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:45357/api/school/respect/person?since=2026-01-27T03%3A07%3A45.619593405Z&includeRelated=false. 2026-01-27 04:07:46.739 [eventLoopGroupProxy-18-1] WARN i.n.u.c.AbstractEventExecutor - A task raised an exception. Task: io.netty.channel.AbstractChannel$AbstractUnsafe$8@33febae4 java.util.concurrent.RejectedExecutionException: event executor terminated at io.netty.util.concurrent.SingleThreadEventExecutor.reject(SingleThreadEventExecutor.java:1005) at io.netty.util.concurrent.SingleThreadEventExecutor.offerTask(SingleThreadEventExecutor.java:388) at io.netty.util.concurrent.SingleThreadEventExecutor.addTask(SingleThreadEventExecutor.java:381) at io.netty.util.concurrent.SingleThreadEventExecutor.execute(SingleThreadEventExecutor.java:907) at io.netty.util.concurrent.SingleThreadEventExecutor.execute0(SingleThreadEventExecutor.java:873) at io.netty.util.concurrent.SingleThreadEventExecutor.execute(SingleThreadEventExecutor.java:863) at io.netty.channel.DefaultChannelPipeline.destroyUp(DefaultChannelPipeline.java:816) at io.netty.channel.DefaultChannelPipeline.destroy(DefaultChannelPipeline.java:801) at io.netty.channel.DefaultChannelPipeline.access$700(DefaultChannelPipeline.java:45) at io.netty.channel.DefaultChannelPipeline$HeadContext.channelUnregistered(DefaultChannelPipeline.java:1411) at io.netty.channel.DefaultChannelPipeline.fireChannelUnregistered(DefaultChannelPipeline.java:780) at io.netty.channel.AbstractChannel$AbstractUnsafe$8.run(AbstractChannel.java:692) at io.netty.util.concurrent.AbstractEventExecutor.runTask(AbstractEventExecutor.java:148) at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:141) at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:507) at io.netty.channel.SingleThreadIoEventLoop.run(SingleThreadIoEventLoop.java:183) at io.netty.util.concurrent.SingleThreadEventExecutor$5.run(SingleThreadEventExecutor.java:1073) at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) at java.base/java.lang.Thread.run(Thread.java:1583) 2026-01-27 04:07:47.870 [Test worker @coroutine#144] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 04:07:47.871 [Test worker @coroutine#144] INFO io.ktor.server.Application - Application started in 0.052 seconds. 2026-01-27 04:07:47.882 [DefaultDispatcher-worker-2 @coroutine#153] INFO io.ktor.server.Application - Responding at http://0.0.0.0:46135 2026-01-27 04:07:47.927 [DefaultDispatcher-worker-6 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46135/api/school/respect/person 2026-01-27 04:07:47.927 [DefaultDispatcher-worker-6 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Converted request body using io.ktor.serialization.kotlinx.KotlinxSerializationConverter@59e43ac5 for http://localhost:46135/api/school/respect/person 2026-01-27 04:07:47.927 [DefaultDispatcher-worker-6 @coroutine#151] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:46135/api/school/respect/person 2026-01-27 04:07:47.927 [DefaultDispatcher-worker-6 @coroutine#151] TRACE i.k.c.plugins.defaultTransformers - Transformed with default transformers request body for http://localhost:46135/api/school/respect/person from class io.ktor.http.content.TextContent 2026-01-27 04:07:47.946 [eventLoopGroupProxy-22-1 @call-handler#163] 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-01-27 04:07:47.956 [eventLoopGroupProxy-22-1 @call-handler#163] 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-01-27 04:07:47.959 [DefaultDispatcher-worker-2 @call-handler#163] 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-01-27 04:07:47.969 [DefaultDispatcher-worker-5 @coroutine#151] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46135/api/school/respect/person 2026-01-27 04:07:47.970 [DefaultDispatcher-worker-5 @coroutine#151] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46135/api/school/respect/person 2026-01-27 04:07:47.970 [DefaultDispatcher-worker-5 @coroutine#151] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46135/api/school/respect/person 2026-01-27 04:07:50.158 [Test worker @coroutine#168] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 04:07:50.162 [Test worker @coroutine#168] INFO io.ktor.server.Application - Application started in 0.056 seconds. 2026-01-27 04:07:50.163 [DefaultDispatcher-worker-11 @coroutine#177] INFO io.ktor.server.Application - Responding at http://0.0.0.0:43969 2026-01-27 04:07:50.164 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:43969/api/school/respect/schoolpermissiongrant 2026-01-27 04:07:50.164 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:43969/api/school/respect/schoolpermissiongrant. 2026-01-27 04:07:50.164 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:43969/api/school/respect/schoolpermissiongrant 2026-01-27 04:07:50.167 [eventLoopGroupProxy-25-1 @call-handler#181] 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-01-27 04:07:50.170 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:43969/api/school/respect/schoolpermissiongrant 2026-01-27 04:07:50.170 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:43969/api/school/respect/schoolpermissiongrant 2026-01-27 04:07:50.170 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:43969/api/school/respect/schoolpermissiongrant 2026-01-27 04:07:50.170 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:43969/api/school/respect/schoolpermissiongrant. 2026-01-27 04:07:50.221 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:43969/api/school/respect/person?includeRelated=false 2026-01-27 04:07:50.222 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:43969/api/school/respect/person?includeRelated=false. 2026-01-27 04:07:50.222 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:43969/api/school/respect/person?includeRelated=false 2026-01-27 04:07:50.231 [eventLoopGroupProxy-25-1 @call-handler#187] 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-01-27 04:07:50.251 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:43969/api/school/respect/person?includeRelated=false 2026-01-27 04:07:50.254 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:43969/api/school/respect/person?includeRelated=false 2026-01-27 04:07:50.255 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:43969/api/school/respect/person?includeRelated=false 2026-01-27 04:07:50.259 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:43969/api/school/respect/person?includeRelated=false. 2026-01-27 04:07:52.268 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:43969/api/school/respect/person?includeRelated=false 2026-01-27 04:07:52.268 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:43969/api/school/respect/person?includeRelated=false. 2026-01-27 04:07:52.268 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:43969/api/school/respect/person?includeRelated=false 2026-01-27 04:07:52.270 [eventLoopGroupProxy-25-1 @call-handler#194] 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-01-27 04:07:52.274 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:43969/api/school/respect/person?includeRelated=false 2026-01-27 04:07:52.274 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:43969/api/school/respect/person?includeRelated=false 2026-01-27 04:07:52.274 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:43969/api/school/respect/person?includeRelated=false 2026-01-27 04:07:52.276 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:43969/api/school/respect/person?includeRelated=false. 2026-01-27 04:07:54.413 [Test worker @coroutine#200] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 04:07:54.414 [Test worker @coroutine#200] INFO io.ktor.server.Application - Application started in 0.045 seconds. 2026-01-27 04:07:54.415 [DefaultDispatcher-worker-5 @coroutine#209] INFO io.ktor.server.Application - Responding at http://0.0.0.0:36977 2026-01-27 04:07:54.419 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:36977/api/school/respect/person?includeRelated=false 2026-01-27 04:07:54.419 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:36977/api/school/respect/person?includeRelated=false. 2026-01-27 04:07:54.419 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:36977/api/school/respect/person?includeRelated=false 2026-01-27 04:07:54.423 [eventLoopGroupProxy-28-1 @call-handler#214] 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-01-27 04:07:54.427 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:36977/api/school/respect/person?includeRelated=false 2026-01-27 04:07:54.427 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:36977/api/school/respect/person?includeRelated=false 2026-01-27 04:07:54.427 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:36977/api/school/respect/person?includeRelated=false 2026-01-27 04:07:54.428 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:36977/api/school/respect/person?includeRelated=false. 2026-01-27 04:07:54.476 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:36977/api/school/respect/person?since=2026-01-27T03%3A07%3A54.423609845Z&includeRelated=false 2026-01-27 04:07:54.476 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:36977/api/school/respect/person?since=2026-01-27T03%3A07%3A54.423609845Z&includeRelated=false. 2026-01-27 04:07:54.476 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:36977/api/school/respect/person?since=2026-01-27T03%3A07%3A54.423609845Z&includeRelated=false 2026-01-27 04:07:54.477 [eventLoopGroupProxy-28-1 @call-handler#221] 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-01-27 04:07:54.482 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:36977/api/school/respect/person?since=2026-01-27T03%3A07%3A54.423609845Z&includeRelated=false 2026-01-27 04:07:54.482 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:36977/api/school/respect/person?since=2026-01-27T03%3A07%3A54.423609845Z&includeRelated=false 2026-01-27 04:07:54.482 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:36977/api/school/respect/person?since=2026-01-27T03%3A07%3A54.423609845Z&includeRelated=false 2026-01-27 04:07:54.483 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:36977/api/school/respect/person?since=2026-01-27T03%3A07%3A54.423609845Z&includeRelated=false. 2026-01-27 13:14:24.770 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:14:24.917 [Test worker] INFO io.ktor.server.Application - Application started in 0.327 seconds. 2026-01-27 13:14:25.042 [DefaultDispatcher-worker-1 @coroutine#2] INFO io.ktor.server.Application - Responding at http://0.0.0.0:33551 2026-01-27 13:14:25.358 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33551/resources/index.json 2026-01-27 13:14:25.359 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:33551/resources/index.json. 2026-01-27 13:14:25.360 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33551/resources/index.json 2026-01-27 13:14:25.543 [eventLoopGroupProxy-4-1 @call-handler#12] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 13:14:25.590 [eventLoopGroupProxy-4-1 @call-handler#12] INFO io.ktor.server.Application - 200 OK: GET - /resources/index.json in 53ms 2026-01-27 13:14:25.596 [DefaultDispatcher-worker-2 @coroutine#8] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33551/resources/index.json 2026-01-27 13:14:25.598 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33551/resources/index.json 2026-01-27 13:14:25.598 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33551/resources/index.json 2026-01-27 13:14:25.617 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsFeed for http://localhost:33551/resources/index.json. 2026-01-27 13:14:25.651 [DefaultDispatcher-worker-1 @coroutine#20] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33551/resources/index.json 2026-01-27 13:14:25.651 [DefaultDispatcher-worker-1 @coroutine#20] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:33551/resources/index.json. 2026-01-27 13:14:25.651 [DefaultDispatcher-worker-1 @coroutine#20] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33551/resources/index.json 2026-01-27 13:14:25.654 [eventLoopGroupProxy-4-1 @call-handler#22] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 13:14:25.655 [eventLoopGroupProxy-4-1 @call-handler#22] INFO io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 1ms 2026-01-27 13:14:25.656 [DefaultDispatcher-worker-1 @coroutine#20] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33551/resources/index.json 2026-01-27 13:14:25.657 [DefaultDispatcher-worker-1 @coroutine#20] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33551/resources/index.json 2026-01-27 13:14:25.657 [DefaultDispatcher-worker-1 @coroutine#20] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33551/resources/index.json 2026-01-27 13:14:27.679 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:14:27.680 [Test worker] INFO io.ktor.server.Application - Application started in 0.001 seconds. 2026-01-27 13:14:27.684 [DefaultDispatcher-worker-1 @coroutine#26] INFO io.ktor.server.Application - Responding at http://0.0.0.0:33851 2026-01-27 13:14:27.722 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33851/resources/index.json 2026-01-27 13:14:27.722 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:33851/resources/index.json. 2026-01-27 13:14:27.722 [Test worker @coroutine#30] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33851/resources/index.json 2026-01-27 13:14:27.726 [eventLoopGroupProxy-7-1 @call-handler#35] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 13:14:27.727 [eventLoopGroupProxy-7-1 @call-handler#35] INFO io.ktor.server.Application - 200 OK: GET - /resources/index.json in 2ms 2026-01-27 13:14:27.727 [Test worker @coroutine#30] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33851/resources/index.json 2026-01-27 13:14:27.727 [Test worker @coroutine#30] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33851/resources/index.json 2026-01-27 13:14:27.728 [Test worker @coroutine#30] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33851/resources/index.json 2026-01-27 13:14:27.728 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsFeed for http://localhost:33851/resources/index.json. 2026-01-27 13:14:27.734 [DefaultDispatcher-worker-1 @coroutine#43] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33851/resources/index.json 2026-01-27 13:14:27.734 [DefaultDispatcher-worker-1 @coroutine#43] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:33851/resources/index.json. 2026-01-27 13:14:27.734 [DefaultDispatcher-worker-1 @coroutine#43] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33851/resources/index.json 2026-01-27 13:14:27.736 [eventLoopGroupProxy-7-1 @call-handler#45] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 13:14:27.736 [eventLoopGroupProxy-7-1 @call-handler#45] INFO io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 1ms 2026-01-27 13:14:27.737 [DefaultDispatcher-worker-3 @coroutine#43] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33851/resources/index.json 2026-01-27 13:14:27.737 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33851/resources/index.json 2026-01-27 13:14:27.737 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33851/resources/index.json 2026-01-27 13:14:29.744 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:14:29.745 [Test worker] INFO io.ktor.server.Application - Application started in 0.001 seconds. 2026-01-27 13:14:29.746 [DefaultDispatcher-worker-3 @coroutine#49] INFO io.ktor.server.Application - Responding at http://0.0.0.0:34197 2026-01-27 13:14:29.751 [Test worker @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34197/resources/lesson001.json 2026-01-27 13:14:29.751 [Test worker @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:34197/resources/lesson001.json. 2026-01-27 13:14:29.751 [Test worker @coroutine#52] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:34197/resources/lesson001.json 2026-01-27 13:14:29.754 [eventLoopGroupProxy-10-1 @call-handler#56] TRACE io.ktor.server.routing.Routing - Trace for [resources, lesson001.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[lesson001.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[lesson001.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 13:14:29.755 [eventLoopGroupProxy-10-1 @call-handler#56] INFO io.ktor.server.Application - 200 OK: GET - /resources/lesson001.json in 1ms 2026-01-27 13:14:29.756 [DefaultDispatcher-worker-3 @coroutine#52] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34197/resources/lesson001.json 2026-01-27 13:14:29.756 [DefaultDispatcher-worker-3 @coroutine#52] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34197/resources/lesson001.json 2026-01-27 13:14:29.756 [DefaultDispatcher-worker-3 @coroutine#52] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34197/resources/lesson001.json 2026-01-27 13:14:29.779 [DefaultDispatcher-worker-3 @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsPublication for http://localhost:34197/resources/lesson001.json. 2026-01-27 13:14:33.054 [Test worker @coroutine#61] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:14:33.084 [Test worker @coroutine#61] INFO io.ktor.server.Application - Application started in 1.142 seconds. 2026-01-27 13:14:33.092 [DefaultDispatcher-worker-3 @coroutine#70] INFO io.ktor.server.Application - Responding at http://0.0.0.0:46567 2026-01-27 13:14:33.122 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46567/api/school/respect/schoolpermissiongrant 2026-01-27 13:14:33.122 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:46567/api/school/respect/schoolpermissiongrant. 2026-01-27 13:14:33.122 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:46567/api/school/respect/schoolpermissiongrant 2026-01-27 13:14:33.143 [eventLoopGroupProxy-13-1 @call-handler#74] 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-01-27 13:14:33.179 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46567/api/school/respect/schoolpermissiongrant 2026-01-27 13:14:33.179 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46567/api/school/respect/schoolpermissiongrant 2026-01-27 13:14:33.180 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46567/api/school/respect/schoolpermissiongrant 2026-01-27 13:14:33.183 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:46567/api/school/respect/schoolpermissiongrant. 2026-01-27 13:14:33.333 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46567/api/school/respect/person?includeRelated=false 2026-01-27 13:14:33.334 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:46567/api/school/respect/person?includeRelated=false. 2026-01-27 13:14:33.335 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:46567/api/school/respect/person?includeRelated=false 2026-01-27 13:14:33.340 [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-01-27 13:14:33.377 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46567/api/school/respect/person?includeRelated=false 2026-01-27 13:14:33.377 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46567/api/school/respect/person?includeRelated=false 2026-01-27 13:14:33.377 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46567/api/school/respect/person?includeRelated=false 2026-01-27 13:14:33.380 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:46567/api/school/respect/person?includeRelated=false. 2026-01-27 13:14:33.390 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46567/api/school/respect/person?includeRelated=false 2026-01-27 13:14:33.390 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:46567/api/school/respect/person?includeRelated=false. 2026-01-27 13:14:33.390 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:46567/api/school/respect/person?includeRelated=false 2026-01-27 13:14:33.393 [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-01-27 13:14:33.396 [DefaultDispatcher-worker-3 @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-01-27 13:14:33.397 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46567/api/school/respect/person?includeRelated=false 2026-01-27 13:14:33.398 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46567/api/school/respect/person?includeRelated=false 2026-01-27 13:14:33.398 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46567/api/school/respect/person?includeRelated=false 2026-01-27 13:14:35.531 [Test worker @coroutine#90] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:14:35.532 [Test worker @coroutine#90] INFO io.ktor.server.Application - Application started in 0.035 seconds. 2026-01-27 13:14:35.534 [DefaultDispatcher-worker-5 @coroutine#99] INFO io.ktor.server.Application - Responding at http://0.0.0.0:44153 2026-01-27 13:14:35.534 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44153/api/school/respect/schoolpermissiongrant 2026-01-27 13:14:35.534 [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:44153/api/school/respect/schoolpermissiongrant. 2026-01-27 13:14:35.534 [Test worker @coroutine#90] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:44153/api/school/respect/schoolpermissiongrant 2026-01-27 13:14:35.537 [eventLoopGroupProxy-16-1 @call-handler#103] 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-01-27 13:14:35.539 [Test worker @coroutine#90] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44153/api/school/respect/schoolpermissiongrant 2026-01-27 13:14:35.539 [Test worker @coroutine#90] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44153/api/school/respect/schoolpermissiongrant 2026-01-27 13:14:35.539 [Test worker @coroutine#90] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44153/api/school/respect/schoolpermissiongrant 2026-01-27 13:14:35.539 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:44153/api/school/respect/schoolpermissiongrant. 2026-01-27 13:14:35.581 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44153/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 13:14:35.581 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:44153/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100. 2026-01-27 13:14:35.581 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:44153/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 13:14:35.583 [eventLoopGroupProxy-16-1 @call-handler#113] 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-01-27 13:14:35.585 [DefaultDispatcher-worker-3 @coroutine#109] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44153/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 13:14:35.586 [DefaultDispatcher-worker-3 @coroutine#109] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44153/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 13:14:35.586 [DefaultDispatcher-worker-3 @coroutine#109] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44153/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 13:14:35.586 [DefaultDispatcher-worker-3 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:44153/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100. 2026-01-27 13:14:37.750 [Test worker @coroutine#118] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:14:37.752 [Test worker @coroutine#118] INFO io.ktor.server.Application - Application started in 0.048 seconds. 2026-01-27 13:14:37.754 [DefaultDispatcher-worker-4 @coroutine#127] INFO io.ktor.server.Application - Responding at http://0.0.0.0:40321 2026-01-27 13:14:37.766 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40321/api/school/respect/person?includeRelated=false 2026-01-27 13:14:37.769 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:40321/api/school/respect/person?includeRelated=false. 2026-01-27 13:14:37.769 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40321/api/school/respect/person?includeRelated=false 2026-01-27 13:14:37.788 [eventLoopGroupProxy-19-1 @call-handler#132] 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-01-27 13:14:37.795 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40321/api/school/respect/person?includeRelated=false 2026-01-27 13:14:37.796 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40321/api/school/respect/person?includeRelated=false 2026-01-27 13:14:37.796 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40321/api/school/respect/person?includeRelated=false 2026-01-27 13:14:37.801 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:40321/api/school/respect/person?includeRelated=false. 2026-01-27 13:14:37.903 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40321/api/school/respect/person?since=2026-01-27T12%3A14%3A37.790402500Z&includeRelated=false 2026-01-27 13:14:37.903 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:40321/api/school/respect/person?since=2026-01-27T12%3A14%3A37.790402500Z&includeRelated=false. 2026-01-27 13:14:37.903 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40321/api/school/respect/person?since=2026-01-27T12%3A14%3A37.790402500Z&includeRelated=false 2026-01-27 13:14:37.910 [eventLoopGroupProxy-19-1 @call-handler#138] 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-01-27 13:14:37.920 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40321/api/school/respect/person?since=2026-01-27T12%3A14%3A37.790402500Z&includeRelated=false 2026-01-27 13:14:37.920 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40321/api/school/respect/person?since=2026-01-27T12%3A14%3A37.790402500Z&includeRelated=false 2026-01-27 13:14:37.921 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40321/api/school/respect/person?since=2026-01-27T12%3A14%3A37.790402500Z&includeRelated=false 2026-01-27 13:14:37.922 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:40321/api/school/respect/person?since=2026-01-27T12%3A14%3A37.790402500Z&includeRelated=false. 2026-01-27 13:14:40.101 [Test worker @coroutine#144] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:14:40.103 [Test worker @coroutine#144] INFO io.ktor.server.Application - Application started in 0.038 seconds. 2026-01-27 13:14:40.104 [DefaultDispatcher-worker-1 @coroutine#153] INFO io.ktor.server.Application - Responding at http://0.0.0.0:34257 2026-01-27 13:14:40.143 [DefaultDispatcher-worker-1 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34257/api/school/respect/person 2026-01-27 13:14:40.144 [DefaultDispatcher-worker-1 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Converted request body using io.ktor.serialization.kotlinx.KotlinxSerializationConverter@145156d2 for http://localhost:34257/api/school/respect/person 2026-01-27 13:14:40.144 [DefaultDispatcher-worker-1 @coroutine#151] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:34257/api/school/respect/person 2026-01-27 13:14:40.144 [DefaultDispatcher-worker-1 @coroutine#151] TRACE i.k.c.plugins.defaultTransformers - Transformed with default transformers request body for http://localhost:34257/api/school/respect/person from class io.ktor.http.content.TextContent 2026-01-27 13:14:40.150 [eventLoopGroupProxy-22-1 @call-handler#163] 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-01-27 13:14:40.156 [eventLoopGroupProxy-22-1 @call-handler#163] 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-01-27 13:14:40.158 [DefaultDispatcher-worker-1 @call-handler#163] 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-01-27 13:14:40.159 [DefaultDispatcher-worker-2 @coroutine#151] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34257/api/school/respect/person 2026-01-27 13:14:40.159 [DefaultDispatcher-worker-2 @coroutine#151] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34257/api/school/respect/person 2026-01-27 13:14:40.160 [DefaultDispatcher-worker-2 @coroutine#151] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34257/api/school/respect/person 2026-01-27 13:14:42.334 [Test worker @coroutine#168] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:14:42.335 [Test worker @coroutine#168] INFO io.ktor.server.Application - Application started in 0.04 seconds. 2026-01-27 13:14:42.336 [DefaultDispatcher-worker-3 @coroutine#177] INFO io.ktor.server.Application - Responding at http://0.0.0.0:45853 2026-01-27 13:14:42.338 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45853/api/school/respect/schoolpermissiongrant 2026-01-27 13:14:42.338 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:45853/api/school/respect/schoolpermissiongrant. 2026-01-27 13:14:42.338 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45853/api/school/respect/schoolpermissiongrant 2026-01-27 13:14:42.341 [eventLoopGroupProxy-25-1 @call-handler#181] 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-01-27 13:14:42.343 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45853/api/school/respect/schoolpermissiongrant 2026-01-27 13:14:42.343 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45853/api/school/respect/schoolpermissiongrant 2026-01-27 13:14:42.343 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45853/api/school/respect/schoolpermissiongrant 2026-01-27 13:14:42.344 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:45853/api/school/respect/schoolpermissiongrant. 2026-01-27 13:14:42.382 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45853/api/school/respect/person?includeRelated=false 2026-01-27 13:14:42.382 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:45853/api/school/respect/person?includeRelated=false. 2026-01-27 13:14:42.383 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45853/api/school/respect/person?includeRelated=false 2026-01-27 13:14:42.384 [eventLoopGroupProxy-25-1 @call-handler#187] 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-01-27 13:14:42.392 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45853/api/school/respect/person?includeRelated=false 2026-01-27 13:14:42.392 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45853/api/school/respect/person?includeRelated=false 2026-01-27 13:14:42.392 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45853/api/school/respect/person?includeRelated=false 2026-01-27 13:14:42.393 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:45853/api/school/respect/person?includeRelated=false. 2026-01-27 13:14:44.401 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45853/api/school/respect/person?includeRelated=false 2026-01-27 13:14:44.401 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:45853/api/school/respect/person?includeRelated=false. 2026-01-27 13:14:44.401 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45853/api/school/respect/person?includeRelated=false 2026-01-27 13:14:44.403 [eventLoopGroupProxy-25-1 @call-handler#194] 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-01-27 13:14:44.407 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45853/api/school/respect/person?includeRelated=false 2026-01-27 13:14:44.407 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45853/api/school/respect/person?includeRelated=false 2026-01-27 13:14:44.407 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45853/api/school/respect/person?includeRelated=false 2026-01-27 13:14:44.411 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:45853/api/school/respect/person?includeRelated=false. 2026-01-27 13:14:46.547 [Test worker @coroutine#200] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:14:46.548 [Test worker @coroutine#200] INFO io.ktor.server.Application - Application started in 0.04 seconds. 2026-01-27 13:14:46.549 [DefaultDispatcher-worker-3 @coroutine#209] INFO io.ktor.server.Application - Responding at http://0.0.0.0:36889 2026-01-27 13:14:46.553 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:36889/api/school/respect/person?includeRelated=false 2026-01-27 13:14:46.553 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:36889/api/school/respect/person?includeRelated=false. 2026-01-27 13:14:46.553 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:36889/api/school/respect/person?includeRelated=false 2026-01-27 13:14:46.556 [eventLoopGroupProxy-28-1 @call-handler#214] 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-01-27 13:14:46.559 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:36889/api/school/respect/person?includeRelated=false 2026-01-27 13:14:46.559 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:36889/api/school/respect/person?includeRelated=false 2026-01-27 13:14:46.559 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:36889/api/school/respect/person?includeRelated=false 2026-01-27 13:14:46.560 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:36889/api/school/respect/person?includeRelated=false. 2026-01-27 13:14:46.608 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:36889/api/school/respect/person?since=2026-01-27T12%3A14%3A46.556513699Z&includeRelated=false 2026-01-27 13:14:46.608 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:36889/api/school/respect/person?since=2026-01-27T12%3A14%3A46.556513699Z&includeRelated=false. 2026-01-27 13:14:46.609 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:36889/api/school/respect/person?since=2026-01-27T12%3A14%3A46.556513699Z&includeRelated=false 2026-01-27 13:14:46.610 [eventLoopGroupProxy-28-1 @call-handler#221] 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-01-27 13:14:46.613 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:36889/api/school/respect/person?since=2026-01-27T12%3A14%3A46.556513699Z&includeRelated=false 2026-01-27 13:14:46.613 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:36889/api/school/respect/person?since=2026-01-27T12%3A14%3A46.556513699Z&includeRelated=false 2026-01-27 13:14:46.613 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:36889/api/school/respect/person?since=2026-01-27T12%3A14%3A46.556513699Z&includeRelated=false 2026-01-27 13:14:46.614 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:36889/api/school/respect/person?since=2026-01-27T12%3A14%3A46.556513699Z&includeRelated=false. 2026-01-27 13:28:18.474 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:28:18.605 [Test worker] INFO io.ktor.server.Application - Application started in 0.298 seconds. 2026-01-27 13:28:18.712 [DefaultDispatcher-worker-2 @coroutine#2] INFO io.ktor.server.Application - Responding at http://0.0.0.0:41553 2026-01-27 13:28:19.053 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41553/resources/index.json 2026-01-27 13:28:19.053 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:41553/resources/index.json. 2026-01-27 13:28:19.053 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:41553/resources/index.json 2026-01-27 13:28:19.268 [eventLoopGroupProxy-4-1 @call-handler#12] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 13:28:19.324 [eventLoopGroupProxy-4-1 @call-handler#12] INFO io.ktor.server.Application - 200 OK: GET - /resources/index.json in 65ms 2026-01-27 13:28:19.328 [DefaultDispatcher-worker-1 @coroutine#8] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41553/resources/index.json 2026-01-27 13:28:19.330 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41553/resources/index.json 2026-01-27 13:28:19.330 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41553/resources/index.json 2026-01-27 13:28:19.352 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsFeed for http://localhost:41553/resources/index.json. 2026-01-27 13:28:19.424 [DefaultDispatcher-worker-1 @coroutine#20] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41553/resources/index.json 2026-01-27 13:28:19.424 [DefaultDispatcher-worker-1 @coroutine#20] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:41553/resources/index.json. 2026-01-27 13:28:19.424 [DefaultDispatcher-worker-1 @coroutine#20] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:41553/resources/index.json 2026-01-27 13:28:19.440 [eventLoopGroupProxy-4-1 @call-handler#22] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 13:28:19.443 [eventLoopGroupProxy-4-1 @call-handler#22] INFO io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 7ms 2026-01-27 13:28:19.454 [DefaultDispatcher-worker-1 @coroutine#20] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41553/resources/index.json 2026-01-27 13:28:19.454 [DefaultDispatcher-worker-1 @coroutine#20] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41553/resources/index.json 2026-01-27 13:28:19.454 [DefaultDispatcher-worker-1 @coroutine#20] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41553/resources/index.json 2026-01-27 13:28:21.484 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:28:21.485 [Test worker] INFO io.ktor.server.Application - Application started in 0.001 seconds. 2026-01-27 13:28:21.486 [DefaultDispatcher-worker-1 @coroutine#26] INFO io.ktor.server.Application - Responding at http://0.0.0.0:40041 2026-01-27 13:28:21.525 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40041/resources/index.json 2026-01-27 13:28:21.525 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:40041/resources/index.json. 2026-01-27 13:28:21.525 [Test worker @coroutine#30] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40041/resources/index.json 2026-01-27 13:28:21.529 [eventLoopGroupProxy-7-1 @call-handler#35] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 13:28:21.531 [eventLoopGroupProxy-7-1 @call-handler#35] INFO io.ktor.server.Application - 200 OK: GET - /resources/index.json in 2ms 2026-01-27 13:28:21.532 [Test worker @coroutine#30] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40041/resources/index.json 2026-01-27 13:28:21.533 [Test worker @coroutine#30] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40041/resources/index.json 2026-01-27 13:28:21.533 [Test worker @coroutine#30] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40041/resources/index.json 2026-01-27 13:28:21.535 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsFeed for http://localhost:40041/resources/index.json. 2026-01-27 13:28:21.546 [DefaultDispatcher-worker-5 @coroutine#43] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40041/resources/index.json 2026-01-27 13:28:21.546 [DefaultDispatcher-worker-5 @coroutine#43] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:40041/resources/index.json. 2026-01-27 13:28:21.546 [DefaultDispatcher-worker-5 @coroutine#43] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40041/resources/index.json 2026-01-27 13:28:21.549 [eventLoopGroupProxy-7-1 @call-handler#45] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 13:28:21.549 [eventLoopGroupProxy-7-1 @call-handler#45] INFO io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 1ms 2026-01-27 13:28:21.551 [DefaultDispatcher-worker-3 @coroutine#43] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40041/resources/index.json 2026-01-27 13:28:21.551 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40041/resources/index.json 2026-01-27 13:28:21.551 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40041/resources/index.json 2026-01-27 13:28:23.564 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:28:23.565 [Test worker] INFO io.ktor.server.Application - Application started in 0.001 seconds. 2026-01-27 13:28:23.566 [DefaultDispatcher-worker-3 @coroutine#49] INFO io.ktor.server.Application - Responding at http://0.0.0.0:35611 2026-01-27 13:28:23.571 [Test worker @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:35611/resources/lesson001.json 2026-01-27 13:28:23.571 [Test worker @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:35611/resources/lesson001.json. 2026-01-27 13:28:23.571 [Test worker @coroutine#52] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:35611/resources/lesson001.json 2026-01-27 13:28:23.574 [eventLoopGroupProxy-10-1 @call-handler#56] TRACE io.ktor.server.routing.Routing - Trace for [resources, lesson001.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[lesson001.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[lesson001.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 13:28:23.576 [DefaultDispatcher-worker-6 @call-handler#56] INFO io.ktor.server.Application - 200 OK: GET - /resources/lesson001.json in 2ms 2026-01-27 13:28:23.577 [DefaultDispatcher-worker-6 @coroutine#52] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:35611/resources/lesson001.json 2026-01-27 13:28:23.577 [DefaultDispatcher-worker-3 @coroutine#52] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:35611/resources/lesson001.json 2026-01-27 13:28:23.577 [DefaultDispatcher-worker-3 @coroutine#52] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:35611/resources/lesson001.json 2026-01-27 13:28:23.601 [DefaultDispatcher-worker-3 @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsPublication for http://localhost:35611/resources/lesson001.json. 2026-01-27 13:28:26.696 [Test worker @coroutine#61] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:28:26.722 [Test worker @coroutine#61] INFO io.ktor.server.Application - Application started in 0.885 seconds. 2026-01-27 13:28:26.728 [DefaultDispatcher-worker-6 @coroutine#70] INFO io.ktor.server.Application - Responding at http://0.0.0.0:34337 2026-01-27 13:28:26.762 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34337/api/school/respect/schoolpermissiongrant 2026-01-27 13:28:26.762 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:34337/api/school/respect/schoolpermissiongrant. 2026-01-27 13:28:26.762 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:34337/api/school/respect/schoolpermissiongrant 2026-01-27 13:28:26.777 [eventLoopGroupProxy-13-1 @call-handler#74] 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-01-27 13:28:26.817 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34337/api/school/respect/schoolpermissiongrant 2026-01-27 13:28:26.817 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34337/api/school/respect/schoolpermissiongrant 2026-01-27 13:28:26.817 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34337/api/school/respect/schoolpermissiongrant 2026-01-27 13:28:26.821 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:34337/api/school/respect/schoolpermissiongrant. 2026-01-27 13:28:26.900 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34337/api/school/respect/person?includeRelated=false 2026-01-27 13:28:26.900 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:34337/api/school/respect/person?includeRelated=false. 2026-01-27 13:28:26.900 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:34337/api/school/respect/person?includeRelated=false 2026-01-27 13:28:26.903 [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-01-27 13:28:26.946 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34337/api/school/respect/person?includeRelated=false 2026-01-27 13:28:26.946 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34337/api/school/respect/person?includeRelated=false 2026-01-27 13:28:26.946 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34337/api/school/respect/person?includeRelated=false 2026-01-27 13:28:26.948 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:34337/api/school/respect/person?includeRelated=false. 2026-01-27 13:28:26.958 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34337/api/school/respect/person?includeRelated=false 2026-01-27 13:28:26.959 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:34337/api/school/respect/person?includeRelated=false. 2026-01-27 13:28:26.959 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:34337/api/school/respect/person?includeRelated=false 2026-01-27 13:28:26.963 [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-01-27 13:28:26.966 [DefaultDispatcher-worker-6 @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-01-27 13:28:26.967 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34337/api/school/respect/person?includeRelated=false 2026-01-27 13:28:26.968 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34337/api/school/respect/person?includeRelated=false 2026-01-27 13:28:26.968 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34337/api/school/respect/person?includeRelated=false 2026-01-27 13:28:29.074 [Test worker @coroutine#90] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:28:29.075 [Test worker @coroutine#90] INFO io.ktor.server.Application - Application started in 0.03 seconds. 2026-01-27 13:28:29.077 [DefaultDispatcher-worker-3 @coroutine#99] INFO io.ktor.server.Application - Responding at http://0.0.0.0:41013 2026-01-27 13:28:29.078 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41013/api/school/respect/schoolpermissiongrant 2026-01-27 13:28:29.078 [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:41013/api/school/respect/schoolpermissiongrant. 2026-01-27 13:28:29.078 [Test worker @coroutine#90] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:41013/api/school/respect/schoolpermissiongrant 2026-01-27 13:28:29.081 [eventLoopGroupProxy-16-1 @call-handler#103] 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-01-27 13:28:29.083 [Test worker @coroutine#90] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41013/api/school/respect/schoolpermissiongrant 2026-01-27 13:28:29.083 [Test worker @coroutine#90] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41013/api/school/respect/schoolpermissiongrant 2026-01-27 13:28:29.083 [Test worker @coroutine#90] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41013/api/school/respect/schoolpermissiongrant 2026-01-27 13:28:29.083 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:41013/api/school/respect/schoolpermissiongrant. 2026-01-27 13:28:29.120 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:41013/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 13:28:29.120 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:41013/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100. 2026-01-27 13:28:29.121 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:41013/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 13:28:29.122 [eventLoopGroupProxy-16-1 @call-handler#113] 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-01-27 13:28:29.126 [DefaultDispatcher-worker-3 @coroutine#109] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:41013/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 13:28:29.126 [DefaultDispatcher-worker-3 @coroutine#109] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:41013/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 13:28:29.126 [DefaultDispatcher-worker-3 @coroutine#109] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:41013/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 13:28:29.128 [DefaultDispatcher-worker-3 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:41013/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100. 2026-01-27 13:28:31.290 [Test worker @coroutine#118] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:28:31.291 [Test worker @coroutine#118] INFO io.ktor.server.Application - Application started in 0.036 seconds. 2026-01-27 13:28:31.300 [DefaultDispatcher-worker-3 @coroutine#127] INFO io.ktor.server.Application - Responding at http://0.0.0.0:39811 2026-01-27 13:28:31.306 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39811/api/school/respect/person?includeRelated=false 2026-01-27 13:28:31.306 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39811/api/school/respect/person?includeRelated=false. 2026-01-27 13:28:31.306 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39811/api/school/respect/person?includeRelated=false 2026-01-27 13:28:31.315 [eventLoopGroupProxy-19-1 @call-handler#132] 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-01-27 13:28:31.324 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39811/api/school/respect/person?includeRelated=false 2026-01-27 13:28:31.324 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39811/api/school/respect/person?includeRelated=false 2026-01-27 13:28:31.324 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39811/api/school/respect/person?includeRelated=false 2026-01-27 13:28:31.326 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39811/api/school/respect/person?includeRelated=false. 2026-01-27 13:28:31.419 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39811/api/school/respect/person?since=2026-01-27T12%3A28%3A31.317891314Z&includeRelated=false 2026-01-27 13:28:31.422 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39811/api/school/respect/person?since=2026-01-27T12%3A28%3A31.317891314Z&includeRelated=false. 2026-01-27 13:28:31.422 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39811/api/school/respect/person?since=2026-01-27T12%3A28%3A31.317891314Z&includeRelated=false 2026-01-27 13:28:31.431 [eventLoopGroupProxy-19-1 @call-handler#138] 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-01-27 13:28:31.444 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39811/api/school/respect/person?since=2026-01-27T12%3A28%3A31.317891314Z&includeRelated=false 2026-01-27 13:28:31.444 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39811/api/school/respect/person?since=2026-01-27T12%3A28%3A31.317891314Z&includeRelated=false 2026-01-27 13:28:31.444 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39811/api/school/respect/person?since=2026-01-27T12%3A28%3A31.317891314Z&includeRelated=false 2026-01-27 13:28:31.448 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39811/api/school/respect/person?since=2026-01-27T12%3A28%3A31.317891314Z&includeRelated=false. 2026-01-27 13:28:33.638 [Test worker @coroutine#144] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:28:33.639 [Test worker @coroutine#144] INFO io.ktor.server.Application - Application started in 0.037 seconds. 2026-01-27 13:28:33.643 [DefaultDispatcher-worker-1 @coroutine#153] INFO io.ktor.server.Application - Responding at http://0.0.0.0:43535 2026-01-27 13:28:33.719 [DefaultDispatcher-worker-4 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:43535/api/school/respect/person 2026-01-27 13:28:33.720 [DefaultDispatcher-worker-4 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Converted request body using io.ktor.serialization.kotlinx.KotlinxSerializationConverter@7a589594 for http://localhost:43535/api/school/respect/person 2026-01-27 13:28:33.720 [DefaultDispatcher-worker-4 @coroutine#151] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:43535/api/school/respect/person 2026-01-27 13:28:33.720 [DefaultDispatcher-worker-4 @coroutine#151] TRACE i.k.c.plugins.defaultTransformers - Transformed with default transformers request body for http://localhost:43535/api/school/respect/person from class io.ktor.http.content.TextContent 2026-01-27 13:28:33.740 [eventLoopGroupProxy-22-1 @call-handler#163] 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-01-27 13:28:33.748 [eventLoopGroupProxy-22-1 @call-handler#163] 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-01-27 13:28:33.750 [DefaultDispatcher-worker-4 @call-handler#163] 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-01-27 13:28:33.751 [DefaultDispatcher-worker-5 @coroutine#151] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:43535/api/school/respect/person 2026-01-27 13:28:33.751 [DefaultDispatcher-worker-5 @coroutine#151] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:43535/api/school/respect/person 2026-01-27 13:28:33.751 [DefaultDispatcher-worker-5 @coroutine#151] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:43535/api/school/respect/person 2026-01-27 13:28:35.940 [Test worker @coroutine#168] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:28:35.941 [Test worker @coroutine#168] INFO io.ktor.server.Application - Application started in 0.04 seconds. 2026-01-27 13:28:35.942 [DefaultDispatcher-worker-2 @coroutine#177] INFO io.ktor.server.Application - Responding at http://0.0.0.0:33117 2026-01-27 13:28:35.943 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33117/api/school/respect/schoolpermissiongrant 2026-01-27 13:28:35.943 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:33117/api/school/respect/schoolpermissiongrant. 2026-01-27 13:28:35.943 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33117/api/school/respect/schoolpermissiongrant 2026-01-27 13:28:35.946 [eventLoopGroupProxy-25-1 @call-handler#181] 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-01-27 13:28:35.948 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33117/api/school/respect/schoolpermissiongrant 2026-01-27 13:28:35.948 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33117/api/school/respect/schoolpermissiongrant 2026-01-27 13:28:35.948 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33117/api/school/respect/schoolpermissiongrant 2026-01-27 13:28:35.949 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:33117/api/school/respect/schoolpermissiongrant. 2026-01-27 13:28:36.001 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33117/api/school/respect/person?includeRelated=false 2026-01-27 13:28:36.001 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:33117/api/school/respect/person?includeRelated=false. 2026-01-27 13:28:36.001 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33117/api/school/respect/person?includeRelated=false 2026-01-27 13:28:36.005 [eventLoopGroupProxy-25-1 @call-handler#187] 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-01-27 13:28:36.017 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33117/api/school/respect/person?includeRelated=false 2026-01-27 13:28:36.017 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33117/api/school/respect/person?includeRelated=false 2026-01-27 13:28:36.017 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33117/api/school/respect/person?includeRelated=false 2026-01-27 13:28:36.018 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:33117/api/school/respect/person?includeRelated=false. 2026-01-27 13:28:38.025 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:33117/api/school/respect/person?includeRelated=false 2026-01-27 13:28:38.025 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:33117/api/school/respect/person?includeRelated=false. 2026-01-27 13:28:38.025 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:33117/api/school/respect/person?includeRelated=false 2026-01-27 13:28:38.027 [eventLoopGroupProxy-25-1 @call-handler#194] 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-01-27 13:28:38.041 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:33117/api/school/respect/person?includeRelated=false 2026-01-27 13:28:38.042 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:33117/api/school/respect/person?includeRelated=false 2026-01-27 13:28:38.042 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:33117/api/school/respect/person?includeRelated=false 2026-01-27 13:28:38.043 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:33117/api/school/respect/person?includeRelated=false. 2026-01-27 13:28:40.172 [Test worker @coroutine#200] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 13:28:40.174 [Test worker @coroutine#200] INFO io.ktor.server.Application - Application started in 0.05 seconds. 2026-01-27 13:28:40.179 [DefaultDispatcher-worker-5 @coroutine#209] INFO io.ktor.server.Application - Responding at http://0.0.0.0:37819 2026-01-27 13:28:40.185 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:37819/api/school/respect/person?includeRelated=false 2026-01-27 13:28:40.185 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:37819/api/school/respect/person?includeRelated=false. 2026-01-27 13:28:40.185 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:37819/api/school/respect/person?includeRelated=false 2026-01-27 13:28:40.189 [eventLoopGroupProxy-28-1 @call-handler#214] 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-01-27 13:28:40.195 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:37819/api/school/respect/person?includeRelated=false 2026-01-27 13:28:40.195 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:37819/api/school/respect/person?includeRelated=false 2026-01-27 13:28:40.195 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:37819/api/school/respect/person?includeRelated=false 2026-01-27 13:28:40.196 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:37819/api/school/respect/person?includeRelated=false. 2026-01-27 13:28:40.252 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:37819/api/school/respect/person?since=2026-01-27T12%3A28%3A40.190469352Z&includeRelated=false 2026-01-27 13:28:40.252 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:37819/api/school/respect/person?since=2026-01-27T12%3A28%3A40.190469352Z&includeRelated=false. 2026-01-27 13:28:40.252 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:37819/api/school/respect/person?since=2026-01-27T12%3A28%3A40.190469352Z&includeRelated=false 2026-01-27 13:28:40.254 [eventLoopGroupProxy-28-1 @call-handler#221] 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-01-27 13:28:40.258 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:37819/api/school/respect/person?since=2026-01-27T12%3A28%3A40.190469352Z&includeRelated=false 2026-01-27 13:28:40.258 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:37819/api/school/respect/person?since=2026-01-27T12%3A28%3A40.190469352Z&includeRelated=false 2026-01-27 13:28:40.258 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:37819/api/school/respect/person?since=2026-01-27T12%3A28%3A40.190469352Z&includeRelated=false 2026-01-27 13:28:40.259 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:37819/api/school/respect/person?since=2026-01-27T12%3A28%3A40.190469352Z&includeRelated=false. 2026-01-27 17:47:01.942 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:47:02.075 [Test worker] INFO io.ktor.server.Application - Application started in 0.283 seconds. 2026-01-27 17:47:02.196 [DefaultDispatcher-worker-1 @coroutine#2] INFO io.ktor.server.Application - Responding at http://0.0.0.0:37123 2026-01-27 17:47:02.534 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:37123/resources/index.json 2026-01-27 17:47:02.535 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:37123/resources/index.json. 2026-01-27 17:47:02.535 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:37123/resources/index.json 2026-01-27 17:47:02.754 [eventLoopGroupProxy-4-1 @call-handler#12] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 17:47:02.889 [eventLoopGroupProxy-4-1 @call-handler#12] INFO io.ktor.server.Application - 200 OK: GET - /resources/index.json in 141ms 2026-01-27 17:47:02.906 [DefaultDispatcher-worker-1 @coroutine#8] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:37123/resources/index.json 2026-01-27 17:47:02.909 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:37123/resources/index.json 2026-01-27 17:47:02.910 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:37123/resources/index.json 2026-01-27 17:47:02.948 [DefaultDispatcher-worker-1 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsFeed for http://localhost:37123/resources/index.json. 2026-01-27 17:47:03.025 [DefaultDispatcher-worker-2 @coroutine#20] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:37123/resources/index.json 2026-01-27 17:47:03.025 [DefaultDispatcher-worker-2 @coroutine#20] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:37123/resources/index.json. 2026-01-27 17:47:03.025 [DefaultDispatcher-worker-2 @coroutine#20] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:37123/resources/index.json 2026-01-27 17:47:03.038 [eventLoopGroupProxy-4-1 @call-handler#22] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 17:47:03.047 [eventLoopGroupProxy-4-1 @call-handler#22] INFO io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 9ms 2026-01-27 17:47:03.049 [DefaultDispatcher-worker-3 @coroutine#20] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:37123/resources/index.json 2026-01-27 17:47:03.049 [DefaultDispatcher-worker-3 @coroutine#20] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:37123/resources/index.json 2026-01-27 17:47:03.049 [DefaultDispatcher-worker-3 @coroutine#20] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:37123/resources/index.json 2026-01-27 17:47:05.071 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:47:05.072 [Test worker] INFO io.ktor.server.Application - Application started in 0.001 seconds. 2026-01-27 17:47:05.076 [DefaultDispatcher-worker-3 @coroutine#26] INFO io.ktor.server.Application - Responding at http://0.0.0.0:39677 2026-01-27 17:47:05.131 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39677/resources/index.json 2026-01-27 17:47:05.131 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39677/resources/index.json. 2026-01-27 17:47:05.131 [Test worker @coroutine#30] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39677/resources/index.json 2026-01-27 17:47:05.135 [eventLoopGroupProxy-7-1 @call-handler#35] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 17:47:05.136 [eventLoopGroupProxy-7-1 @call-handler#35] INFO io.ktor.server.Application - 200 OK: GET - /resources/index.json in 1ms 2026-01-27 17:47:05.137 [Test worker @coroutine#30] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39677/resources/index.json 2026-01-27 17:47:05.138 [Test worker @coroutine#30] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39677/resources/index.json 2026-01-27 17:47:05.138 [Test worker @coroutine#30] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39677/resources/index.json 2026-01-27 17:47:05.138 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsFeed for http://localhost:39677/resources/index.json. 2026-01-27 17:47:05.144 [DefaultDispatcher-worker-1 @coroutine#43] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39677/resources/index.json 2026-01-27 17:47:05.145 [DefaultDispatcher-worker-1 @coroutine#43] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39677/resources/index.json. 2026-01-27 17:47:05.145 [DefaultDispatcher-worker-1 @coroutine#43] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39677/resources/index.json 2026-01-27 17:47:05.146 [eventLoopGroupProxy-7-1 @call-handler#45] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 17:47:05.147 [eventLoopGroupProxy-7-1 @call-handler#45] INFO io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 1ms 2026-01-27 17:47:05.148 [DefaultDispatcher-worker-1 @coroutine#43] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39677/resources/index.json 2026-01-27 17:47:05.148 [DefaultDispatcher-worker-1 @coroutine#43] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39677/resources/index.json 2026-01-27 17:47:05.148 [DefaultDispatcher-worker-1 @coroutine#43] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39677/resources/index.json 2026-01-27 17:47:07.154 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:47:07.155 [Test worker] INFO io.ktor.server.Application - Application started in 0.001 seconds. 2026-01-27 17:47:07.156 [DefaultDispatcher-worker-1 @coroutine#49] INFO io.ktor.server.Application - Responding at http://0.0.0.0:35525 2026-01-27 17:47:07.159 [Test worker @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:35525/resources/lesson001.json 2026-01-27 17:47:07.159 [Test worker @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:35525/resources/lesson001.json. 2026-01-27 17:47:07.159 [Test worker @coroutine#52] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:35525/resources/lesson001.json 2026-01-27 17:47:07.161 [eventLoopGroupProxy-10-1 @call-handler#56] TRACE io.ktor.server.routing.Routing - Trace for [resources, lesson001.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[lesson001.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[lesson001.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 17:47:07.162 [eventLoopGroupProxy-10-1 @call-handler#56] INFO io.ktor.server.Application - 200 OK: GET - /resources/lesson001.json in 1ms 2026-01-27 17:47:07.163 [DefaultDispatcher-worker-5 @coroutine#52] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:35525/resources/lesson001.json 2026-01-27 17:47:07.163 [DefaultDispatcher-worker-3 @coroutine#52] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:35525/resources/lesson001.json 2026-01-27 17:47:07.163 [DefaultDispatcher-worker-3 @coroutine#52] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:35525/resources/lesson001.json 2026-01-27 17:47:07.181 [DefaultDispatcher-worker-3 @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsPublication for http://localhost:35525/resources/lesson001.json. 2026-01-27 17:47:09.957 [Test worker @coroutine#61] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:47:09.970 [Test worker @coroutine#61] INFO io.ktor.server.Application - Application started in 0.626 seconds. 2026-01-27 17:47:09.973 [DefaultDispatcher-worker-5 @coroutine#70] INFO io.ktor.server.Application - Responding at http://0.0.0.0:39497 2026-01-27 17:47:09.986 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39497/api/school/respect/schoolpermissiongrant 2026-01-27 17:47:09.986 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39497/api/school/respect/schoolpermissiongrant. 2026-01-27 17:47:09.986 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39497/api/school/respect/schoolpermissiongrant 2026-01-27 17:47:09.997 [eventLoopGroupProxy-13-1 @call-handler#74] 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-01-27 17:47:10.017 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39497/api/school/respect/schoolpermissiongrant 2026-01-27 17:47:10.017 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39497/api/school/respect/schoolpermissiongrant 2026-01-27 17:47:10.017 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39497/api/school/respect/schoolpermissiongrant 2026-01-27 17:47:10.020 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39497/api/school/respect/schoolpermissiongrant. 2026-01-27 17:47:10.094 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39497/api/school/respect/person?includeRelated=false 2026-01-27 17:47:10.094 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39497/api/school/respect/person?includeRelated=false. 2026-01-27 17:47:10.094 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39497/api/school/respect/person?includeRelated=false 2026-01-27 17:47:10.097 [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-01-27 17:47:10.121 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39497/api/school/respect/person?includeRelated=false 2026-01-27 17:47:10.121 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39497/api/school/respect/person?includeRelated=false 2026-01-27 17:47:10.121 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39497/api/school/respect/person?includeRelated=false 2026-01-27 17:47:10.123 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39497/api/school/respect/person?includeRelated=false. 2026-01-27 17:47:10.130 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39497/api/school/respect/person?includeRelated=false 2026-01-27 17:47:10.130 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39497/api/school/respect/person?includeRelated=false. 2026-01-27 17:47:10.130 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39497/api/school/respect/person?includeRelated=false 2026-01-27 17:47:10.133 [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-01-27 17:47:10.136 [DefaultDispatcher-worker-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-01-27 17:47:10.137 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39497/api/school/respect/person?includeRelated=false 2026-01-27 17:47:10.137 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39497/api/school/respect/person?includeRelated=false 2026-01-27 17:47:10.137 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39497/api/school/respect/person?includeRelated=false 2026-01-27 17:47:12.261 [Test worker @coroutine#90] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:47:12.262 [Test worker @coroutine#90] INFO io.ktor.server.Application - Application started in 0.038 seconds. 2026-01-27 17:47:12.264 [DefaultDispatcher-worker-5 @coroutine#99] INFO io.ktor.server.Application - Responding at http://0.0.0.0:46691 2026-01-27 17:47:12.265 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46691/api/school/respect/schoolpermissiongrant 2026-01-27 17:47:12.265 [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:46691/api/school/respect/schoolpermissiongrant. 2026-01-27 17:47:12.265 [Test worker @coroutine#90] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:46691/api/school/respect/schoolpermissiongrant 2026-01-27 17:47:12.270 [eventLoopGroupProxy-16-1 @call-handler#103] 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-01-27 17:47:12.271 [Test worker @coroutine#90] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46691/api/school/respect/schoolpermissiongrant 2026-01-27 17:47:12.271 [Test worker @coroutine#90] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46691/api/school/respect/schoolpermissiongrant 2026-01-27 17:47:12.272 [Test worker @coroutine#90] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46691/api/school/respect/schoolpermissiongrant 2026-01-27 17:47:12.272 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:46691/api/school/respect/schoolpermissiongrant. 2026-01-27 17:47:12.309 [DefaultDispatcher-worker-1 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46691/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 17:47:12.309 [DefaultDispatcher-worker-1 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:46691/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100. 2026-01-27 17:47:12.309 [DefaultDispatcher-worker-1 @coroutine#109] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:46691/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 17:47:12.311 [eventLoopGroupProxy-16-1 @call-handler#113] 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-01-27 17:47:12.313 [DefaultDispatcher-worker-5 @coroutine#109] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46691/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 17:47:12.313 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46691/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 17:47:12.314 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46691/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 17:47:12.315 [DefaultDispatcher-worker-5 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:46691/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100. 2026-01-27 17:47:14.468 [Test worker @coroutine#118] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:47:14.469 [Test worker @coroutine#118] INFO io.ktor.server.Application - Application started in 0.038 seconds. 2026-01-27 17:47:14.475 [DefaultDispatcher-worker-1 @coroutine#127] INFO io.ktor.server.Application - Responding at http://0.0.0.0:45747 2026-01-27 17:47:14.482 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45747/api/school/respect/person?includeRelated=false 2026-01-27 17:47:14.483 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:45747/api/school/respect/person?includeRelated=false. 2026-01-27 17:47:14.483 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45747/api/school/respect/person?includeRelated=false 2026-01-27 17:47:14.509 [eventLoopGroupProxy-19-1 @call-handler#132] 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-01-27 17:47:14.523 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45747/api/school/respect/person?includeRelated=false 2026-01-27 17:47:14.523 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45747/api/school/respect/person?includeRelated=false 2026-01-27 17:47:14.523 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45747/api/school/respect/person?includeRelated=false 2026-01-27 17:47:14.527 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:45747/api/school/respect/person?includeRelated=false. 2026-01-27 17:47:14.617 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45747/api/school/respect/person?since=2026-01-27T16%3A47%3A14.510577943Z&includeRelated=false 2026-01-27 17:47:14.617 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:45747/api/school/respect/person?since=2026-01-27T16%3A47%3A14.510577943Z&includeRelated=false. 2026-01-27 17:47:14.617 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45747/api/school/respect/person?since=2026-01-27T16%3A47%3A14.510577943Z&includeRelated=false 2026-01-27 17:47:14.631 [eventLoopGroupProxy-19-1 @call-handler#138] 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-01-27 17:47:14.640 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45747/api/school/respect/person?since=2026-01-27T16%3A47%3A14.510577943Z&includeRelated=false 2026-01-27 17:47:14.641 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45747/api/school/respect/person?since=2026-01-27T16%3A47%3A14.510577943Z&includeRelated=false 2026-01-27 17:47:14.641 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45747/api/school/respect/person?since=2026-01-27T16%3A47%3A14.510577943Z&includeRelated=false 2026-01-27 17:47:14.643 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:45747/api/school/respect/person?since=2026-01-27T16%3A47%3A14.510577943Z&includeRelated=false. 2026-01-27 17:47:16.839 [Test worker @coroutine#144] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:47:16.840 [Test worker @coroutine#144] INFO io.ktor.server.Application - Application started in 0.037 seconds. 2026-01-27 17:47:16.848 [DefaultDispatcher-worker-1 @coroutine#153] INFO io.ktor.server.Application - Responding at http://0.0.0.0:46247 2026-01-27 17:47:16.908 [DefaultDispatcher-worker-4 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:46247/api/school/respect/person 2026-01-27 17:47:16.909 [DefaultDispatcher-worker-4 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Converted request body using io.ktor.serialization.kotlinx.KotlinxSerializationConverter@4e347e16 for http://localhost:46247/api/school/respect/person 2026-01-27 17:47:16.909 [DefaultDispatcher-worker-4 @coroutine#151] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:46247/api/school/respect/person 2026-01-27 17:47:16.909 [DefaultDispatcher-worker-4 @coroutine#151] TRACE i.k.c.plugins.defaultTransformers - Transformed with default transformers request body for http://localhost:46247/api/school/respect/person from class io.ktor.http.content.TextContent 2026-01-27 17:47:16.930 [eventLoopGroupProxy-22-1 @call-handler#163] 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-01-27 17:47:16.950 [eventLoopGroupProxy-22-1 @call-handler#163] 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-01-27 17:47:16.955 [DefaultDispatcher-worker-1 @call-handler#163] 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-01-27 17:47:16.958 [DefaultDispatcher-worker-3 @coroutine#151] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:46247/api/school/respect/person 2026-01-27 17:47:16.958 [DefaultDispatcher-worker-3 @coroutine#151] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:46247/api/school/respect/person 2026-01-27 17:47:16.958 [DefaultDispatcher-worker-3 @coroutine#151] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:46247/api/school/respect/person 2026-01-27 17:47:19.154 [Test worker @coroutine#168] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:47:19.155 [Test worker @coroutine#168] INFO io.ktor.server.Application - Application started in 0.038 seconds. 2026-01-27 17:47:19.156 [DefaultDispatcher-worker-15 @coroutine#177] INFO io.ktor.server.Application - Responding at http://0.0.0.0:42115 2026-01-27 17:47:19.158 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42115/api/school/respect/schoolpermissiongrant 2026-01-27 17:47:19.158 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:42115/api/school/respect/schoolpermissiongrant. 2026-01-27 17:47:19.158 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:42115/api/school/respect/schoolpermissiongrant 2026-01-27 17:47:19.160 [eventLoopGroupProxy-25-1 @call-handler#181] 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-01-27 17:47:19.163 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42115/api/school/respect/schoolpermissiongrant 2026-01-27 17:47:19.163 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42115/api/school/respect/schoolpermissiongrant 2026-01-27 17:47:19.164 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42115/api/school/respect/schoolpermissiongrant 2026-01-27 17:47:19.164 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42115/api/school/respect/schoolpermissiongrant. 2026-01-27 17:47:19.219 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42115/api/school/respect/person?includeRelated=false 2026-01-27 17:47:19.219 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:42115/api/school/respect/person?includeRelated=false. 2026-01-27 17:47:19.219 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:42115/api/school/respect/person?includeRelated=false 2026-01-27 17:47:19.226 [eventLoopGroupProxy-25-1 @call-handler#187] 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-01-27 17:47:19.235 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42115/api/school/respect/person?includeRelated=false 2026-01-27 17:47:19.235 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42115/api/school/respect/person?includeRelated=false 2026-01-27 17:47:19.235 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42115/api/school/respect/person?includeRelated=false 2026-01-27 17:47:19.236 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42115/api/school/respect/person?includeRelated=false. 2026-01-27 17:47:21.242 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42115/api/school/respect/person?includeRelated=false 2026-01-27 17:47:21.242 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:42115/api/school/respect/person?includeRelated=false. 2026-01-27 17:47:21.242 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:42115/api/school/respect/person?includeRelated=false 2026-01-27 17:47:21.244 [eventLoopGroupProxy-25-1 @call-handler#194] 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-01-27 17:47:21.248 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42115/api/school/respect/person?includeRelated=false 2026-01-27 17:47:21.248 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42115/api/school/respect/person?includeRelated=false 2026-01-27 17:47:21.248 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42115/api/school/respect/person?includeRelated=false 2026-01-27 17:47:21.249 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:42115/api/school/respect/person?includeRelated=false. 2026-01-27 17:47:23.383 [Test worker @coroutine#200] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:47:23.384 [Test worker @coroutine#200] INFO io.ktor.server.Application - Application started in 0.046 seconds. 2026-01-27 17:47:23.385 [DefaultDispatcher-worker-15 @coroutine#209] INFO io.ktor.server.Application - Responding at http://0.0.0.0:37569 2026-01-27 17:47:23.387 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:37569/api/school/respect/person?includeRelated=false 2026-01-27 17:47:23.388 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:37569/api/school/respect/person?includeRelated=false. 2026-01-27 17:47:23.388 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:37569/api/school/respect/person?includeRelated=false 2026-01-27 17:47:23.390 [eventLoopGroupProxy-28-1 @call-handler#214] 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-01-27 17:47:23.393 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:37569/api/school/respect/person?includeRelated=false 2026-01-27 17:47:23.394 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:37569/api/school/respect/person?includeRelated=false 2026-01-27 17:47:23.394 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:37569/api/school/respect/person?includeRelated=false 2026-01-27 17:47:23.395 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:37569/api/school/respect/person?includeRelated=false. 2026-01-27 17:47:23.437 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:37569/api/school/respect/person?since=2026-01-27T16%3A47%3A23.390506562Z&includeRelated=false 2026-01-27 17:47:23.437 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:37569/api/school/respect/person?since=2026-01-27T16%3A47%3A23.390506562Z&includeRelated=false. 2026-01-27 17:47:23.437 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:37569/api/school/respect/person?since=2026-01-27T16%3A47%3A23.390506562Z&includeRelated=false 2026-01-27 17:47:23.439 [eventLoopGroupProxy-28-1 @call-handler#221] 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-01-27 17:47:23.442 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:37569/api/school/respect/person?since=2026-01-27T16%3A47%3A23.390506562Z&includeRelated=false 2026-01-27 17:47:23.442 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:37569/api/school/respect/person?since=2026-01-27T16%3A47%3A23.390506562Z&includeRelated=false 2026-01-27 17:47:23.442 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:37569/api/school/respect/person?since=2026-01-27T16%3A47%3A23.390506562Z&includeRelated=false 2026-01-27 17:47:23.443 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:37569/api/school/respect/person?since=2026-01-27T16%3A47%3A23.390506562Z&includeRelated=false. 2026-01-27 17:50:31.029 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:50:31.134 [Test worker] INFO io.ktor.server.Application - Application started in 0.29 seconds. 2026-01-27 17:50:31.231 [DefaultDispatcher-worker-1 @coroutine#2] INFO io.ktor.server.Application - Responding at http://0.0.0.0:40289 2026-01-27 17:50:31.497 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40289/resources/index.json 2026-01-27 17:50:31.497 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:40289/resources/index.json. 2026-01-27 17:50:31.497 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40289/resources/index.json 2026-01-27 17:50:31.643 [eventLoopGroupProxy-4-1 @call-handler#12] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 17:50:31.682 [eventLoopGroupProxy-4-1 @call-handler#12] INFO io.ktor.server.Application - 200 OK: GET - /resources/index.json in 44ms 2026-01-27 17:50:31.689 [DefaultDispatcher-worker-2 @coroutine#8] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40289/resources/index.json 2026-01-27 17:50:31.691 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40289/resources/index.json 2026-01-27 17:50:31.691 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40289/resources/index.json 2026-01-27 17:50:31.718 [DefaultDispatcher-worker-2 @coroutine#8] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsFeed for http://localhost:40289/resources/index.json. 2026-01-27 17:50:31.759 [DefaultDispatcher-worker-3 @coroutine#20] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40289/resources/index.json 2026-01-27 17:50:31.759 [DefaultDispatcher-worker-3 @coroutine#20] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:40289/resources/index.json. 2026-01-27 17:50:31.759 [DefaultDispatcher-worker-3 @coroutine#20] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40289/resources/index.json 2026-01-27 17:50:31.785 [eventLoopGroupProxy-4-1 @call-handler#22] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 17:50:31.796 [eventLoopGroupProxy-4-1 @call-handler#22] INFO io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 11ms 2026-01-27 17:50:31.797 [DefaultDispatcher-worker-5 @coroutine#20] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40289/resources/index.json 2026-01-27 17:50:31.798 [DefaultDispatcher-worker-5 @coroutine#20] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40289/resources/index.json 2026-01-27 17:50:31.798 [DefaultDispatcher-worker-5 @coroutine#20] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40289/resources/index.json 2026-01-27 17:50:33.832 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:50:33.833 [Test worker] INFO io.ktor.server.Application - Application started in 0.001 seconds. 2026-01-27 17:50:33.838 [DefaultDispatcher-worker-3 @coroutine#26] INFO io.ktor.server.Application - Responding at http://0.0.0.0:40637 2026-01-27 17:50:33.915 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40637/resources/index.json 2026-01-27 17:50:33.916 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:40637/resources/index.json. 2026-01-27 17:50:33.916 [Test worker @coroutine#30] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40637/resources/index.json 2026-01-27 17:50:33.920 [eventLoopGroupProxy-7-1 @call-handler#35] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 17:50:33.922 [DefaultDispatcher-worker-3 @call-handler#35] INFO io.ktor.server.Application - 200 OK: GET - /resources/index.json in 2ms 2026-01-27 17:50:33.923 [Test worker @coroutine#30] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40637/resources/index.json 2026-01-27 17:50:33.923 [Test worker @coroutine#30] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40637/resources/index.json 2026-01-27 17:50:33.923 [Test worker @coroutine#30] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40637/resources/index.json 2026-01-27 17:50:33.924 [Test worker @coroutine#30] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsFeed for http://localhost:40637/resources/index.json. 2026-01-27 17:50:33.932 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:40637/resources/index.json 2026-01-27 17:50:33.932 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:40637/resources/index.json. 2026-01-27 17:50:33.932 [DefaultDispatcher-worker-3 @coroutine#43] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:40637/resources/index.json 2026-01-27 17:50:33.935 [eventLoopGroupProxy-7-1 @call-handler#45] TRACE io.ktor.server.routing.Routing - Trace for [resources, index.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[index.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 17:50:33.935 [eventLoopGroupProxy-7-1 @call-handler#45] INFO io.ktor.server.Application - 304 Not Modified: GET - /resources/index.json in 1ms 2026-01-27 17:50:33.937 [DefaultDispatcher-worker-5 @coroutine#43] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:40637/resources/index.json 2026-01-27 17:50:33.937 [DefaultDispatcher-worker-5 @coroutine#43] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:40637/resources/index.json 2026-01-27 17:50:33.937 [DefaultDispatcher-worker-5 @coroutine#43] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:40637/resources/index.json 2026-01-27 17:50:35.946 [Test worker] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:50:35.947 [Test worker] INFO io.ktor.server.Application - Application started in 0.001 seconds. 2026-01-27 17:50:35.948 [DefaultDispatcher-worker-5 @coroutine#49] INFO io.ktor.server.Application - Responding at http://0.0.0.0:45821 2026-01-27 17:50:35.952 [Test worker @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:45821/resources/lesson001.json 2026-01-27 17:50:35.952 [Test worker @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:45821/resources/lesson001.json. 2026-01-27 17:50:35.952 [Test worker @coroutine#52] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:45821/resources/lesson001.json 2026-01-27 17:50:35.955 [eventLoopGroupProxy-10-1 @call-handler#56] TRACE io.ktor.server.routing.Routing - Trace for [resources, lesson001.json] /, segment:0 -> SUCCESS @ / /(staticContent), segment:0 -> SUCCESS @ /(staticContent) /(staticContent)/resources, segment:1 -> SUCCESS @ /(staticContent)/resources /(staticContent)/resources/{...}, segment:2 -> SUCCESS; Parameters [static-content-path-parameter=[lesson001.json]] @ /(staticContent)/resources/{...} /(staticContent)/resources/{...}/(method:GET), segment:2 -> SUCCESS @ /(staticContent)/resources/{...}/(method:GET) Matched routes: "" -> "(staticContent)" -> "resources" -> "{...}" -> "(method:GET)" Routing resolve result: SUCCESS; Parameters [static-content-path-parameter=[lesson001.json]] @ /(staticContent)/resources/{...}/(method:GET) 2026-01-27 17:50:35.956 [DefaultDispatcher-worker-3 @call-handler#56] INFO io.ktor.server.Application - 200 OK: GET - /resources/lesson001.json in 1ms 2026-01-27 17:50:35.957 [DefaultDispatcher-worker-3 @coroutine#52] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:45821/resources/lesson001.json 2026-01-27 17:50:35.957 [DefaultDispatcher-worker-5 @coroutine#52] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:45821/resources/lesson001.json 2026-01-27 17:50:35.957 [DefaultDispatcher-worker-5 @coroutine#52] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:45821/resources/lesson001.json 2026-01-27 17:50:35.978 [DefaultDispatcher-worker-5 @coroutine#52] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class world.respect.lib.opds.model.OpdsPublication for http://localhost:45821/resources/lesson001.json. 2026-01-27 17:50:39.312 [Test worker @coroutine#61] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:50:39.338 [Test worker @coroutine#61] INFO io.ktor.server.Application - Application started in 1.19 seconds. 2026-01-27 17:50:39.350 [DefaultDispatcher-worker-5 @coroutine#70] INFO io.ktor.server.Application - Responding at http://0.0.0.0:34683 2026-01-27 17:50:39.363 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34683/api/school/respect/schoolpermissiongrant 2026-01-27 17:50:39.363 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:34683/api/school/respect/schoolpermissiongrant. 2026-01-27 17:50:39.363 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:34683/api/school/respect/schoolpermissiongrant 2026-01-27 17:50:39.373 [eventLoopGroupProxy-13-1 @call-handler#74] 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-01-27 17:50:39.404 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34683/api/school/respect/schoolpermissiongrant 2026-01-27 17:50:39.405 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34683/api/school/respect/schoolpermissiongrant 2026-01-27 17:50:39.405 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34683/api/school/respect/schoolpermissiongrant 2026-01-27 17:50:39.415 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:34683/api/school/respect/schoolpermissiongrant. 2026-01-27 17:50:39.604 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34683/api/school/respect/person?includeRelated=false 2026-01-27 17:50:39.605 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:34683/api/school/respect/person?includeRelated=false. 2026-01-27 17:50:39.606 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:34683/api/school/respect/person?includeRelated=false 2026-01-27 17:50:39.619 [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-01-27 17:50:39.661 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34683/api/school/respect/person?includeRelated=false 2026-01-27 17:50:39.664 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34683/api/school/respect/person?includeRelated=false 2026-01-27 17:50:39.665 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34683/api/school/respect/person?includeRelated=false 2026-01-27 17:50:39.668 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:34683/api/school/respect/person?includeRelated=false. 2026-01-27 17:50:39.684 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:34683/api/school/respect/person?includeRelated=false 2026-01-27 17:50:39.685 [Test worker @coroutine#61] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:34683/api/school/respect/person?includeRelated=false. 2026-01-27 17:50:39.685 [Test worker @coroutine#61] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:34683/api/school/respect/person?includeRelated=false 2026-01-27 17:50:39.690 [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-01-27 17:50:39.702 [DefaultDispatcher-worker-6 @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-01-27 17:50:39.706 [Test worker @coroutine#61] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:34683/api/school/respect/person?includeRelated=false 2026-01-27 17:50:39.706 [Test worker @coroutine#61] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:34683/api/school/respect/person?includeRelated=false 2026-01-27 17:50:39.706 [Test worker @coroutine#61] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:34683/api/school/respect/person?includeRelated=false 2026-01-27 17:50:41.849 [Test worker @coroutine#90] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:50:41.850 [Test worker @coroutine#90] INFO io.ktor.server.Application - Application started in 0.036 seconds. 2026-01-27 17:50:41.851 [DefaultDispatcher-worker-1 @coroutine#99] INFO io.ktor.server.Application - Responding at http://0.0.0.0:38799 2026-01-27 17:50:41.852 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38799/api/school/respect/schoolpermissiongrant 2026-01-27 17:50:41.852 [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:38799/api/school/respect/schoolpermissiongrant. 2026-01-27 17:50:41.852 [Test worker @coroutine#90] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38799/api/school/respect/schoolpermissiongrant 2026-01-27 17:50:41.855 [eventLoopGroupProxy-16-1 @call-handler#103] 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-01-27 17:50:41.857 [Test worker @coroutine#90] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38799/api/school/respect/schoolpermissiongrant 2026-01-27 17:50:41.857 [Test worker @coroutine#90] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38799/api/school/respect/schoolpermissiongrant 2026-01-27 17:50:41.857 [Test worker @coroutine#90] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38799/api/school/respect/schoolpermissiongrant 2026-01-27 17:50:41.857 [Test worker @coroutine#90] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:38799/api/school/respect/schoolpermissiongrant. 2026-01-27 17:50:41.895 [DefaultDispatcher-worker-6 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:38799/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 17:50:41.895 [DefaultDispatcher-worker-6 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:38799/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100. 2026-01-27 17:50:41.895 [DefaultDispatcher-worker-6 @coroutine#109] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:38799/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 17:50:41.896 [eventLoopGroupProxy-16-1 @call-handler#113] 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-01-27 17:50:41.901 [DefaultDispatcher-worker-1 @coroutine#109] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:38799/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 17:50:41.901 [DefaultDispatcher-worker-1 @coroutine#109] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:38799/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 17:50:41.901 [DefaultDispatcher-worker-1 @coroutine#109] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:38799/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100 2026-01-27 17:50:41.902 [DefaultDispatcher-worker-1 @coroutine#109] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:38799/api/school/respect/person?includeDeleted=true&includeRelated=false&offset=0&limit=100. 2026-01-27 17:50:44.035 [Test worker @coroutine#118] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:50:44.037 [Test worker @coroutine#118] INFO io.ktor.server.Application - Application started in 0.047 seconds. 2026-01-27 17:50:44.040 [DefaultDispatcher-worker-1 @coroutine#127] INFO io.ktor.server.Application - Responding at http://0.0.0.0:44569 2026-01-27 17:50:44.044 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44569/api/school/respect/person?includeRelated=false 2026-01-27 17:50:44.044 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:44569/api/school/respect/person?includeRelated=false. 2026-01-27 17:50:44.044 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:44569/api/school/respect/person?includeRelated=false 2026-01-27 17:50:44.055 [eventLoopGroupProxy-19-1 @call-handler#132] 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-01-27 17:50:44.068 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44569/api/school/respect/person?includeRelated=false 2026-01-27 17:50:44.069 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44569/api/school/respect/person?includeRelated=false 2026-01-27 17:50:44.069 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44569/api/school/respect/person?includeRelated=false 2026-01-27 17:50:44.072 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:44569/api/school/respect/person?includeRelated=false. 2026-01-27 17:50:44.145 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:44569/api/school/respect/person?since=2026-01-27T16%3A50%3A44.056518111Z&includeRelated=false 2026-01-27 17:50:44.147 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:44569/api/school/respect/person?since=2026-01-27T16%3A50%3A44.056518111Z&includeRelated=false. 2026-01-27 17:50:44.147 [Test worker @coroutine#118] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:44569/api/school/respect/person?since=2026-01-27T16%3A50%3A44.056518111Z&includeRelated=false 2026-01-27 17:50:44.155 [eventLoopGroupProxy-19-1 @call-handler#138] 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-01-27 17:50:44.165 [Test worker @coroutine#118] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:44569/api/school/respect/person?since=2026-01-27T16%3A50%3A44.056518111Z&includeRelated=false 2026-01-27 17:50:44.165 [Test worker @coroutine#118] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:44569/api/school/respect/person?since=2026-01-27T16%3A50%3A44.056518111Z&includeRelated=false 2026-01-27 17:50:44.165 [Test worker @coroutine#118] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:44569/api/school/respect/person?since=2026-01-27T16%3A50%3A44.056518111Z&includeRelated=false 2026-01-27 17:50:44.169 [Test worker @coroutine#118] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:44569/api/school/respect/person?since=2026-01-27T16%3A50%3A44.056518111Z&includeRelated=false. 2026-01-27 17:50:46.358 [Test worker @coroutine#144] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:50:46.366 [Test worker @coroutine#144] INFO io.ktor.server.Application - Application started in 0.045 seconds. 2026-01-27 17:50:46.379 [DefaultDispatcher-worker-5 @coroutine#153] INFO io.ktor.server.Application - Responding at http://0.0.0.0:42921 2026-01-27 17:50:46.448 [DefaultDispatcher-worker-1 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:42921/api/school/respect/person 2026-01-27 17:50:46.449 [DefaultDispatcher-worker-1 @coroutine#151] TRACE i.k.c.p.c.ContentNegotiation - Converted request body using io.ktor.serialization.kotlinx.KotlinxSerializationConverter@32c97ee6 for http://localhost:42921/api/school/respect/person 2026-01-27 17:50:46.449 [DefaultDispatcher-worker-1 @coroutine#151] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:42921/api/school/respect/person 2026-01-27 17:50:46.449 [DefaultDispatcher-worker-1 @coroutine#151] TRACE i.k.c.plugins.defaultTransformers - Transformed with default transformers request body for http://localhost:42921/api/school/respect/person from class io.ktor.http.content.TextContent 2026-01-27 17:50:46.472 [eventLoopGroupProxy-22-1 @call-handler#163] 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-01-27 17:50:46.489 [eventLoopGroupProxy-22-1 @call-handler#163] 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-01-27 17:50:46.497 [DefaultDispatcher-worker-1 @call-handler#163] 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-01-27 17:50:46.502 [DefaultDispatcher-worker-4 @coroutine#151] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:42921/api/school/respect/person 2026-01-27 17:50:46.502 [DefaultDispatcher-worker-4 @coroutine#151] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:42921/api/school/respect/person 2026-01-27 17:50:46.502 [DefaultDispatcher-worker-4 @coroutine#151] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:42921/api/school/respect/person 2026-01-27 17:50:48.725 [Test worker @coroutine#168] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:50:48.733 [Test worker @coroutine#168] INFO io.ktor.server.Application - Application started in 0.066 seconds. 2026-01-27 17:50:48.737 [DefaultDispatcher-worker-3 @coroutine#177] INFO io.ktor.server.Application - Responding at http://0.0.0.0:39713 2026-01-27 17:50:48.738 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39713/api/school/respect/schoolpermissiongrant 2026-01-27 17:50:48.739 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39713/api/school/respect/schoolpermissiongrant. 2026-01-27 17:50:48.739 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39713/api/school/respect/schoolpermissiongrant 2026-01-27 17:50:48.744 [eventLoopGroupProxy-25-1 @call-handler#181] 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-01-27 17:50:48.746 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39713/api/school/respect/schoolpermissiongrant 2026-01-27 17:50:48.747 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39713/api/school/respect/schoolpermissiongrant 2026-01-27 17:50:48.747 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39713/api/school/respect/schoolpermissiongrant 2026-01-27 17:50:48.747 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39713/api/school/respect/schoolpermissiongrant. 2026-01-27 17:50:48.807 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39713/api/school/respect/person?includeRelated=false 2026-01-27 17:50:48.814 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39713/api/school/respect/person?includeRelated=false. 2026-01-27 17:50:48.815 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39713/api/school/respect/person?includeRelated=false 2026-01-27 17:50:48.828 [eventLoopGroupProxy-25-1 @call-handler#187] 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-01-27 17:50:48.844 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39713/api/school/respect/person?includeRelated=false 2026-01-27 17:50:48.844 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39713/api/school/respect/person?includeRelated=false 2026-01-27 17:50:48.844 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39713/api/school/respect/person?includeRelated=false 2026-01-27 17:50:48.845 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39713/api/school/respect/person?includeRelated=false. 2026-01-27 17:50:50.853 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39713/api/school/respect/person?includeRelated=false 2026-01-27 17:50:50.853 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39713/api/school/respect/person?includeRelated=false. 2026-01-27 17:50:50.853 [Test worker @coroutine#168] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39713/api/school/respect/person?includeRelated=false 2026-01-27 17:50:50.855 [eventLoopGroupProxy-25-1 @call-handler#194] 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-01-27 17:50:50.861 [Test worker @coroutine#168] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39713/api/school/respect/person?includeRelated=false 2026-01-27 17:50:50.861 [Test worker @coroutine#168] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39713/api/school/respect/person?includeRelated=false 2026-01-27 17:50:50.861 [Test worker @coroutine#168] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39713/api/school/respect/person?includeRelated=false 2026-01-27 17:50:50.862 [Test worker @coroutine#168] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39713/api/school/respect/person?includeRelated=false. 2026-01-27 17:50:53.007 [Test worker @coroutine#200] INFO io.ktor.server.Application - Autoreload is disabled because the development mode is off. 2026-01-27 17:50:53.008 [Test worker @coroutine#200] INFO io.ktor.server.Application - Application started in 0.054 seconds. 2026-01-27 17:50:53.009 [DefaultDispatcher-worker-3 @coroutine#209] INFO io.ktor.server.Application - Responding at http://0.0.0.0:39211 2026-01-27 17:50:53.012 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39211/api/school/respect/person?includeRelated=false 2026-01-27 17:50:53.012 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39211/api/school/respect/person?includeRelated=false. 2026-01-27 17:50:53.012 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39211/api/school/respect/person?includeRelated=false 2026-01-27 17:50:53.014 [eventLoopGroupProxy-28-1 @call-handler#214] 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-01-27 17:50:53.018 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39211/api/school/respect/person?includeRelated=false 2026-01-27 17:50:53.018 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39211/api/school/respect/person?includeRelated=false 2026-01-27 17:50:53.018 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39211/api/school/respect/person?includeRelated=false 2026-01-27 17:50:53.019 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39211/api/school/respect/person?includeRelated=false. 2026-01-27 17:50:53.058 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Adding Accept=application/json header for http://localhost:39211/api/school/respect/person?since=2026-01-27T16%3A50%3A53.014993558Z&includeRelated=false 2026-01-27 17:50:53.059 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Body type class io.ktor.client.utils.EmptyContent is in ignored types. Skipping ContentNegotiation for http://localhost:39211/api/school/respect/person?since=2026-01-27T16%3A50%3A53.014993558Z&includeRelated=false. 2026-01-27 17:50:53.059 [Test worker @coroutine#200] TRACE i.ktor.client.plugins.HttpPlainText - Adding Accept-Charset=UTF-8 to http://localhost:39211/api/school/respect/person?since=2026-01-27T16%3A50%3A53.014993558Z&includeRelated=false 2026-01-27 17:50:53.060 [eventLoopGroupProxy-28-1 @call-handler#221] 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-01-27 17:50:53.063 [Test worker @coroutine#200] TRACE io.ktor.client.plugins.SaveBody - Saving body for http://localhost:39211/api/school/respect/person?since=2026-01-27T16%3A50%3A53.014993558Z&includeRelated=false 2026-01-27 17:50:53.063 [Test worker @coroutine#200] TRACE i.k.client.plugins.HttpCallValidator - Validating response for request http://localhost:39211/api/school/respect/person?since=2026-01-27T16%3A50%3A53.014993558Z&includeRelated=false 2026-01-27 17:50:53.063 [Test worker @coroutine#200] TRACE i.k.c.p.DefaultResponseValidation - Skipping default response validation for http://localhost:39211/api/school/respect/person?since=2026-01-27T16%3A50%3A53.014993558Z&includeRelated=false 2026-01-27 17:50:53.064 [Test worker @coroutine#200] TRACE i.k.c.p.c.ContentNegotiation - Response body was converted to class java.util.ArrayList for http://localhost:39211/api/school/respect/person?since=2026-01-27T16%3A50%3A53.014993558Z&includeRelated=false.